Right, it's going into "stopped" mode, rather than "completed". It's expecting there to be something that'll take the VAP out of power save state, but nothing ever happens to do so.
ok. I think I have enough information to track down a fix. Thanks! -adrian On 18 December 2014 at 23:53, Matthias Apitz <g...@unixarea.de> wrote: > 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, g...@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. _______________________________________________ freebsd-wireless@freebsd.org mailing list http://lists.freebsd.org/mailman/listinfo/freebsd-wireless To unsubscribe, send any mail to "freebsd-wireless-unsubscr...@freebsd.org"
