rt2500pci: with continuos traffic fails

Live forum: http://rt2x00.serialmonkey.com/viewtopic.php?t=4619

a-slack

14-02-2008 22:25:29

rt2500pci module v2.0.14 (kernel 2.6.25-rc1) when there is hight traffic or continuos traffic fails. Trying to download an iso image with w-g-e-t in 3 or 4 minutes device lose association, dmesg
[codedseq29il]wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX ReassocResp from 00:14:7c:b3:ae:38 (capab=0x471 status=0 aid=1)
wlan0: associated
/*
* that is a normal trace
*/
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=1)
wlan0: deauthenticated
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX ReassocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: association frame received from 00:14:7c:b3:ae:38, but not in associate state - ignored
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: authentication frame received from 00:14:7c:b3:ae:38, but not in authenticate state - ignored
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: authentication frame received from 00:14:7c:b3:ae:38, but not in authenticate state - ignored
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: association frame received from 00:14:7c:b3:ae:38, but not in associate state - ignored
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x471 status=0 aid=1)
wlan0: associated
/*
* after 1 or 2 minutes wpa_supplicant reconnects correctly
*/
[/codedseq29il]
normal AP capabilities are 0x471 seen with differents devices (atheros, zydas ...). I am using WPA-PSK/TKIP.

IvD

19-02-2008 11:46:50

Please test attached patch to see if that helps.

IvD

19-02-2008 18:11:45

Instead of the patch, please update to latest rt2x00.git version. That one contains above patch + 2nd fix.

a-slack

06-05-2008 18:55:06

Problem still present in kernel 2.6.26-rc1.

rt2500pci module v2.1.4 (kernel 2.6.26-rc1), similar trace in dmesg
[code273x91d7]PCI: Found IRQ 9 for device 0000:00:0a.0
phy0: Selected rate control algorithm 'pid'
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x471 status=0 aid=1)
wlan0: associated
wlan0: switched to short barker preamble (BSSID=00:14:7c:b3:ae:38)
/*
* that is a normal trace
*/
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: deauthenticated
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=1)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: RX deauthentication from 00:14:7c:b3:ae:38 (reason=7)
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX ReassocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: association frame received from 00:14:7c:b3:ae:38, but not in associate state - ignored
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x451 status=1 aid=1)
wlan0: AP denied association (code=1)
wlan0: association with AP 00:14:7c:b3:ae:38 timed out
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX AssocResp from 00:14:7c:b3:ae:38 (capab=0x471 status=0 aid=1)
wlan0: associated
wlan0: switched to short barker preamble (BSSID=00:14:7c:b3:ae:38)
/*
* after 1 or 2 minutes wpa_supplicant reconnects correctly
*/
wlan0: Initial auth_alg=0
wlan0: authenticate with AP 00:14:7c:b3:ae:38
wlan0: RX authentication from 00:14:7c:b3:ae:38 (alg=0 transaction=2 status=0)
wlan0: authenticated
wlan0: associate with AP 00:14:7c:b3:ae:38
wlan0: RX ReassocResp from 00:14:7c:b3:ae:38 (capab=0x471 status=0 aid=1)
wlan0: associated[/code273x91d7]
register dumps attached.

More things ... now device start with 1 Mbps, it doesn't change rate automatically, iwconfig wlan0 rate <new rate> is needed
[code273x91d7]# iwconfig wlan0
wlan0 IEEE 802.11 ESSID:"WLAN"
Mode:Managed Frequency:2.422 GHz Access Point: 00:14:7C:B3:AE:38
Bit Rate=1 Mb/s Tx-Power=18 dBm
Retry min limit:7 RTS thr:off Fragment thr=2352 B
Encryption key:0D12-4B85-62DD-FEB0-2171-F1B4-5EF1-8224-AC7A-D65B-5450-DF87-3C27-2257-5AE9-DAD9 [3]
Link Quality=49/100 Signal level=-65 dBm
Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0
Tx excessive retries:0 Invalid misc:0 Missed beacon:0[/code273x91d7]
and although you put 54M speed is too slow (85 KB/s when in direct download can achieve 400 KB/s)
[code273x91d7]$ wget -i urls_knoppix.txt
--2008-05-06 20:49:04-- ftp://ftp.rediris.es/mirror/knoppix/KNOPPIX_V5.1.1CD-2007-01-04-EN.iso
=> `KNOPPIX_V5.1.1CD-2007-01-04-EN.iso'
Resolviendo ftp.rediris.es... 130.206.1.5
Connecting to ftp.rediris.es|130.206.1.5|:21... conectado.
Identificándose como anonymous ... ¡Dentro!
==> SYST ... hecho. ==> PWD ... hecho.
==> TYPE I ... hecho. ==> CWD /mirror/knoppix ... hecho.
==> SIZE KNOPPIX_V5.1.1CD-2007-01-04-EN.iso ... 730177536
==> PASV ... hecho. ==> RETR KNOPPIX_V5.1.1CD-2007-01-04-EN.iso ... hecho.
Longitud: 730177536 (696M)

0% [ ] 4.969.096 84,3K/s eta 7h 44m [/code273x91d7]
rt2500pci works better in 2.6.25.