2010-10-26 10:54:47

by Pavel Machek

[permalink] [raw]
Subject: carl9170 disconnects under load

Hi!

I'm testing driver from -wireless testing, cca 2.6.26-rc7.

It seems to work (up to 802.11n speeds -- I've seen iwconfig claiming
121 MB/sec), but disconnects rather quickly when under load:

telnet 192.168.1.3 chargen > /dev/null

The machine is powerpc mpc5200b board, with OHCI driver -- it is not
capable of USB2.0 speeds.

Any ideas?
Pavel

wlan0: RX AssocResp from 00:23:f8:5d:76:93 (capab=0xc21 status=0
aid=1)
wlan0: associated
ieee80211 phy2: Allocated STA 00:23:f8:5d:76:93
ieee80211 phy2: Inserted STA 00:23:f8:5d:76:93
ieee80211 phy2: WMM queue=2 aci=0 acm=0 aifs=3 cWmin=15 cWmax=1023
txop=0 uapsd=0
ieee80211 phy2: WMM queue=3 aci=1 acm=0 aifs=7 cWmin=15 cWmax=1023
txop=0 uapsd=0
ieee80211 phy2: WMM queue=1 aci=2 acm=0 aifs=2 cWmin=7 cWmax=15
txop=94 uapsd=0
ieee80211 phy2: WMM queue=0 aci=3 acm=0 aifs=2 cWmin=3 cWmax=7 txop=47
uapsd=0
Open BA session requested for 00:23:f8:5d:76:93 tid 0
activated addBA response timer on tid 0
switched off addBA timer for tid 0
Aggregation is on for tid 0
Rx A-MPDU request on tid 0 result 0
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
net_ratelimit: 79 callbacks suppressed
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
net_ratelimit: 58 callbacks suppressed
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
ieee80211 phy2: release an RX reorder frame due to timeout on earlier
frames
usb 1-1: USB disconnect, address 6
Tx BA session stop requested for 00:23:f8:5d:76:93 tid 0
Rx BA session stop requested for 00:23:f8:5d:76:93 tid 0
wlan0: deauthenticating from 00:23:f8:5d:76:93 by local choice
(reason=3)
Tx BA session stop requested for 00:23:f8:5d:76:93 tid 0
ieee80211 phy2: Removed STA 00:23:f8:5d:76:93
ieee80211 phy2: Destroyed STA 00:23:f8:5d:76:93
ieee80211 phy2: device now idle


2010-11-01 09:46:58

by Pavel Machek

[permalink] [raw]
Subject: Re: carl9170 disconnects under load

Hi!

> > I'm testing driver from -wireless testing, cca 2.6.26-rc7.
> >
> > It seems to work (up to 802.11n speeds -- I've seen iwconfig claiming
> > 121 MB/sec), but disconnects rather quickly when under load:
> >
> > telnet 192.168.1.3 chargen > /dev/null
> >
> > The machine is powerpc mpc5200b board, with OHCI driver -- it is not
> > capable of USB2.0 speeds.
>
> So I tried with different AP, one not capable of 802.11n speeds. It
> fails in different way :-(. But this one is with my backport to
> 2.6.34...

But it fails with wireless-testing tree, too :-(.

Linux mpc5200b 2.6.36-rc8-wl-pma-48197-gdcae733-dirty #43 Tue Oct 26
12:06:21 CEST 2010 ppc

Pavel

PHY: f0003000:00 - Link is Up - 100/Full
warning: `named' uses 32-bit capabilities (legacy support in use)
process `named' is using obsolete setsockopt SO_BSDCOMPAT
ieee80211 phy0: device now idle
ieee80211 phy0: device no longer idle - scanning
wlan0: authenticate with 00:11:95:05:30:d7 (try 1)
wlan0: authenticated
ieee80211 phy0: device now idle
ieee80211 phy0: device no longer idle - working
wlan0: associate with 00:11:95:05:30:d7 (try 1)
wlan0: RX AssocResp from 00:11:95:05:30:d7 (capab=0x421 status=0
aid=1)
wlan0: associated
ieee80211 phy0: Allocated STA 00:11:95:05:30:d7
ieee80211 phy0: Inserted STA 00:11:95:05:30:d7
usb 1-1: restart device (4)
usb 1-1: firmware upload failed (-110).
usb 1-1: Failed to restart device (-110).
ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-5)
wlan0: deauthenticating from 00:11:95:05:30:d7 by local choice
(reason=3)
ieee80211 phy0: Removed STA 00:11:95:05:30:d7
ieee80211 phy0: Destroyed STA 00:11:95:05:30:d7
ieee80211 phy0: device now idle
cfg80211: Calling CRDA to update world regulatory domain
usb 1-1: reset full speed USB device using of:ppc-of-ohci and address
2
-/bin/sh-3.00# usb 1-1: device descriptor read/64, error -110

2010-11-01 12:12:10

by Pavel Machek

[permalink] [raw]
Subject: Re: carl9170 disconnects under load

Hi!

> > > > I'm testing driver from -wireless testing, cca 2.6.26-rc7.
> > > >
> > > > It seems to work (up to 802.11n speeds -- I've seen iwconfig claiming
> > > > 121 MB/sec), but disconnects rather quickly when under load:
> > > >
> > > > telnet 192.168.1.3 chargen > /dev/null
> > > >
> > > > The machine is powerpc mpc5200b board, with OHCI driver -- it is not
> > > > capable of USB2.0 speeds.
> > >
> > > So I tried with different AP, one not capable of 802.11n speeds. It
> > > fails in different way :-(. But this one is with my backport to
> > > 2.6.34...
> >
> > But it fails with wireless-testing tree, too :-(.
> >
> > Linux mpc5200b 2.6.36-rc8-wl-pma-48197-gdcae733-dirty #43 Tue Oct 26
> > 12:06:21 CEST 2010 ppc
>
> It works "better" if I force AP to 1Mbps.... (Note that I have USB1.1
> host only).

Ok, so I tried on powerpc machine with usb2.0... it worked a bit
better, but after a reboot, I got this one:

usb 1-3: Atheros AR9170 is registered as 'phy0'
kjournald starting. Commit interval 5 seconds
EXT3-fs (sda3): warning: maximal mount count reached, running e2fsck
is recommended
EXT3-fs (sda3): using internal journal
EXT3-fs (sda3): recovery complete
EXT3-fs (sda3): mounted filesystem with writeback data mode
warning: `proftpd' uses 32-bit capabilities (legacy support in use)
PHY: f0003000:01 - Link is Up - 100/Full
mpc5xxx_can f0000900.can: setting BTR0=0x07 BTR1=0x1c
FW: MAC RESET
wlan0: authenticate with 00:11:95:05:30:d7 (try 1)
wlan0: authenticated
wlan0: associate with 00:11:95:05:30:d7 (try 1)
wlan0: RX AssocResp from 00:11:95:05:30:d7 (capab=0x421 status=0
aid=1)
wlan0: associated
usb 1-3: no command feedback received (-110).
carl9170 cmd: 08 01 00 00 f0 36 1c 00 00 24 00 00
.....6...$..
usb 1-3: restart device (6)
writing reg 0x1c36f0 (val 0x2400) failed (-110)
lost 3 command responses/traps! w:13 g:0
writing reg 0x1d0104 (val 0x3) failed (-110)
usb 1-3: kill pending tx urbs.
usb 1-3: firmware upload failed (-110).
usb 1-3: stuck tx urbs!
usb 1-3: Failed to restart device (-110).
writing reg 0x1c36f0 (val 0x2400) failed (-5)
writing reg 0x1c36f0 (val 0x2400) failed (-5)
writing reg 0x1c36f0 (val 0x5000) failed (-5)
wlan0: deauthenticating from 00:11:95:05:30:d7 by local choice
(reason=3)
usb 1-3: reset high speed USB device using ehci_hcd and address 3
cfg80211: Calling CRDA to update world regulatory domain

2010-11-01 10:10:23

by Pavel Machek

[permalink] [raw]
Subject: Re: carl9170 disconnects under load

On Mon 2010-11-01 10:46:49, Pavel Machek wrote:
> Hi!
>
> > > I'm testing driver from -wireless testing, cca 2.6.26-rc7.
> > >
> > > It seems to work (up to 802.11n speeds -- I've seen iwconfig claiming
> > > 121 MB/sec), but disconnects rather quickly when under load:
> > >
> > > telnet 192.168.1.3 chargen > /dev/null
> > >
> > > The machine is powerpc mpc5200b board, with OHCI driver -- it is not
> > > capable of USB2.0 speeds.
> >
> > So I tried with different AP, one not capable of 802.11n speeds. It
> > fails in different way :-(. But this one is with my backport to
> > 2.6.34...
>
> But it fails with wireless-testing tree, too :-(.
>
> Linux mpc5200b 2.6.36-rc8-wl-pma-48197-gdcae733-dirty #43 Tue Oct 26
> 12:06:21 CEST 2010 ppc

It works "better" if I force AP to 1Mbps.... (Note that I have USB1.1
host only).
Pavel

Registered led device: carl9170-phy1::assoc
usb 1-1: Atheros AR9170 is registered as 'phy1'
ieee80211 phy1: device now idle
ieee80211 phy1: device no longer idle - scanning
wlan0: authenticate with 00:11:95:05:30:d7 (try 1)
wlan0: authenticated
ieee80211 phy1: device now idle
ieee80211 phy1: device no longer idle - working
wlan0: associate with 00:11:95:05:30:d7 (try 1)
wlan0: RX AssocResp from 00:11:95:05:30:d7 (capab=0x421 status=0
aid=1)
wlan0: associated
ieee80211 phy1: Allocated STA 00:11:95:05:30:d7
ieee80211 phy1: Inserted STA 00:11:95:05:30:d7
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
ieee80211 phy1: FW: MAC RESET
-/bin/sh-3.00#



2010-11-01 09:31:50

by Pavel Machek

[permalink] [raw]
Subject: Re: carl9170 disconnects under load

Hi!

> I'm testing driver from -wireless testing, cca 2.6.26-rc7.
>
> It seems to work (up to 802.11n speeds -- I've seen iwconfig claiming
> 121 MB/sec), but disconnects rather quickly when under load:
>
> telnet 192.168.1.3 chargen > /dev/null
>
> The machine is powerpc mpc5200b board, with OHCI driver -- it is not
> capable of USB2.0 speeds.

So I tried with different AP, one not capable of 802.11n speeds. It
fails in different way :-(. But this one is with my backport to
2.6.34...
Pavel

wlan0: associate with 00:11:95:05:30:d7 (try 1)
wlan0: RX AssocResp from 00:11:95:05:30:d7 (capab=0x421 status=0
aid=1)
wlan0: associated
phy0: Allocated STA 00:11:95:05:30:d7
phy0: Inserted STA 00:11:95:05:30:d7
phy0: Removed STA 00:11:95:05:30:d7
phy0: Destroyed STA 00:11:95:05:30:d7
wlan0: deauthenticating from 00:11:95:05:30:d7 by local choice
(reason=3)
phy0: device now idle
cfg80211: Calling CRDA to update world regulatory domain
phy0: device no longer idle - scanning
wlan0: authenticate with 00:11:95:05:30:d7 (try 1)
wlan0: authenticated
phy0: device now idle
phy0: device no longer idle - working
wlan0: associate with 00:11:95:05:30:d7 (try 1)
wlan0: RX AssocResp from 00:11:95:05:30:d7 (capab=0x421 status=0
aid=1)
wlan0: associated
phy0: Allocated STA 00:11:95:05:30:d7
phy0: Inserted STA 00:11:95:05:30:d7
received frame with suspicious error code (0x81).
received frame with suspicious error code (0x3).
received frame with suspicious error code (0x1).
received frame with suspicious error code (0x2).
received frame with suspicious error code (0x1).
received frame with suspicious error code (0x3).
usb 1-1: no command feedback received (-110).
carl9170 cmd: 08 01 00 00 04 01 1d 00 03 00 00 00
............
usb 1-1: restart device (6)
writing reg 0x1d0104 (val 0x3) failed (-110)


> Any ideas?
> Pavel
>
> wlan0: RX AssocResp from 00:23:f8:5d:76:93 (capab=0xc21 status=0
> aid=1)
> wlan0: associated
> ieee80211 phy2: Allocated STA 00:23:f8:5d:76:93
> ieee80211 phy2: Inserted STA 00:23:f8:5d:76:93
> ieee80211 phy2: WMM queue=2 aci=0 acm=0 aifs=3 cWmin=15 cWmax=1023
> txop=0 uapsd=0
> ieee80211 phy2: WMM queue=3 aci=1 acm=0 aifs=7 cWmin=15 cWmax=1023
> txop=0 uapsd=0
> ieee80211 phy2: WMM queue=1 aci=2 acm=0 aifs=2 cWmin=7 cWmax=15
> txop=94 uapsd=0
> ieee80211 phy2: WMM queue=0 aci=3 acm=0 aifs=2 cWmin=3 cWmax=7 txop=47
> uapsd=0
> Open BA session requested for 00:23:f8:5d:76:93 tid 0
> activated addBA response timer on tid 0
> switched off addBA timer for tid 0
> Aggregation is on for tid 0
> Rx A-MPDU request on tid 0 result 0
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> net_ratelimit: 79 callbacks suppressed
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> net_ratelimit: 58 callbacks suppressed
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> ieee80211 phy2: release an RX reorder frame due to timeout on earlier
> frames
> usb 1-1: USB disconnect, address 6
> Tx BA session stop requested for 00:23:f8:5d:76:93 tid 0
> Rx BA session stop requested for 00:23:f8:5d:76:93 tid 0
> wlan0: deauthenticating from 00:23:f8:5d:76:93 by local choice
> (reason=3)
> Tx BA session stop requested for 00:23:f8:5d:76:93 tid 0
> ieee80211 phy2: Removed STA 00:23:f8:5d:76:93
> ieee80211 phy2: Destroyed STA 00:23:f8:5d:76:93
> ieee80211 phy2: device now idle