Date: Fri, 19 Dec 2014 08:53:39 +0100 From: Matthias Apitz <guru@unixarea.de> To: Adrian Chadd <adrian@freebsd.org> Cc: "freebsd-wireless@freebsd.org" <freebsd-wireless@freebsd.org>, Nathan Whitehorn <nwhitehorn@freebsd.org> Subject: Re: Issues with urtwn Message-ID: <20141219075339.GA1748@unixarea.DDR.dd> In-Reply-To: <CAJ-VmomHguekQzfJ17gbK4_tTsuDPCfkDOjcTmviddDrmEEzoQ@mail.gmail.com> References: <20141102084605.GA60031@unixarea.DDR.dd> <54564C92.8040104@freebsd.org> <20141102152953.GA20263@unixarea.DDR.dd> <54564E4D.4020703@freebsd.org> <CAJ-Vmomhzsyn0k5Ym1=qm-LibJmW=8zb2LunYHNf4sSvWsP1=w@mail.gmail.com> <20141103054633.GA3258@unixarea.DDR.dd> <20141103095530.GA42402@unixarea.DDR.dd> <CAJ-Vmokn_Kqos%2B7HhN0EmB6JohD3g%2BVaXaqkhG-6%2BYZkgDoZFA@mail.gmail.com> <20141123163811.GA5739@unixarea.DDR.dd> <CAJ-VmomHguekQzfJ17gbK4_tTsuDPCfkDOjcTmviddDrmEEzoQ@mail.gmail.com>
next in thread | previous in thread | raw e-mail | index | archive | help
El día Wednesday, December 17, 2014 a las 06:58:09PM -0800, Adrian Chadd escribió: > ... > Would someone please try this again, but run it with scan debugging > enabled (wlandebug +scan) ? The scan_task() routine has some useful > debugging just before it may wake up the VAP; I'd like to see what > that says (whether it says "done" or "stopped" in it.) > ... > Hi, Here comes the messages from such 'wlandebug +scan'; please let me know if you need more tests/logs: ... Dec 19 07:41:24 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] ieee80211_scan_assoc_success Dec 19 07:41:24 unixarea kernel: wlan0: link state changed to UP Dec 19 07:41:24 unixarea dhclient[1828]: send_packet: No buffer space available Dec 19 07:41:24 unixarea wpa_supplicant[1731]: wlan0: Associated with 00:26:0b:4b:b8:44 Dec 19 07:41:24 unixarea wpa_supplicant[1731]: wlan0: WPA: Key negotiation completed with 00:26:0b:4b:b8:44 [PTK=CCMP GTK=CCMP] Dec 19 07:41:24 unixarea wpa_supplicant[1731]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:26:0b:4b:b8:44 completed [id=2 id_str=] Dec 19 07:41:31 unixarea dhclient: New IP Address (wlan0): 10.49.30.104 Dec 19 07:41:31 unixarea dhclient: New Subnet Mask (wlan0): 255.255.255.0 Dec 19 07:41:31 unixarea dhclient: New Broadcast Address (wlan0): 10.49.30.255 Dec 19 07:41:31 unixarea dhclient: New Routers (wlan0): 10.49.30.1 I run in another terminal a cycle of a proc fetching a file with WGET from some sever; the lines are from ipmon for every WGET: Dec 19 07:41:47 unixarea ipmon[508]: 07:41:47.581028 wlan0 @0:14 p 10.49.30.104,37367 -> 132.174.1.212,80 PR tcp len 20 60 -S K-S OUT Dec 19 07:41:50 unixarea ipmon[508]: 07:41:50.803714 wlan0 @0:14 p 10.49.30.104,52814 -> 132.174.1.212,80 PR tcp len 20 60 -S K-S OUT Dec 19 07:41:53 unixarea ipmon[508]: 07:41:53.206811 wlan0 @0:14 p 10.49.30.104,53615 -> 132.174.1.212,80 PR tcp len 20 60 -S K-S OUT Dec 19 07:41:55 unixarea ipmon[508]: 07:41:55.872432 wlan0 @0:14 p 10.49.30.104,55755 -> 132.174.1.212,80 PR tcp len 20 60 -S K-S OUT ... Dec 19 07:46:23 unixarea ipmon[508]: 07:46:23.463618 wlan0 @0:14 p 10.49.30.104,46622 -> 132.174.1.212,80 PR tcp len 20 60 -S K-S OUT now the 300 sec bgscan intervall is over and the bgscan occurs: Dec 19 07:46:24 unixarea kernel: wlan0: ieee80211_bg_scan: active scan, ticks 2141470 duration 150 Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: GuruDebug: Enable station power save mode Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: chan 1g -> 1g [active, dwell min 20ms max 150ms] Dec 19 07:46:24 unixarea kernel: [00:26:0b:4b:b8:44] new probe_resp on chan 1 (bss chan 1) "OCLCPublic" rssi 64 Dec 19 07:46:24 unixarea kernel: [00:26:0b:4b:b8:44] caps 0x431 bintval 100 erp 0x100 country [NL 1-13,23] Dec 19 07:46:24 unixarea kernel: [00:26:0b:4b:b8:44] new probe_resp on chan 1 (bss chan 1) "OCLCPublic" rssi 64 Dec 19 07:46:24 unixarea kernel: [00:26:0b:4b:b8:44] caps 0x431 bintval 100 erp 0x100 country [NL 1-13,23] Dec 19 07:46:24 unixarea kernel: [00:26:0b:4b:b8:44] new beacon on chan 1 (bss chan 1) "OCLCPublic" rssi 64 Dec 19 07:46:24 unixarea kernel: [00:26:0b:4b:b8:44] caps 0x431 bintval 100 erp 0x100 country [NL 1-13,23] Dec 19 07:46:24 unixarea kernel: wlan0: ieee80211_add_scan: chan 1g min dwell met (2141573 > 2141495) Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: chan 1g -> 6g [active, dwell min 20ms max 49ms] Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: stopped, [ticks 2141628, dwell min 20 scanend 2141622] Dec 19 07:46:24 unixarea kernel: wlan0: ieee80211_bg_scan: active scan, ticks 2141675 duration 150 Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: chan 1g -> 11g [active, dwell min 20ms max 150ms] Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: stopped, [ticks 2141840, dwell min 20 scanend 2141825] Dec 19 07:46:24 unixarea kernel: wlan0: ieee80211_bg_scan: active scan, ticks 2141880 duration 150 Dec 19 07:46:24 unixarea kernel: wlan0: scan_task: chan 1g -> 7g [active, dwell min 20ms max 150ms] Dec 19 07:46:25 unixarea kernel: wlan0: scan_task: stopped, [ticks 2142046, dwell min 20 scanend 2142030] after this no more traffic can pass the wlan0 interface; its state is: wlan0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500 ether 80:1f:02:ee:16:37 inet 10.49.30.104 netmask 0xffffff00 broadcast 10.49.30.255 nd6 options=29<PERFORMNUD,IFDISABLED,AUTO_LINKLOCAL> media: IEEE 802.11 Wireless Ethernet OFDM/54Mbps mode 11g status: associated ssid OCLCPublic channel 1 (2412 MHz 11g) bssid 00:26:0b:4b:b8:44 country US authmode WPA2/802.11i privacy ON deftxkey UNDEF AES-CCM 2:128-bit txpower 0 bmiss 7 scanvalid 60 bgscan bgscanintvl 300 bgscanidle 250 roam:rssi 7 roam:rate 5 protmode CTS roaming MANUAL after some more time some pwr save queue flows over: Dec 19 07:50:59 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 1 (size 50) Dec 19 07:51:01 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 2 (size 50) Dec 19 07:51:02 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 3 (size 50) Dec 19 07:51:12 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 4 (size 50) Dec 19 07:51:15 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 5 (size 50) Dec 19 07:51:18 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 6 (size 50) Dec 19 07:51:23 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 7 (size 50) Dec 19 07:51:24 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 8 (size 50) Dec 19 07:51:25 unixarea kernel: wlan0: [00:26:0b:4b:b8:44] pwr save q overflow, drops 9 (size 50) -- Matthias Apitz, guru@unixarea.de, http://www.unixarea.de/ +49-170-4527211 1989-2014: The Wall was torn down so that we go to war together again. El Muro ha sido derribado para que nos unimos en ir a la guerra otra vez. Diese Grenze wurde aufgehoben damit wir gemeinsam wieder in den Krieg ziehen.
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20141219075339.GA1748>