Skip site navigation (1)Skip section navigation (2)
Date:      Sun, 07 Sep 2014 01:43:30 +0000
From:      bugzilla-noreply@freebsd.org
To:        freebsd-wireless@FreeBSD.org
Subject:   [Bug 192950] [iwn] Centrino Advanced-N 6205 slow on 11n, better on 11g
Message-ID:  <bug-192950-21060-C6emU8oY3W@https.bugs.freebsd.org/bugzilla/>
In-Reply-To: <bug-192950-21060@https.bugs.freebsd.org/bugzilla/>
References:  <bug-192950-21060@https.bugs.freebsd.org/bugzilla/>

next in thread | previous in thread | raw e-mail | index | archive | help
https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=192950

--- Comment #18 from Hiren Panchasara <hiren@FreeBSD.org> ---
Noticing only 400kbps on ssh from laptop to desktop on wifi.

root@flymockour-l7:/home/hirenp/head/sys/net80211 # sysctl dev.iwn.0.debug=0xff
dev.iwn.0.debug: 0 -> 255
root@flymockour-l7:/home/hirenp/head/sys/net80211 # wlandebug +rate
net.wlan.0.debug: 0x0 => 0x20<rate>

I see the rate is stuck at 0x0080.

Here are logs to send packet no: 1614 to 1616 - just for example.

I've added following printf to see if on successful transfer, ratectl code is
getting trigger to ++ the rate. It seems to be firing but not doing the right
thing.

Index: if_iwn.c
===================================================================
--- if_iwn.c    (revision 270821)
+++ if_iwn.c    (working copy)
@@ -3500,10 +3500,12 @@
         */
        if (status & IWN_TX_FAIL) {
                ifp->if_oerrors++;
+       DPRINTF(sc, IWN_DEBUG_XMIT, "->%s Not updating rate control
if_opackets: %lu\n", __func__, ifp->if_opackets);
                ieee80211_ratectl_tx_complete(vap, ni,
                    IEEE80211_RATECTL_TX_FAILURE, &ackfailcnt, NULL);
        } else {
                ifp->if_opackets++;
+       DPRINTF(sc, IWN_DEBUG_XMIT, "->%s updating rate control if_opackets:
%lu\n", __func__, ifp->if_opackets);
                ieee80211_ratectl_tx_complete(vap, ni,
                    IEEE80211_RATECTL_TX_SUCCESS, &ackfailcnt, NULL);
        }

Logs:

64190 Sep  6 18:27:57 flymockour-l7 kernel: ->iwn_tx_done updating rate control
if_opackets: 1613
64191 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=63; qid 6 idx
154 flags 0 type 192(RX_PHY) len 68
64192 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=0; qid 6 idx
155 flags 0 type 193(MPDU_RX_DONE) len 168
64193 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 55 rssi 9766
11051 0 result -56
64194 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=1; qid 6 idx
156 flags 0 type 192(RX_PHY) len 68
64195 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=2; qid 6 idx
157 flags 0 type 193(MPDU_RX_DONE) len 336
64196 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 29 rssi 4884
5399 0 result -50
64197 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=3; qid 6 idx
158 flags 0 type 157(BEACON_STATS) len 528
64198 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
102; cck=48, ofdm=2, ht=0, total=152, thresh=5100
64199 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=4; qid 9 idx 1
flags 0 type 168(IWN_CMD_SET_SENSITIVITY) len 4
64200 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=5; qid 6 idx
159 flags 0 type 192(RX_PHY) len 68
64201 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=6; qid 6 idx
160 flags 0 type 193(MPDU_RX_DONE) len 120
64202 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 56 rssi 10023
11308 0 result -56
64203 Sep  6 18:27:57 flymockour-l7 kernel: iwn_start_locked: called
64204 Sep  6 18:27:57 flymockour-l7 kernel: iwn_tx_data: qid 0 idx 63 len 80
nsegs 2 flags 0x00100018 rate 0x0080 plcp 0x00004100
64205 Sep  6 18:27:57 flymockour-l7 kernel: iwn_start_locked: done
64206 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=7; qid 0 idx 63
flags 0 type 28(TX_DONE) len 44
64207 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_tx_done: qid 0 idx 63
retries 0 nkill 0 rate 80004100 duration 296 status 201
64208 Sep  6 18:27:57 flymockour-l7 kernel: ->iwn_tx_done updating rate control
if_opackets: 1614
64209 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=8; qid 6 idx
172 flags 0 type 192(RX_PHY) len 68
64210 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=9; qid 6 idx
173 flags 0 type 193(MPDU_RX_DONE) len 336
64211 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 28 rssi 4627
5399 0 result -49
64212 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=10; qid 6 idx
174 flags 0 type 157(BEACON_STATS) len 528
64213 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
102; cck=11, ofdm=0, ht=0, total=113, thresh=5100
64214 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=11; qid 6 idx
183 flags 0 type 192(RX_PHY) len 68
64215 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=12; qid 6 idx
184 flags 0 type 193(MPDU_RX_DONE) len 336
64216 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 30 rssi 3858
5398 0 result -52
64217 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=13; qid 6 idx
185 flags 0 type 157(BEACON_STATS) len 528
64218 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
103; cck=36, ofdm=0, ht=0, total=139, thresh=5150
64219 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=14; qid 6 idx
188 flags 0 type 192(RX_PHY) len 68
64220 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=15; qid 6 idx
189 flags 0 type 193(MPDU_RX_DONE) len 120
64221 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 55 rssi 9765
11051 0 result -56
64222 Sep  6 18:27:57 flymockour-l7 kernel: iwn_start_locked: called
64223 Sep  6 18:27:57 flymockour-l7 kernel: iwn_tx_data: qid 0 idx 64 len 86
nsegs 2 flags 0x00100018 rate 0x0080 plcp 0x00004100
64224 Sep  6 18:27:57 flymockour-l7 kernel: iwn_start_locked: done
64225 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=16; qid 0 idx
64 flags 0 type 28(TX_DONE) len 44
64226 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_tx_done: qid 0 idx 64
retries 0 nkill 0 rate 80004100 duration 304 status 201
64227 Sep  6 18:27:57 flymockour-l7 kernel: ->iwn_tx_done updating rate control
if_opackets: 1615
64228 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=17; qid 6 idx
200 flags 0 type 192(RX_PHY) len 68
64229 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=18; qid 6 idx
201 flags 0 type 193(MPDU_RX_DONE) len 120
64230 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 55 rssi 9766
11051 0 result -56
64231 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=19; qid 6 idx
206 flags 0 type 192(RX_PHY) len 68
64232 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=20; qid 6 idx
207 flags 0 type 193(MPDU_RX_DONE) len 336
64233 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 29 rssi 4885
5656 0 result -49
64234 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=21; qid 6 idx
208 flags 0 type 157(BEACON_STATS) len 528
64235 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
102; cck=32, ofdm=1, ht=0, total=135, thresh=5100
64236 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=22; qid 6 idx
219 flags 0 type 192(RX_PHY) len 68
64237 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=23; qid 6 idx
220 flags 0 type 193(MPDU_RX_DONE) len 336
64238 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 33 rssi 4884
6169 0 result -52
64239 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=24; qid 6 idx
221 flags 0 type 157(BEACON_STATS) len 528
64240 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
104; cck=50, ofdm=0, ht=0, total=154, thresh=5200
64241 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=25; qid 6 idx
222 flags 0 type 162(BEACON_MISSED) len 20
64242 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: beacons missed 0/0
64243 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=26; qid 6 idx
234 flags 0 type 192(RX_PHY) len 68
64244 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=27; qid 6 idx
235 flags 0 type 193(MPDU_RX_DONE) len 336
64245 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 31 rssi 4114
5399 0 result -52
64246 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=28; qid 6 idx
236 flags 0 type 157(BEACON_STATS) len 528
64247 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
101; cck=16, ofdm=0, ht=0, total=117, thresh=5050
64248 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=29; qid 6 idx
244 flags 0 type 192(RX_PHY) len 68
64249 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=30; qid 6 idx
245 flags 0 type 193(MPDU_RX_DONE) len 336
64250 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 31 rssi 3602
5399 0 result -52
64251 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=31; qid 6 idx
246 flags 0 type 157(BEACON_STATS) len 528
64252 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
102; cck=56, ofdm=0, ht=0, total=158, thresh=5100
64253 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: PLCP error
threshold raw (56) comparison (5600) over limit (5100); retune!
64254 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=32; qid 6 idx
255 flags 0 type 192(RX_PHY) len 68
64255 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=33; qid 7 idx 0
flags 0 type 193(MPDU_RX_DONE) len 336
64256 Sep  6 18:27:57 flymockour-l7 kernel: iwn5000_get_rssi: agc 31 rssi 3858
5655 0 result -52
64257 Sep  6 18:27:57 flymockour-l7 kernel: iwn_notif_intr: cur=34; qid 7 idx 1
flags 0 type 157(BEACON_STATS) len 528
64258 Sep  6 18:27:57 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
103; cck=22, ofdm=1, ht=0, total=126, thresh=5150
64259 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=35; qid 7 idx
10 flags 0 type 192(RX_PHY) len 68
64260 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=36; qid 7 idx
11 flags 0 type 193(MPDU_RX_DONE) len 336
64261 Sep  6 18:27:58 flymockour-l7 kernel: iwn5000_get_rssi: agc 31 rssi 4114
5399 0 result -52
64262 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=37; qid 7 idx
12 flags 0 type 157(BEACON_STATS) len 528
64263 Sep  6 18:27:58 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
102; cck=32, ofdm=2, ht=0, total=136, thresh=5100
64264 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=38; qid 7 idx
21 flags 0 type 192(RX_PHY) len 68
64265 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=39; qid 7 idx
22 flags 0 type 193(MPDU_RX_DONE) len 336
64266 Sep  6 18:27:58 flymockour-l7 kernel: iwn5000_get_rssi: agc 32 rssi 4371
5656 0 result -52
64267 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=40; qid 7 idx
23 flags 0 type 157(BEACON_STATS) len 528
64268 Sep  6 18:27:58 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
103; cck=22, ofdm=11, ht=0, total=136, thresh=5150
64269 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=41; qid 7 idx
24 flags 0 type 162(BEACON_MISSED) len 20
64270 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: beacons missed 0/0
64271 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=42; qid 7 idx
32 flags 0 type 192(RX_PHY) len 68
64272 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=43; qid 7 idx
33 flags 0 type 193(MPDU_RX_DONE) len 168
64273 Sep  6 18:27:58 flymockour-l7 kernel: iwn5000_get_rssi: agc 56 rssi 9766
11308 0 result -56
64274 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=44; qid 7 idx
35 flags 0 type 192(RX_PHY) len 68
64275 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=45; qid 7 idx
36 flags 0 type 193(MPDU_RX_DONE) len 336
64276 Sep  6 18:27:58 flymockour-l7 kernel: iwn5000_get_rssi: agc 30 rssi 4115
5142 0 result -52
64277 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=46; qid 7 idx
37 flags 0 type 157(BEACON_STATS) len 528
64278 Sep  6 18:27:58 flymockour-l7 kernel: iwn_check_rx_recovery: time delta:
102; cck=50, ofdm=13, ht=0, total=165, thresh=5100
64279 Sep  6 18:27:58 flymockour-l7 kernel: iwn_check_rx_recovery: PLCP error
threshold raw (63) comparison (6300) over limit (5100); retune!
64280 Sep  6 18:27:58 flymockour-l7 kernel: iwn_start_locked: called
64281 Sep  6 18:27:58 flymockour-l7 kernel: iwn_tx_data: qid 0 idx 65 len 80
nsegs 2 flags 0x00100018 rate 0x0080 plcp 0x00004100
64282 Sep  6 18:27:58 flymockour-l7 kernel: iwn_start_locked: done
64283 Sep  6 18:27:58 flymockour-l7 kernel: iwn_notif_intr: cur=47; qid 0 idx
65 flags 0 type 28(TX_DONE) len 44
64284 Sep  6 18:27:58 flymockour-l7 kernel: iwn5000_tx_done: qid 0 idx 65
retries 0 nkill 0 rate 80004100 duration 296 status 201
64285 Sep  6 18:27:58 flymockour-l7 kernel: ->iwn_tx_done updating rate control
if_opackets: 1616

-- 
You are receiving this mail because:
You are the assignee for the bug.



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?bug-192950-21060-C6emU8oY3W>