[ath9k-devel] Hostapd+WMM causes packet loss

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

Hello, 

I was redirected here from hostapd mailinglist. I am trying to
run a 802.11n 5GHz AP from a tl-wdn4800 pci-e 3x3 adapter which seems to
contain a AR9380 chipset.

With disabled wmm (wmm_enabled=0), I get reliable connection at 54 Mb/s,
1-4ms ping with no apparent packet loss, and around 17 Mb/sec actual
transfer rate:
[  3]  0.0- 1.0 sec  2.12 MBytes  17.8 Mbits/sec
[  3]  1.0- 2.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  2.0- 3.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  3.0- 4.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  4.0- 5.0 sec  2.12 MBytes  17.8 Mbits/sec
[  3]  5.0- 6.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  6.0- 7.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  7.0- 8.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  8.0- 9.0 sec  2.00 MBytes  16.8 Mbits/sec
[  3]  9.0-10.0 sec  2.12 MBytes  17.8 Mbits/sec


When I enable wmm, I get faster connection (up to 270 Mbit/s)
with significant packet loss which seems to come every few
seconds for a second or more:

[  3]  0.0- 1.0 sec  9.12 MBytes  76.5 Mbits/sec
[  3]  1.0- 2.0 sec  4.75 MBytes  39.8 Mbits/sec
[  3]  2.0- 3.0 sec  3.38 MBytes  28.3 Mbits/sec
[  3]  3.0- 4.0 sec  3.25 MBytes  27.3 Mbits/sec
[  3]  4.0- 5.0 sec  6.25 MBytes  52.4 Mbits/sec
[  3]  5.0- 6.0 sec  3.50 MBytes  29.4 Mbits/sec
[  3]  6.0- 7.0 sec  3.12 MBytes  26.2 Mbits/sec
[  3]  7.0- 8.0 sec  6.38 MBytes  53.5 Mbits/sec
[  3]  8.0- 9.0 sec  0.00 Bytes  0.00 bits/sec
[  3]  9.0-10.0 sec  6.62 MBytes  55.6 Mbits/sec
[  3] 10.0-11.0 sec  0.00 Bytes  0.00 bits/sec
[  3] 11.0-12.0 sec  1.12 MBytes  9.44 Mbits/sec
[  3] 12.0-13.0 sec  5.25 MBytes  44.0 Mbits/sec
[  3] 13.0-14.0 sec  6.38 MBytes  53.5 Mbits/sec
[  3] 14.0-15.0 sec  3.25 MBytes  27.3 Mbits/sec
[  3] 15.0-16.0 sec  3.75 MBytes  31.5 Mbits/sec
[  3] 16.0-17.0 sec  0.00 Bytes  0.00 bits/sec
[  3] 17.0-18.0 sec  0.00 Bytes  0.00 bits/sec
[  3] 18.0-19.0 sec  6.88 MBytes  57.7 Mbits/sec
[  3] 19.0-20.0 sec  0.00 Bytes  0.00 bits/sec
[  3] 20.0-21.0 sec  0.00 Bytes  0.00 bits/sec
[  3]  0.0-21.4 sec  73.1 MBytes  28.6 Mbits/sec

This doesn't seem to be just QoS load prioritizing,
since ping in a no-load situation has similar issues:

64 bytes from 10.0.0.1: icmp_seq=1 ttl=64 time=6.14 ms
64 bytes from 10.0.0.1: icmp_seq=2 ttl=64 time=2.85 ms
64 bytes from 10.0.0.1: icmp_seq=3 ttl=64 time=5.03 ms
64 bytes from 10.0.0.1: icmp_seq=4 ttl=64 time=3.79 ms
64 bytes from 10.0.0.1: icmp_seq=5 ttl=64 time=3.66 ms
64 bytes from 10.0.0.1: icmp_seq=6 ttl=64 time=2.50 ms
64 bytes from 10.0.0.1: icmp_seq=13 ttl=64 time=2.54 ms
64 bytes from 10.0.0.1: icmp_seq=17 ttl=64 time=2.96 ms
64 bytes from 10.0.0.1: icmp_seq=18 ttl=64 time=7.66 ms
64 bytes from 10.0.0.1: icmp_seq=19 ttl=64 time=3.22 ms

Both clients, running Intel Ultimate-N 6300 3x3 cards,
are affected. The wireless spectrum seems to be more or less clear
containing only 2 other weak 5GHz APs, both on Channel 44
compared to my 48.

Is this a known issue? I couldn't find any mention of it.
Can someone point me at what I should try? I tried
using a different channel but I did not see any change
in behavior.

Thanks!

Rasto

====================================================
Versions/Configuration:

Client:
Debian, vanilla 3.9.8 kernel, wpa_supplicant 1.0-3+b2
Server:
Debian, debian 3.10-2-amd64 kernel, hostapd 1.0-3+b2

lspci -v:
01:00.0 Network controller: Qualcomm Atheros AR93xx Wireless Network
Adapter (rev 01)
        Subsystem: Qualcomm Atheros Device 3112
        Physical Slot: 0
        Flags: bus master, fast devsel, latency 0, IRQ 16
        Memory at fe9e0000 (64-bit, non-prefetchable) [size=128K]
        Expansion ROM at fe9d0000 [disabled] [size=64K]
        Capabilities: [40] Power Management version 3
        Capabilities: [50] MSI: Enable- Count=1/4 Maskable+ 64bit+
        Capabilities: [70] Express Endpoint, MSI 00
        Capabilities: [100] Advanced Error Reporting
        Capabilities: [140] Virtual Channel
        Capabilities: [300] Device Serial Number 00-00-00-00-00-00-00-00
        Kernel driver in use: ath9k

parts of hostapd.conf that seem relevant:

interface=wlan0
bridge=br0
driver=nl80211
hw_mode=a
channel=48
rts_threshold=2347
fragm_threshold=2346

wmm_enabled=1
wmm_ac_bk_cwmin=4
wmm_ac_bk_cwmax=10
wmm_ac_bk_aifs=7
wmm_ac_bk_txop_limit=0
wmm_ac_bk_acm=0
wmm_ac_be_aifs=3
wmm_ac_be_cwmin=4
wmm_ac_be_cwmax=10
wmm_ac_be_txop_limit=0
wmm_ac_be_acm=0
wmm_ac_vi_aifs=2
wmm_ac_vi_cwmin=3
wmm_ac_vi_cwmax=4
wmm_ac_vi_txop_limit=94
wmm_ac_vi_acm=0
wmm_ac_vo_aifs=2
wmm_ac_vo_cwmin=2
wmm_ac_vo_cwmax=3
wmm_ac_vo_txop_limit=47
wmm_ac_vo_acm=0
ieee80211n=1
ht_capab=[HT40-][SHORT-GI-40]

wpa=2
wpa_key_mgmt=WPA-PSK WPA-EAP
wpa_pairwise=CCMP TKIP
rsn_pairwise=CCMP

[ath9k-devel] Hostapd+WMM causes packet loss

[Adrian Chadd]

Sounds like enabling WMM enables using 11n rates and you're seeing some
significant packet loss when 11n is enabled.


-adrian



On 25 August 2013 08:57, Rasto Sramek <rasto@100acrewood.org> wrote:

> Hello,
>
> I was redirected here from hostapd mailinglist. I am trying to
> run a 802.11n 5GHz AP from a tl-wdn4800 pci-e 3x3 adapter which seems to
> contain a AR9380 chipset.
>
> With disabled wmm (wmm_enabled=0), I get reliable connection at 54 Mb/s,
> 1-4ms ping with no apparent packet loss, and around 17 Mb/sec actual
> transfer rate:
> [  3]  0.0- 1.0 sec  2.12 MBytes  17.8 Mbits/sec
> [  3]  1.0- 2.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  2.0- 3.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  3.0- 4.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  4.0- 5.0 sec  2.12 MBytes  17.8 Mbits/sec
> [  3]  5.0- 6.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  6.0- 7.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  7.0- 8.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  8.0- 9.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  9.0-10.0 sec  2.12 MBytes  17.8 Mbits/sec
>
>
> When I enable wmm, I get faster connection (up to 270 Mbit/s)
> with significant packet loss which seems to come every few
> seconds for a second or more:
>
> [  3]  0.0- 1.0 sec  9.12 MBytes  76.5 Mbits/sec
> [  3]  1.0- 2.0 sec  4.75 MBytes  39.8 Mbits/sec
> [  3]  2.0- 3.0 sec  3.38 MBytes  28.3 Mbits/sec
> [  3]  3.0- 4.0 sec  3.25 MBytes  27.3 Mbits/sec
> [  3]  4.0- 5.0 sec  6.25 MBytes  52.4 Mbits/sec
> [  3]  5.0- 6.0 sec  3.50 MBytes  29.4 Mbits/sec
> [  3]  6.0- 7.0 sec  3.12 MBytes  26.2 Mbits/sec
> [  3]  7.0- 8.0 sec  6.38 MBytes  53.5 Mbits/sec
> [  3]  8.0- 9.0 sec  0.00 Bytes  0.00 bits/sec
> [  3]  9.0-10.0 sec  6.62 MBytes  55.6 Mbits/sec
> [  3] 10.0-11.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 11.0-12.0 sec  1.12 MBytes  9.44 Mbits/sec
> [  3] 12.0-13.0 sec  5.25 MBytes  44.0 Mbits/sec
> [  3] 13.0-14.0 sec  6.38 MBytes  53.5 Mbits/sec
> [  3] 14.0-15.0 sec  3.25 MBytes  27.3 Mbits/sec
> [  3] 15.0-16.0 sec  3.75 MBytes  31.5 Mbits/sec
> [  3] 16.0-17.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 17.0-18.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 18.0-19.0 sec  6.88 MBytes  57.7 Mbits/sec
> [  3] 19.0-20.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 20.0-21.0 sec  0.00 Bytes  0.00 bits/sec
> [  3]  0.0-21.4 sec  73.1 MBytes  28.6 Mbits/sec
>
> This doesn't seem to be just QoS load prioritizing,
> since ping in a no-load situation has similar issues:
>
> 64 bytes from 10.0.0.1: icmp_seq=1 ttl=64 time=6.14 ms
> 64 bytes from 10.0.0.1: icmp_seq=2 ttl=64 time=2.85 ms
> 64 bytes from 10.0.0.1: icmp_seq=3 ttl=64 time=5.03 ms
> 64 bytes from 10.0.0.1: icmp_seq=4 ttl=64 time=3.79 ms
> 64 bytes from 10.0.0.1: icmp_seq=5 ttl=64 time=3.66 ms
> 64 bytes from 10.0.0.1: icmp_seq=6 ttl=64 time=2.50 ms
> 64 bytes from 10.0.0.1: icmp_seq=13 ttl=64 time=2.54 ms
> 64 bytes from 10.0.0.1: icmp_seq=17 ttl=64 time=2.96 ms
> 64 bytes from 10.0.0.1: icmp_seq=18 ttl=64 time=7.66 ms
> 64 bytes from 10.0.0.1: icmp_seq=19 ttl=64 time=3.22 ms
>
> Both clients, running Intel Ultimate-N 6300 3x3 cards,
> are affected. The wireless spectrum seems to be more or less clear
> containing only 2 other weak 5GHz APs, both on Channel 44
> compared to my 48.
>
> Is this a known issue? I couldn't find any mention of it.
> Can someone point me at what I should try? I tried
> using a different channel but I did not see any change
> in behavior.
>
> Thanks!
>
> Rasto
>
> ====================================================
> Versions/Configuration:
>
> Client:
> Debian, vanilla 3.9.8 kernel, wpa_supplicant 1.0-3+b2
> Server:
> Debian, debian 3.10-2-amd64 kernel, hostapd 1.0-3+b2
>
> lspci -v:
> 01:00.0 Network controller: Qualcomm Atheros AR93xx Wireless Network
> Adapter (rev 01)
>         Subsystem: Qualcomm Atheros Device 3112
>         Physical Slot: 0
>         Flags: bus master, fast devsel, latency 0, IRQ 16
>         Memory at fe9e0000 (64-bit, non-prefetchable) [size=128K]
>         Expansion ROM at fe9d0000 [disabled] [size=64K]
>         Capabilities: [40] Power Management version 3
>         Capabilities: [50] MSI: Enable- Count=1/4 Maskable+ 64bit+
>         Capabilities: [70] Express Endpoint, MSI 00
>         Capabilities: [100] Advanced Error Reporting
>         Capabilities: [140] Virtual Channel
>         Capabilities: [300] Device Serial Number 00-00-00-00-00-00-00-00
>         Kernel driver in use: ath9k
>
> parts of hostapd.conf that seem relevant:
>
> interface=wlan0
> bridge=br0
> driver=nl80211
> hw_mode=a
> channel=48
> rts_threshold=2347
> fragm_threshold=2346
>
> wmm_enabled=1
> wmm_ac_bk_cwmin=4
> wmm_ac_bk_cwmax=10
> wmm_ac_bk_aifs=7
> wmm_ac_bk_txop_limit=0
> wmm_ac_bk_acm=0
> wmm_ac_be_aifs=3
> wmm_ac_be_cwmin=4
> wmm_ac_be_cwmax=10
> wmm_ac_be_txop_limit=0
> wmm_ac_be_acm=0
> wmm_ac_vi_aifs=2
> wmm_ac_vi_cwmin=3
> wmm_ac_vi_cwmax=4
> wmm_ac_vi_txop_limit=94
> wmm_ac_vi_acm=0
> wmm_ac_vo_aifs=2
> wmm_ac_vo_cwmin=2
> wmm_ac_vo_cwmax=3
> wmm_ac_vo_txop_limit=47
> wmm_ac_vo_acm=0
> ieee80211n=1
> ht_capab=[HT40-][SHORT-GI-40]
>
> wpa=2
> wpa_key_mgmt=WPA-PSK WPA-EAP
> wpa_pairwise=CCMP TKIP
> rsn_pairwise=CCMP
>
> _______________________________________________
> ath9k-devel mailing list
> ath9k-devel at lists.ath9k.org
> https://lists.ath9k.org/mailman/listinfo/ath9k-devel
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.ath9k.org/pipermail/ath9k-devel/attachments/20130825/479d05c8/attachment.html 

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

That's a good point, WMM probably has nothing to do with it. I still
suppose it's a problem on the AP side rather than the client, as the 
clients are much more extensively tested. Where should I start
debugging? I'm a bit lost since it works without errors in logs..
Cheers, 
Rasto

> Sounds like enabling WMM enables using 11n rates and you're seeing some
> significant packet loss when 11n is enabled.
> 
> 
> -adrian
> 
> 
> 
> On 25 August 2013 08:57, Rasto Sramek <rasto@100acrewood.org> wrote:
>      Hello,
> 
>      I was redirected here from hostapd mailinglist. I am trying to
>      run a 802.11n 5GHz AP from a tl-wdn4800 pci-e 3x3 adapter which seems
>      to
>      contain a AR9380 chipset.
> 
>      With disabled wmm (wmm_enabled=0), I get reliable connection at 54
>      Mb/s,
>      1-4ms ping with no apparent packet loss, and around 17 Mb/sec actual
>      transfer rate:
>      [ ??3] ??0.0- 1.0 sec ??2.12 MBytes ??17.8 Mbits/sec
>      [ ??3] ??1.0- 2.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??2.0- 3.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??3.0- 4.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??4.0- 5.0 sec ??2.12 MBytes ??17.8 Mbits/sec
>      [ ??3] ??5.0- 6.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??6.0- 7.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??7.0- 8.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??8.0- 9.0 sec ??2.00 MBytes ??16.8 Mbits/sec
>      [ ??3] ??9.0-10.0 sec ??2.12 MBytes ??17.8 Mbits/sec
> 
> 
>      When I enable wmm, I get faster connection (up to 270 Mbit/s)
>      with significant packet loss which seems to come every few
>      seconds for a second or more:
> 
>      [ ??3] ??0.0- 1.0 sec ??9.12 MBytes ??76.5 Mbits/sec
>      [ ??3] ??1.0- 2.0 sec ??4.75 MBytes ??39.8 Mbits/sec
>      [ ??3] ??2.0- 3.0 sec ??3.38 MBytes ??28.3 Mbits/sec
>      [ ??3] ??3.0- 4.0 sec ??3.25 MBytes ??27.3 Mbits/sec
>      [ ??3] ??4.0- 5.0 sec ??6.25 MBytes ??52.4 Mbits/sec
>      [ ??3] ??5.0- 6.0 sec ??3.50 MBytes ??29.4 Mbits/sec
>      [ ??3] ??6.0- 7.0 sec ??3.12 MBytes ??26.2 Mbits/sec
>      [ ??3] ??7.0- 8.0 sec ??6.38 MBytes ??53.5 Mbits/sec
>      [ ??3] ??8.0- 9.0 sec ??0.00 Bytes ??0.00 bits/sec
>      [ ??3] ??9.0-10.0 sec ??6.62 MBytes ??55.6 Mbits/sec
>      [ ??3] 10.0-11.0 sec ??0.00 Bytes ??0.00 bits/sec
>      [ ??3] 11.0-12.0 sec ??1.12 MBytes ??9.44 Mbits/sec
>      [ ??3] 12.0-13.0 sec ??5.25 MBytes ??44.0 Mbits/sec
>      [ ??3] 13.0-14.0 sec ??6.38 MBytes ??53.5 Mbits/sec
>      [ ??3] 14.0-15.0 sec ??3.25 MBytes ??27.3 Mbits/sec
>      [ ??3] 15.0-16.0 sec ??3.75 MBytes ??31.5 Mbits/sec
>      [ ??3] 16.0-17.0 sec ??0.00 Bytes ??0.00 bits/sec
>      [ ??3] 17.0-18.0 sec ??0.00 Bytes ??0.00 bits/sec
>      [ ??3] 18.0-19.0 sec ??6.88 MBytes ??57.7 Mbits/sec
>      [ ??3] 19.0-20.0 sec ??0.00 Bytes ??0.00 bits/sec
>      [ ??3] 20.0-21.0 sec ??0.00 Bytes ??0.00 bits/sec
>      [ ??3] ??0.0-21.4 sec ??73.1 MBytes ??28.6 Mbits/sec
> 
>      This doesn't seem to be just QoS load prioritizing,
>      since ping in a no-load situation has similar issues:
> 
>      64 bytes from 10.0.0.1: icmp_seq=1 ttl=64 time=6.14 ms
>      64 bytes from 10.0.0.1: icmp_seq=2 ttl=64 time=2.85 ms
>      64 bytes from 10.0.0.1: icmp_seq=3 ttl=64 time=5.03 ms
>      64 bytes from 10.0.0.1: icmp_seq=4 ttl=64 time=3.79 ms
>      64 bytes from 10.0.0.1: icmp_seq=5 ttl=64 time=3.66 ms
>      64 bytes from 10.0.0.1: icmp_seq=6 ttl=64 time=2.50 ms
>      64 bytes from 10.0.0.1: icmp_seq=13 ttl=64 time=2.54 ms
>      64 bytes from 10.0.0.1: icmp_seq=17 ttl=64 time=2.96 ms
>      64 bytes from 10.0.0.1: icmp_seq=18 ttl=64 time=7.66 ms
>      64 bytes from 10.0.0.1: icmp_seq=19 ttl=64 time=3.22 ms
> 
>      Both clients, running Intel Ultimate-N 6300 3x3 cards,
>      are affected. The wireless spectrum seems to be more or less clear
>      containing only 2 other weak 5GHz APs, both on Channel 44
>      compared to my 48.
> 
>      Is this a known issue? I couldn't find any mention of it.
>      Can someone point me at what I should try? I tried
>      using a different channel but I did not see any change
>      in behavior.
> 
>      Thanks!
> 
>      Rasto
> 
>      ====================================================
>      Versions/Configuration:
> 
>      Client:
>      Debian, vanilla 3.9.8 kernel, wpa_supplicant 1.0-3+b2
>      Server:
>      Debian, debian 3.10-2-amd64 kernel, hostapd 1.0-3+b2
> 
>      lspci -v:
>      01:00.0 Network controller: Qualcomm Atheros AR93xx Wireless Network
>      Adapter (rev 01)
>      ?? ?? ?? ?? Subsystem: Qualcomm Atheros Device 3112
>      ?? ?? ?? ?? Physical Slot: 0
>      ?? ?? ?? ?? Flags: bus master, fast devsel, latency 0, IRQ 16
>      ?? ?? ?? ?? Memory at fe9e0000 (64-bit, non-prefetchable) [size=128K]
>      ?? ?? ?? ?? Expansion ROM at fe9d0000 [disabled] [size=64K]
>      ?? ?? ?? ?? Capabilities: [40] Power Management version 3
>      ?? ?? ?? ?? Capabilities: [50] MSI: Enable- Count=1/4 Maskable+ 64bit+
>      ?? ?? ?? ?? Capabilities: [70] Express Endpoint, MSI 00
>      ?? ?? ?? ?? Capabilities: [100] Advanced Error Reporting
>      ?? ?? ?? ?? Capabilities: [140] Virtual Channel
>      ?? ?? ?? ?? Capabilities: [300] Device Serial Number 00-00-00-00-00-00-
>      00-00
>      ?? ?? ?? ?? Kernel driver in use: ath9k
> 
>      parts of hostapd.conf that seem relevant:
> 
>      interface=wlan0
>      bridge=br0
>      driver=nl80211
>      hw_mode=a
>      channel=48
>      rts_threshold=2347
>      fragm_threshold=2346
> 
>      wmm_enabled=1
>      wmm_ac_bk_cwmin=4
>      wmm_ac_bk_cwmax=10
>      wmm_ac_bk_aifs=7
>      wmm_ac_bk_txop_limit=0
>      wmm_ac_bk_acm=0
>      wmm_ac_be_aifs=3
>      wmm_ac_be_cwmin=4
>      wmm_ac_be_cwmax=10
>      wmm_ac_be_txop_limit=0
>      wmm_ac_be_acm=0
>      wmm_ac_vi_aifs=2
>      wmm_ac_vi_cwmin=3
>      wmm_ac_vi_cwmax=4
>      wmm_ac_vi_txop_limit=94
>      wmm_ac_vi_acm=0
>      wmm_ac_vo_aifs=2
>      wmm_ac_vo_cwmin=2
>      wmm_ac_vo_cwmax=3
>      wmm_ac_vo_txop_limit=47
>      wmm_ac_vo_acm=0
>      ieee80211n=1
>      ht_capab=[HT40-][SHORT-GI-40]
> 
>      wpa=2
>      wpa_key_mgmt=WPA-PSK WPA-EAP
>      wpa_pairwise=CCMP TKIP
>      rsn_pairwise=CCMP
> 
>      _______________________________________________
>      ath9k-devel mailing list
>      ath9k-devel at lists.ath9k.org
>      https://lists.ath9k.org/mailman/listinfo/ath9k-devel
> 

[ath9k-devel] Hostapd+WMM causes packet loss

[Mihai Moldovan]

* On 25.08.2013 08:28 PM, Rasto Sramek wrote:
> I'm a bit lost since it works without errors in logs..

Did you by any chance enable DFS in your kernel config?

-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 4506 bytes
Desc: S/MIME Cryptographic Signature
Url : http://lists.ath9k.org/pipermail/ath9k-devel/attachments/20130826/52b091bf/attachment-0001.bin 

[ath9k-devel] Hostapd+WMM causes packet loss

[Mihai Moldovan]

* On 26.08.2013 12:33 AM, Rasto Sramek wrote:
> I do not see an option to enable it, could you tell me what the config
> keyword is called?

The symbol is ATH9K_DFS_CERTIFIED. Only disable it, if it is enabled, do NOT
turn it on.

I've seen packet loss with that enabled (worse at higher speeds when WMM was
turned on.)

However, if ATH9K_DFS_CERTIFIED is unset, that likely isn't the problem, sorry
for not being too helpful.

-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 4506 bytes
Desc: S/MIME Cryptographic Signature
Url : http://lists.ath9k.org/pipermail/ath9k-devel/attachments/20130826/6d08856b/attachment.bin 

[ath9k-devel] Hostapd+WMM causes packet loss

[Sujith Manoharan]

Rasto Sramek wrote:
> Both clients, running Intel Ultimate-N 6300 3x3 cards,
> are affected. The wireless spectrum seems to be more or less clear
> containing only 2 other weak 5GHz APs, both on Channel 44
> compared to my 48.

Does disabling PowerSave on the clients help ?
(iw dev wlan# set power_save off, iw dev wlan# get power_save).

Sujith

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

> * On 26.08.2013 12:33 AM, Rasto Sramek wrote:
> > I do not see an option to enable it, could you tell me what the config
> > keyword is called?
> 
> The symbol is ATH9K_DFS_CERTIFIED. Only disable it, if it is enabled, do NOT
> turn it on.
> 
> I've seen packet loss with that enabled (worse at higher speeds when WMM was
> turned on.)
> 
> However, if ATH9K_DFS_CERTIFIED is unset, that likely isn't the problem, sorry
> for not being too helpful.
> 
It isn't set, thanks anyway!

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

On Mon, Aug 26, 2013 at 08:59:18AM +0530, Sujith Manoharan wrote:
> Rasto Sramek wrote:
> > Both clients, running Intel Ultimate-N 6300 3x3 cards,
> > are affected. The wireless spectrum seems to be more or less clear
> > containing only 2 other weak 5GHz APs, both on Channel 44
> > compared to my 48.
> 
> Does disabling PowerSave on the clients help ?
> (iw dev wlan# set power_save off, iw dev wlan# get power_save).
> 
> Sujith
It doesn't unfortunately. 

Rasto

[ath9k-devel] Hostapd+WMM causes packet loss

[Sujith Manoharan]

Rasto Sramek wrote:
> > Does disabling PowerSave on the clients help ?
> > (iw dev wlan# set power_save off, iw dev wlan# get power_save).
> > 
> It doesn't unfortunately. 

Ok. Can you post the full hostapd log when the issue happens ?
(hostapd -ddt <conf_file>)

Sujith

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

On 08/26/2013 08:30 AM, Sujith Manoharan wrote:
> Rasto Sramek wrote:
>>> Does disabling PowerSave on the clients help ?
>>> (iw dev wlan# set power_save off, iw dev wlan# get power_save).
>>>
>> It doesn't unfortunately.
> Ok. Can you post the full hostapd log when the issue happens ?
> (hostapd -ddt <conf_file>)
>
> Sujith
I'm attaching the log from start to the problem. There is nothing
actually at the time of the problems apart from
1377499227.634542: Add randomness: count=699 entropy=585 messages.

Earlier on it says it switched channels because secondary contains 
another BSS.

Best,
Rasto

-------------- next part --------------
1377499189.241636: random: Trying to read entropy from /dev/random
1377499189.241721: Configuration file: /etc/hostapd/hostapd.conf
1377499189.243324: ctrl_interface_group=0
1377499189.244891: nl80211: interface wlan0 in phy phy0
1377499189.244927: rfkill: initial event: idx=0 type=1 op=0 soft=0 hard=0
1377499189.245198: nl80211: Using driver-based off-channel TX
1377499189.245212: nl80211: Use separate P2P group interface (driver advertised support)
1377499189.245215: nl80211: TDLS supported
1377499189.245217: nl80211: TDLS external setup
1377499189.245547: nl80211: Add own interface ifindex 5
1377499189.245554: nl80211: Add own interface ifindex 4
1377499189.245566: nl80211: Set mode ifindex 4 iftype 3 (AP)
1377499189.289479: nl80211: Create interface iftype 6 (MONITOR)
1377499189.290371: nl80211: New interface mon.wlan0 created: ifindex=22
1377499189.290385: nl80211: Add own interface ifindex 22
1377499189.329650: nl80211: Adding interface wlan0 into bridge br0
1377499189.353830: BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits)
1377499189.354173: nl80211: Regulatory information - country=DK
1377499189.354182: nl80211: 2402-2482 @ 40 MHz
1377499189.354188: nl80211: 5170-5250 @ 40 MHz
1377499189.354192: nl80211: 5250-5330 @ 40 MHz
1377499189.354196: nl80211: 5490-5710 @ 40 MHz
1377499189.354201: nl80211: 57240-65880 @ 2160 MHz
1377499189.354244: nl80211: Added 802.11b mode based on 802.11g information
1377499189.354250: Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm
1377499189.354258: Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm
1377499189.354262: Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm
1377499189.354265: Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm
1377499189.354268: Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm
1377499189.354272: Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm
1377499189.354276: Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm
1377499189.354279: Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm
1377499189.354282: Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm
1377499189.354285: Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm
1377499189.354289: Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm
1377499189.354292: Allowed channel: mode=1 chan=12 freq=2467 MHz max_tx_power=20 dBm
1377499189.354295: Allowed channel: mode=1 chan=13 freq=2472 MHz max_tx_power=20 dBm
1377499189.354298: Allowed channel: mode=2 chan=36 freq=5180 MHz max_tx_power=20 dBm
1377499189.354302: Allowed channel: mode=2 chan=40 freq=5200 MHz max_tx_power=20 dBm
1377499189.354305: Allowed channel: mode=2 chan=44 freq=5220 MHz max_tx_power=20 dBm
1377499189.354308: Allowed channel: mode=2 chan=48 freq=5240 MHz max_tx_power=20 dBm
1377499189.354312: Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm
1377499189.354315: Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm
1377499189.354318: Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm
1377499189.354322: Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm
1377499189.354325: Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm
1377499189.354329: Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm
1377499189.354332: Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm
1377499189.354335: Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm
1377499189.354339: Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm
1377499189.354342: Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm
1377499189.354345: Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm
1377499189.354348: Allowed channel: mode=0 chan=12 freq=2467 MHz max_tx_power=20 dBm
1377499189.354352: Allowed channel: mode=0 chan=13 freq=2472 MHz max_tx_power=20 dBm
1377499189.354360: Scan for neighboring BSSes prior to enabling 40 MHz channel
1377499189.359559: Scan requested (ret=0) - scan timeout 10 seconds
1377499189.359715: Interface initialization will be completed in a callback
1377499189.359790: random: Got 20/20 bytes from /dev/random
1377499189.359852: RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
1377499189.359929: nl80211: Ignore interface down event since interface mon.wlan0 is up
1377499189.359942: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499189.359963: RTM_NEWLINK, IFLA_IFNAME: Interface 'mon.wlan0' added
1377499189.359975: Unknown event 5
1377499189.359985: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499189.359991: RTM_NEWLINK, IFLA_IFNAME: Interface 'br0' added
1377499189.359995: Unknown event 5
1377499189.360003: RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
1377499189.360031: nl80211: Ignore interface down event since interface wlan0 is up
1377499189.360043: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1377499189.360048: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499189.360071: nl80211: if_removed already cleared - ignore event
1377499189.360082: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1377499189.360088: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499189.360110: nl80211: if_removed already cleared - ignore event
1377499189.360132: nl80211: Add ifindex 5 for bridge br0
1377499189.360137: nl80211: Add own interface ifindex 5
1377499189.360143: nl80211: Event message available
1377499189.360166: nl80211: Scan trigger
1377499189.381836: Add randomness: count=1 entropy=0
1377499189.408130: Add randomness: count=2 entropy=1
1377499189.422288: Add randomness: count=3 entropy=2
1377499189.524705: Add randomness: count=4 entropy=3
1377499189.627095: Add randomness: count=5 entropy=4
1377499189.741913: Add randomness: count=6 entropy=5
1377499189.808774: Add randomness: count=7 entropy=6
1377499189.831918: Add randomness: count=8 entropy=7
1377499189.844846: Add randomness: count=9 entropy=8
1377499189.888081: Add randomness: count=10 entropy=9
1377499189.889959: Add randomness: count=11 entropy=10
1377499189.911177: Add randomness: count=12 entropy=11
1377499189.934275: Add randomness: count=13 entropy=12
1377499189.946711: Add randomness: count=14 entropy=13
1377499189.980861: Add randomness: count=15 entropy=14
1377499189.990507: Add randomness: count=16 entropy=15
1377499189.992345: Add randomness: count=17 entropy=16
1377499189.995385: Add randomness: count=18 entropy=17
1377499190.013563: Add randomness: count=19 entropy=18
1377499190.036714: Add randomness: count=20 entropy=19
1377499190.049070: Add randomness: count=21 entropy=20
1377499190.092886: Add randomness: count=22 entropy=21
1377499190.094791: Add randomness: count=23 entropy=22
1377499190.115978: Add randomness: count=24 entropy=23
1377499190.139104: Add randomness: count=25 entropy=24
1377499190.241518: Add randomness: count=26 entropy=25
1377499190.288866: Add randomness: count=27 entropy=26
1377499190.343901: Add randomness: count=28 entropy=27
1377499190.446310: Add randomness: count=29 entropy=28
1377499190.491912: Add randomness: count=30 entropy=29
1377499190.496749: Add randomness: count=31 entropy=30
1377499191.040526: Add randomness: count=32 entropy=31
1377499191.076759: Add randomness: count=33 entropy=32
1377499191.629553: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1377499191.629574: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499191.629635: nl80211: if_removed already cleared - ignore event
1377499191.629644: nl80211: Event message available
1377499191.629677: nl80211: New scan results available
1377499191.629996: nl80211: Received scan results (16 BSSes)
1377499191.630179: Switch own primary and secondary channel to get secondary channel with no Beacons from other BSSes
1377499191.630210: HT40: control channel: 44  secondary channel: 48
1377499191.630215: Completing interface initialization
1377499191.630219: Mode: IEEE 802.11a  Channel: 44  Frequency: 5220 MHz
1377499191.630232: nl80211: Set freq 5220 (ht_enabled=1 sec_channel_offset=1)
1377499191.630352: Failed to update rate sets in kernel module
1377499191.630359: RATE[0] rate=60 flags=0x1
1377499191.630473: RATE[1] rate=90 flags=0x0
1377499191.630477: RATE[2] rate=120 flags=0x1
1377499191.630481: RATE[3] rate=180 flags=0x0
1377499191.630485: RATE[4] rate=240 flags=0x1
1377499191.630489: RATE[5] rate=360 flags=0x0
1377499191.630492: RATE[6] rate=480 flags=0x0
1377499191.630496: RATE[7] rate=540 flags=0x0
1377499191.630563: Flushing old station entries
1377499191.673489: Deauthenticate all stations
1377499191.673731: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
1377499191.673809: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
1377499191.673870: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
1377499191.673925: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
1377499191.673962: Using interface wlan0 with hwaddr a0:f3:c1:0e:7e:9c and ssid '100acrewood'
1377499191.674014: Get randomness: len=32 entropy=33
1377499191.675529: GMK - hexdump(len=32): [REMOVED]
1377499191.675538: Get randomness: len=32 entropy=1
1377499191.676900: Key Counter - hexdump(len=32): [REMOVED]
1377499191.676905: WPA: Delay group state machine start until Beacon frames have been configured
1377499191.685627: VLAN: vlan_set_name_type(name_type=2)
1377499191.685725: nl80211: Set beacon (beacon_set=0)
1377499191.695109: WPA: Start group state machine to set initial keys
1377499191.695121: WPA: group state machine entering state GTK_INIT (VLAN-ID 0)
1377499191.695127: Get randomness: len=16 entropy=0
1377499191.696582: GTK - hexdump(len=16): [REMOVED]
1377499191.696589: WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
1377499191.696629: wpa_driver_nl80211_set_key: ifindex=4 alg=3 addr=0x491d42 key_idx=1 set_tx=1 seq_len=0 key_len=16
1377499191.696645:    broadcast key
1377499191.696810: wpa_driver_nl80211_set_operstate: operstate 0->1 (UP)
1377499191.696825: netlink: Operstate: linkmode=-1, operstate=6
1377499191.697137: wlan0: Setup of interface done.
1377499191.697177: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499191.697185: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499191.697209: nl80211: if_removed already cleared - ignore event
1377499191.697231: nl80211: Add ifindex 5 for bridge br0
1377499191.697236: nl80211: Add own interface ifindex 5
1377499191.697244: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499191.697249: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499191.697270: nl80211: if_removed already cleared - ignore event
1377499191.697291: nl80211: Add ifindex 5 for bridge br0
1377499191.697295: nl80211: Add own interface ifindex 5
1377499191.697303: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499191.697307: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499191.697328: nl80211: if_removed already cleared - ignore event
1377499191.697349: nl80211: Add ifindex 5 for bridge br0
1377499191.697353: nl80211: Add own interface ifindex 5
1377499191.697361: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499191.697365: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499191.697427: nl80211: if_removed already cleared - ignore event
1377499191.697441: VLAN: vlan_newlink(wlan0)
1377499191.697465: VLAN: vlan_newlink(wlan0)
1377499191.697484: VLAN: vlan_newlink(wlan0)
1377499191.697503: VLAN: vlan_newlink(wlan0)
1377499191.757310: Add randomness: count=34 entropy=0
1377499191.793498: Add randomness: count=35 entropy=1
1377499191.859698: Add randomness: count=36 entropy=2
1377499191.895939: Add randomness: count=37 entropy=3
1377499191.962116: Add randomness: count=38 entropy=4
1377499191.998292: Add randomness: count=39 entropy=5
1377499192.064497: Add randomness: count=40 entropy=6
1377499192.100717: Add randomness: count=41 entropy=7
1377499192.166862: Add randomness: count=42 entropy=8
1377499192.203094: Add randomness: count=43 entropy=9
1377499192.269261: Add randomness: count=44 entropy=10
1377499192.305565: Add randomness: count=45 entropy=11
1377499192.371733: Add randomness: count=46 entropy=12
1377499192.407910: Add randomness: count=47 entropy=13
1377499192.474075: Add randomness: count=48 entropy=14
1377499192.510326: Add randomness: count=49 entropy=15
1377499192.576474: Add randomness: count=50 entropy=16
1377499192.612754: Add randomness: count=51 entropy=17
1377499192.678874: Add randomness: count=52 entropy=18
1377499192.715117: Add randomness: count=53 entropy=19
1377499192.781278: Add randomness: count=54 entropy=20
1377499192.817553: Add randomness: count=55 entropy=21
1377499192.883699: Add randomness: count=56 entropy=22
1377499192.919934: Add randomness: count=57 entropy=23
1377499192.986089: Add randomness: count=58 entropy=24
1377499193.022323: Add randomness: count=59 entropy=25
1377499193.088490: Add randomness: count=60 entropy=26
1377499193.124712: Add randomness: count=61 entropy=27
1377499193.190898: Add randomness: count=62 entropy=28
1377499193.227127: Add randomness: count=63 entropy=29
1377499193.293291: Add randomness: count=64 entropy=30
1377499193.329526: Add randomness: count=65 entropy=31
1377499193.395716: Add randomness: count=66 entropy=32
1377499193.431940: Add randomness: count=67 entropy=33
1377499193.498103: Add randomness: count=68 entropy=34
1377499193.534345: Add randomness: count=69 entropy=35
1377499193.600501: Add randomness: count=70 entropy=36
1377499193.636758: Add randomness: count=71 entropy=37
1377499193.702915: Add randomness: count=72 entropy=38
1377499193.739139: Add randomness: count=73 entropy=39
1377499193.805289: Add randomness: count=74 entropy=40
1377499193.841546: Add randomness: count=75 entropy=41
1377499193.907715: Add randomness: count=76 entropy=42
1377499193.943939: Add randomness: count=77 entropy=43
1377499194.010107: Add randomness: count=78 entropy=44
1377499194.046339: Add randomness: count=79 entropy=45
1377499194.112514: Add randomness: count=80 entropy=46
1377499194.148734: Add randomness: count=81 entropy=47
1377499194.214904: Add randomness: count=82 entropy=48
1377499194.251159: Add randomness: count=83 entropy=49
1377499194.317307: Add randomness: count=84 entropy=50
1377499194.353552: Add randomness: count=85 entropy=51
1377499194.419708: Add randomness: count=86 entropy=52
1377499194.455950: Add randomness: count=87 entropy=53
1377499194.522110: Add randomness: count=88 entropy=54
1377499194.558342: Add randomness: count=89 entropy=55
1377499194.624535: Add randomness: count=90 entropy=56
1377499194.660783: Add randomness: count=91 entropy=57
1377499194.726953: Add randomness: count=92 entropy=58
1377499194.763159: Add randomness: count=93 entropy=59
1377499194.829365: Add randomness: count=94 entropy=60
1377499194.865563: Add randomness: count=95 entropy=61
1377499194.931718: Add randomness: count=96 entropy=62
1377499194.967963: Add randomness: count=97 entropy=63
1377499195.034155: Add randomness: count=98 entropy=64
1377499195.070358: Add randomness: count=99 entropy=65
1377499195.136537: Add randomness: count=100 entropy=66
1377499195.172818: Add randomness: count=101 entropy=67
1377499195.238934: Add randomness: count=102 entropy=68
1377499195.275207: Add randomness: count=103 entropy=69
1377499195.341347: Add randomness: count=104 entropy=70
1377499195.377602: Add randomness: count=105 entropy=71
1377499195.443742: Add randomness: count=106 entropy=72
1377499195.480001: Add randomness: count=107 entropy=73
1377499195.546161: Add randomness: count=108 entropy=74
1377499195.582387: Add randomness: count=109 entropy=75
1377499195.648576: Add randomness: count=110 entropy=76
1377499195.684798: Add randomness: count=111 entropy=77
1377499195.750934: Add randomness: count=112 entropy=78
1377499195.787172: Add randomness: count=113 entropy=79
1377499195.853331: Add randomness: count=114 entropy=80
1377499195.889613: Add randomness: count=115 entropy=81
1377499195.955736: Add randomness: count=116 entropy=82
1377499195.992015: Add randomness: count=117 entropy=83
1377499196.058177: Add randomness: count=118 entropy=84
1377499196.094421: Add randomness: count=119 entropy=85
1377499196.160541: Add randomness: count=120 entropy=86
1377499196.196824: Add randomness: count=121 entropy=87
1377499196.272406: Add randomness: count=122 entropy=88
1377499196.299217: Add randomness: count=123 entropy=89
1377499196.365337: Add randomness: count=124 entropy=90
1377499196.401589: Add randomness: count=125 entropy=91
1377499196.467777: Add randomness: count=126 entropy=92
1377499196.503998: Add randomness: count=127 entropy=93
1377499196.570170: Add randomness: count=128 entropy=94
1377499196.606407: Add randomness: count=129 entropy=95
1377499196.672574: Add randomness: count=130 entropy=96
1377499196.708826: Add randomness: count=131 entropy=97
1377499196.774944: Add randomness: count=132 entropy=98
1377499196.811208: Add randomness: count=133 entropy=99
1377499196.877381: Add randomness: count=134 entropy=100
1377499196.913668: Add randomness: count=135 entropy=101
1377499196.979780: Add randomness: count=136 entropy=102
1377499197.016004: Add randomness: count=137 entropy=103
1377499197.082172: Add randomness: count=138 entropy=104
1377499197.118423: Add randomness: count=139 entropy=105
1377499197.184559: Add randomness: count=140 entropy=106
1377499197.220831: Add randomness: count=141 entropy=107
1377499197.286961: Add randomness: count=142 entropy=108
1377499197.323218: Add randomness: count=143 entropy=109
1377499197.389357: Add randomness: count=144 entropy=110
1377499197.425604: Add randomness: count=145 entropy=111
1377499197.491764: Add randomness: count=146 entropy=112
1377499197.528008: Add randomness: count=147 entropy=113
1377499197.594187: Add randomness: count=148 entropy=114
1377499197.630425: Add randomness: count=149 entropy=115
1377499197.696565: Add randomness: count=150 entropy=116
1377499197.732816: Add randomness: count=151 entropy=117
1377499197.798967: Add randomness: count=152 entropy=118
1377499197.835230: Add randomness: count=153 entropy=119
1377499197.901375: Add randomness: count=154 entropy=120
1377499197.937615: Add randomness: count=155 entropy=121
1377499198.003768: Add randomness: count=156 entropy=122
1377499198.040076: Add randomness: count=157 entropy=123
1377499198.106174: Add randomness: count=158 entropy=124
1377499198.142443: Add randomness: count=159 entropy=125
1377499198.208588: Add randomness: count=160 entropy=126
1377499198.244819: Add randomness: count=161 entropy=127
1377499198.310988: Add randomness: count=162 entropy=128
1377499198.347251: Add randomness: count=163 entropy=129
1377499198.413413: Add randomness: count=164 entropy=130
1377499198.449635: Add randomness: count=165 entropy=131
1377499198.515805: Add randomness: count=166 entropy=132
1377499198.552052: Add randomness: count=167 entropy=133
1377499198.618218: Add randomness: count=168 entropy=134
1377499198.654445: Add randomness: count=169 entropy=135
1377499198.720598: Add randomness: count=170 entropy=136
1377499198.756784: Add randomness: count=171 entropy=137
1377499198.822982: Add randomness: count=172 entropy=138
1377499198.859117: Add randomness: count=173 entropy=139
1377499198.925421: Add randomness: count=174 entropy=140
1377499198.961467: Add randomness: count=175 entropy=141
1377499199.027789: Add randomness: count=176 entropy=142
1377499199.063829: Add randomness: count=177 entropy=143
1377499199.130208: Add randomness: count=178 entropy=144
1377499199.166221: Add randomness: count=179 entropy=145
1377499199.232595: Add randomness: count=180 entropy=146
1377499199.268623: Add randomness: count=181 entropy=147
1377499199.334994: Add randomness: count=182 entropy=148
1377499199.371035: Add randomness: count=183 entropy=149
1377499199.437421: Add randomness: count=184 entropy=150
1377499199.473431: Add randomness: count=185 entropy=151
1377499199.539812: Add randomness: count=186 entropy=152
1377499199.575822: Add randomness: count=187 entropy=153
1377499199.642232: Add randomness: count=188 entropy=154
1377499199.678269: Add randomness: count=189 entropy=155
1377499199.744603: Add randomness: count=190 entropy=156
1377499199.847008: Add randomness: count=191 entropy=157
1377499199.949434: Add randomness: count=192 entropy=158
1377499200.051811: Add randomness: count=193 entropy=159
1377499200.154223: Add randomness: count=194 entropy=160
1377499200.256630: Add randomness: count=195 entropy=161
1377499200.292865: Add randomness: count=196 entropy=162
1377499200.359041: Add randomness: count=197 entropy=163
1377499200.395266: Add randomness: count=198 entropy=164
1377499200.461414: Add randomness: count=199 entropy=165
1377499200.497682: Add randomness: count=200 entropy=166
1377499200.563814: Add randomness: count=201 entropy=167
1377499200.600067: Add randomness: count=202 entropy=168
1377499200.666242: Add randomness: count=203 entropy=169
1377499200.702521: Add randomness: count=204 entropy=170
1377499200.768644: Add randomness: count=205 entropy=171
1377499200.804882: Add randomness: count=206 entropy=172
1377499200.871033: Add randomness: count=207 entropy=173
1377499200.907290: Add randomness: count=208 entropy=174
1377499200.973441: Add randomness: count=209 entropy=175
1377499201.010116: Add randomness: count=210 entropy=176
1377499201.010195: Add randomness: count=211 entropy=177
1377499201.075836: Add randomness: count=212 entropy=178
1377499201.112103: Add randomness: count=213 entropy=179
1377499201.178221: Add randomness: count=214 entropy=180
1377499201.214498: Add randomness: count=215 entropy=181
1377499201.280630: Add randomness: count=216 entropy=182
1377499201.316919: Add randomness: count=217 entropy=183
1377499201.383042: Add randomness: count=218 entropy=184
1377499201.419319: Add randomness: count=219 entropy=185
1377499201.485452: Add randomness: count=220 entropy=186
1377499201.521691: Add randomness: count=221 entropy=187
1377499201.587842: Add randomness: count=222 entropy=188
1377499201.624131: Add randomness: count=223 entropy=189
1377499201.690284: Add randomness: count=224 entropy=190
1377499201.726556: Add randomness: count=225 entropy=191
1377499201.792634: Add randomness: count=226 entropy=192
1377499201.828920: Add randomness: count=227 entropy=193
1377499201.895074: Add randomness: count=228 entropy=194
1377499201.931310: Add randomness: count=229 entropy=195
1377499201.997452: Add randomness: count=230 entropy=196
1377499202.033749: Add randomness: count=231 entropy=197
1377499202.099849: Add randomness: count=232 entropy=198
1377499202.136132: Add randomness: count=233 entropy=199
1377499202.202259: Add randomness: count=234 entropy=200
1377499202.238533: Add randomness: count=235 entropy=201
1377499202.304657: Add randomness: count=236 entropy=202
1377499202.340919: Add randomness: count=237 entropy=203
1377499202.407061: Add randomness: count=238 entropy=204
1377499202.443325: Add randomness: count=239 entropy=205
1377499202.509457: Add randomness: count=240 entropy=206
1377499202.545723: Add randomness: count=241 entropy=207
1377499202.611873: Add randomness: count=242 entropy=208
1377499202.648123: Add randomness: count=243 entropy=209
1377499202.714295: Add randomness: count=244 entropy=210
1377499202.750530: Add randomness: count=245 entropy=211
1377499202.816666: Add randomness: count=246 entropy=212
1377499202.852943: Add randomness: count=247 entropy=213
1377499202.919051: Add randomness: count=248 entropy=214
1377499202.955318: Add randomness: count=249 entropy=215
1377499203.021460: Add randomness: count=250 entropy=216
1377499203.057726: Add randomness: count=251 entropy=217
1377499203.123879: Add randomness: count=252 entropy=218
1377499203.160137: Add randomness: count=253 entropy=219
1377499203.226276: Add randomness: count=254 entropy=220
1377499203.262549: Add randomness: count=255 entropy=221
1377499203.328676: Add randomness: count=256 entropy=222
1377499203.431060: Add randomness: count=257 entropy=223
1377499203.533484: Add randomness: count=258 entropy=224
1377499203.569753: Add randomness: count=259 entropy=225
1377499203.635889: Add randomness: count=260 entropy=226
1377499203.672157: Add randomness: count=261 entropy=227
1377499203.685182: mgmt::auth
1377499203.685194: authentication: STA=00:24:d7:54:c3:68 auth_alg=0 auth_transaction=1 status_code=0 wep=0
1377499203.685306:   New STA
1377499203.685338: wlan0: STA 00:24:d7:54:c3:68 IEEE 802.11: authentication OK (open system)
1377499203.685360: wlan0: STA 00:24:d7:54:c3:68 MLME: MLME-AUTHENTICATE.indication(00:24:d7:54:c3:68, OPEN_SYSTEM)
1377499203.685370: wlan0: STA 00:24:d7:54:c3:68 MLME: MLME-DELETEKEYS.request(00:24:d7:54:c3:68)
1377499203.685373: authentication reply: STA=00:24:d7:54:c3:68 auth_alg=0 auth_transaction=2 resp=0 (IE len=0)
1377499203.685449: Add randomness: count=262 entropy=228
1377499203.685678: mgmt::auth cb
1377499203.685691: wlan0: STA 00:24:d7:54:c3:68 IEEE 802.11: authenticated
1377499203.686659: mgmt::assoc_req
1377499203.686669: association request: STA=00:24:d7:54:c3:68 capab_info=0x11 listen_interval=10
1377499203.686679: WMM IE - hexdump(len=7): 00 50 f2 02 00 01 00
1377499203.686688: Validating WMM IE: OUI 00:50:f2  OUI type 2  OUI sub-type 0  version 1  QoS info 0x0
1377499203.686718:   new AID 1
1377499203.686723: HT: STA 00:24:d7:54:c3:68 HT Capabilities Info: 0x0876
1377499203.686728: hostapd_ht_operation_update current operation mode=0x0
1377499203.686735: hostapd_ht_operation_update new operation mode=0x0 changes=0
1377499203.686749: wlan0: STA 00:24:d7:54:c3:68 IEEE 802.11: association OK (aid 1)
1377499203.686799: Add randomness: count=263 entropy=229
1377499203.687136: mgmt::assoc_resp cb
1377499203.687150: wlan0: STA 00:24:d7:54:c3:68 IEEE 802.11: associated (aid 1)
1377499203.687218: wlan0: STA 00:24:d7:54:c3:68 MLME: MLME-ASSOCIATE.indication(00:24:d7:54:c3:68)
1377499203.687233: wlan0: STA 00:24:d7:54:c3:68 MLME: MLME-DELETEKEYS.request(00:24:d7:54:c3:68)
1377499203.687485: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=0xbced60 key_idx=0 set_tx=1 seq_len=0 key_len=0
1377499203.687508:    addr=00:24:d7:54:c3:68
1377499203.687870: wlan0: STA 00:24:d7:54:c3:68 WPA: event 1 notification
1377499203.687898: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=0xbced60 key_idx=0 set_tx=1 seq_len=0 key_len=0
1377499203.687909:    addr=00:24:d7:54:c3:68
1377499203.687955: IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS
1377499203.687970: wlan0: STA 00:24:d7:54:c3:68 WPA: start authentication
1377499203.687973: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state INITIALIZE
1377499203.688001: wpa_driver_nl80211_set_key: ifindex=4 alg=0 addr=0xbced60 key_idx=0 set_tx=1 seq_len=0 key_len=0
1377499203.688010:    addr=00:24:d7:54:c3:68
1377499203.688109: wlan0: STA 00:24:d7:54:c3:68 IEEE 802.1X: unauthorizing port
1377499203.688113: WPA: 00:24:d7:54:c3:68 WPA_PTK_GROUP entering state IDLE
1377499203.688119: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state AUTHENTICATION
1377499203.688130: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state AUTHENTICATION2
1377499203.688135: WPA: Re-initialize GMK/Counter on first station
1377499203.688138: Get randomness: len=32 entropy=230
1377499203.689712: GMK - hexdump(len=32): [REMOVED]
1377499203.689723: Get randomness: len=32 entropy=198
1377499203.691101: Key Counter - hexdump(len=32): [REMOVED]
1377499203.691107: Get randomness: len=16 entropy=166
1377499203.692450: GTK - hexdump(len=16): [REMOVED]
1377499203.692492: wpa_driver_nl80211_set_key: ifindex=4 alg=3 addr=0x491d42 key_idx=1 set_tx=1 seq_len=0 key_len=16
1377499203.692511:    broadcast key
1377499203.692706: WPA: Assign ANonce - hexdump(len=32): d7 22 60 cd 9c 04 e3 41 44 a0 58 ba 4e 50 47 3f 4c 3e 7a e8 9c a3 99 12 4c af 49 6c 46 6a 47 2d
1377499203.692733: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state INITPSK
1377499203.692739: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state PTKSTART
1377499203.692753: wlan0: STA 00:24:d7:54:c3:68 WPA: sending 1/4 msg of 4-Way Handshake
1377499203.692758: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=8 kde_len=0 keyidx=0 encr=0)
1377499203.692813: WPA: Use EAPOL-Key timeout of 100 ms (retry counter 1)
1377499203.692847: nl80211: Event message available
1377499203.692866: nl80211: New station 00:24:d7:54:c3:68
1377499203.696980: IEEE 802.1X: 121 bytes from 00:24:d7:54:c3:68
1377499203.697071:    IEEE 802.1X: version=1 type=3 length=117
1377499203.697077: WPA: Received EAPOL-Key from 00:24:d7:54:c3:68 key_info=0x10a type=2 key_data_length=22
1377499203.697083: WPA: Received Key Nonce - hexdump(len=32): 3c 89 4b b2 7c fe f0 b3 9c 42 6a a1 c0 0c ac 28 50 55 76 72 5e a1 6e f9 82 89 7b ed 66 50 53 f1
1377499203.697105: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01
1377499203.697114: Add randomness: count=264 entropy=150
1377499203.697148: wlan0: STA 00:24:d7:54:c3:68 WPA: received EAPOL-Key frame (2/4 Pairwise)
1377499203.697155: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state PTKCALCNEGOTIATING
1377499203.697196: WPA: PTK derivation - A1=a0:f3:c1:0e:7e:9c A2=00:24:d7:54:c3:68
1377499203.697203: WPA: Nonce1 - hexdump(len=32): d7 22 60 cd 9c 04 e3 41 44 a0 58 ba 4e 50 47 3f 4c 3e 7a e8 9c a3 99 12 4c af 49 6c 46 6a 47 2d
1377499203.697223: WPA: Nonce2 - hexdump(len=32): 3c 89 4b b2 7c fe f0 b3 9c 42 6a a1 c0 0c ac 28 50 55 76 72 5e a1 6e f9 82 89 7b ed 66 50 53 f1
1377499203.697243: WPA: PMK - hexdump(len=32): [REMOVED]
1377499203.697247: WPA: PTK - hexdump(len=48): [REMOVED]
1377499203.697260: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state PTKCALCNEGOTIATING2
1377499203.697266: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state PTKINITNEGOTIATING
1377499203.697449: wlan0: STA 00:24:d7:54:c3:68 WPA: sending 3/4 msg of 4-Way Handshake
1377499203.697457: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=8 kde_len=50 keyidx=1 encr=1)
1377499203.697465: Plaintext EAPOL-Key Key Data - hexdump(len=64): [REMOVED]
1377499203.697596: WPA: Use EAPOL-Key timeout of 100 ms (retry counter 1)
1377499203.699692: IEEE 802.1X: 99 bytes from 00:24:d7:54:c3:68
1377499203.699701:    IEEE 802.1X: version=1 type=3 length=95
1377499203.699705: WPA: Received EAPOL-Key from 00:24:d7:54:c3:68 key_info=0x30a type=2 key_data_length=0
1377499203.699711: WPA: Received Key Nonce - hexdump(len=32): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1377499203.699733: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 02
1377499203.699753: wlan0: STA 00:24:d7:54:c3:68 WPA: received EAPOL-Key frame (4/4 Pairwise)
1377499203.699776: WPA: 00:24:d7:54:c3:68 WPA_PTK entering state PTKINITDONE
1377499203.699838: wpa_driver_nl80211_set_key: ifindex=4 alg=3 addr=0xbced60 key_idx=0 set_tx=1 seq_len=0 key_len=16
1377499203.699855:    addr=00:24:d7:54:c3:68
1377499203.699937: wlan0: AP-STA-CONNECTED 00:24:d7:54:c3:68
1377499203.700009: wlan0: STA 00:24:d7:54:c3:68 IEEE 802.1X: authorizing port
1377499203.700030: wlan0: STA 00:24:d7:54:c3:68 RADIUS: starting accounting session 521AF837-00000000
1377499203.700098: wlan0: STA 00:24:d7:54:c3:68 WPA: pairwise key handshake completed (RSN)
1377499203.738329: Add randomness: count=265 entropy=151
1377499203.774562: Add randomness: count=266 entropy=152
1377499203.840703: Add randomness: count=267 entropy=153
1377499203.876971: Add randomness: count=268 entropy=154
1377499203.943117: Add randomness: count=269 entropy=155
1377499203.979393: Add randomness: count=270 entropy=156
1377499204.008480: mgmt::action cb
1377499204.045513: Add randomness: count=271 entropy=157
1377499204.081780: Add randomness: count=272 entropy=158
1377499204.147897: Add randomness: count=273 entropy=159
1377499204.184170: Add randomness: count=274 entropy=160
1377499204.250303: Add randomness: count=275 entropy=161
1377499204.286561: Add randomness: count=276 entropy=162
1377499204.352696: Add randomness: count=277 entropy=163
1377499204.388973: Add randomness: count=278 entropy=164
1377499204.455094: Add randomness: count=279 entropy=165
1377499204.491387: Add randomness: count=280 entropy=166
1377499204.557488: Add randomness: count=281 entropy=167
1377499204.593768: Add randomness: count=282 entropy=168
1377499204.659914: Add randomness: count=283 entropy=169
1377499204.696177: Add randomness: count=284 entropy=170
1377499204.762303: Add randomness: count=285 entropy=171
1377499204.798567: Add randomness: count=286 entropy=172
1377499204.864714: Add randomness: count=287 entropy=173
1377499204.900964: Add randomness: count=288 entropy=174
1377499204.967098: Add randomness: count=289 entropy=175
1377499205.003410: Add randomness: count=290 entropy=176
1377499205.069531: Add randomness: count=291 entropy=177
1377499205.105823: Add randomness: count=292 entropy=178
1377499205.172257: Add randomness: count=293 entropy=179
1377499205.208192: Add randomness: count=294 entropy=180
1377499205.274329: Add randomness: count=295 entropy=181
1377499205.310586: Add randomness: count=296 entropy=182
1377499205.376716: Add randomness: count=297 entropy=183
1377499205.412998: Add randomness: count=298 entropy=184
1377499205.479124: Add randomness: count=299 entropy=185
1377499205.515376: Add randomness: count=300 entropy=186
1377499205.581521: Add randomness: count=301 entropy=187
1377499205.617814: Add randomness: count=302 entropy=188
1377499205.683940: Add randomness: count=303 entropy=189
1377499205.720212: Add randomness: count=304 entropy=190
1377499205.786308: Add randomness: count=305 entropy=191
1377499205.822595: Add randomness: count=306 entropy=192
1377499205.888723: Add randomness: count=307 entropy=193
1377499205.925029: Add randomness: count=308 entropy=194
1377499205.991115: Add randomness: count=309 entropy=195
1377499206.027431: Add randomness: count=310 entropy=196
1377499206.093518: Add randomness: count=311 entropy=197
1377499206.129799: Add randomness: count=312 entropy=198
1377499206.195842: Add randomness: count=313 entropy=199
1377499206.232211: Add randomness: count=314 entropy=200
1377499206.298210: Add randomness: count=315 entropy=201
1377499206.334655: Add randomness: count=316 entropy=202
1377499206.400541: Add randomness: count=317 entropy=203
1377499206.437051: Add randomness: count=318 entropy=204
1377499206.502877: Add randomness: count=319 entropy=205
1377499206.539393: Add randomness: count=320 entropy=206
1377499206.605209: Add randomness: count=321 entropy=207
1377499206.641798: Add randomness: count=322 entropy=208
1377499206.707540: Add randomness: count=323 entropy=209
1377499206.744218: Add randomness: count=324 entropy=210
1377499206.745470: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1377499206.745483: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1377499206.745550: nl80211: if_removed already cleared - ignore event
1377499206.745581: nl80211: Add ifindex 5 for bridge br0
1377499206.745587: nl80211: Add own interface ifindex 5
1377499206.745600: VLAN: vlan_newlink(wlan0)
1377499206.809900: Add randomness: count=325 entropy=211
1377499206.846606: Add randomness: count=326 entropy=212
1377499206.912274: Add randomness: count=327 entropy=213
1377499206.949025: Add randomness: count=328 entropy=214
1377499207.015128: Add randomness: count=329 entropy=215
1377499207.051420: Add randomness: count=330 entropy=216
1377499207.117540: Add randomness: count=331 entropy=217
1377499207.153830: Add randomness: count=332 entropy=218
1377499207.219931: Add randomness: count=333 entropy=219
1377499207.256242: Add randomness: count=334 entropy=220
1377499207.322337: Add randomness: count=335 entropy=221
1377499207.358623: Add randomness: count=336 entropy=222
1377499207.424733: Add randomness: count=337 entropy=223
1377499207.461014: Add randomness: count=338 entropy=224
1377499207.527140: Add randomness: count=339 entropy=225
1377499207.563414: Add randomness: count=340 entropy=226
1377499207.629590: Add randomness: count=341 entropy=227
1377499207.665838: Add randomness: count=342 entropy=228
1377499207.731945: Add randomness: count=343 entropy=229
1377499207.768244: Add randomness: count=344 entropy=230
1377499207.834380: Add randomness: count=345 entropy=231
1377499207.870626: Add randomness: count=346 entropy=232
1377499207.936743: Add randomness: count=347 entropy=233
1377499207.973026: Add randomness: count=348 entropy=234
1377499208.039174: Add randomness: count=349 entropy=235
1377499208.075431: Add randomness: count=350 entropy=236
1377499208.141550: Add randomness: count=351 entropy=237
1377499208.177842: Add randomness: count=352 entropy=238
1377499208.243947: Add randomness: count=353 entropy=239
1377499208.280247: Add randomness: count=354 entropy=240
1377499208.346367: Add randomness: count=355 entropy=241
1377499208.382647: Add randomness: count=356 entropy=242
1377499208.448765: Add randomness: count=357 entropy=243
1377499208.485045: Add randomness: count=358 entropy=244
1377499208.551174: Add randomness: count=359 entropy=245
1377499208.587451: Add randomness: count=360 entropy=246
1377499208.653576: Add randomness: count=361 entropy=247
1377499208.689851: Add randomness: count=362 entropy=248
1377499208.755972: Add randomness: count=363 entropy=249
1377499208.792306: Add randomness: count=364 entropy=250
1377499208.858387: Add randomness: count=365 entropy=251
1377499208.894666: Add randomness: count=366 entropy=252
1377499208.960799: Add randomness: count=367 entropy=253
1377499208.997069: Add randomness: count=368 entropy=254
1377499209.063199: Add randomness: count=369 entropy=255
1377499209.099509: Add randomness: count=370 entropy=256
1377499209.165582: Add randomness: count=371 entropy=257
1377499209.201881: Add randomness: count=372 entropy=258
1377499209.267963: Add randomness: count=373 entropy=259
1377499209.304295: Add randomness: count=374 entropy=260
1377499209.370396: Add randomness: count=375 entropy=261
1377499209.406679: Add randomness: count=376 entropy=262
1377499209.472802: Add randomness: count=377 entropy=263
1377499209.509079: Add randomness: count=378 entropy=264
1377499209.575209: Add randomness: count=379 entropy=265
1377499209.611468: Add randomness: count=380 entropy=266
1377499209.677616: Add randomness: count=381 entropy=267
1377499209.713913: Add randomness: count=382 entropy=268
1377499209.779984: Add randomness: count=383 entropy=269
1377499209.816213: Add randomness: count=384 entropy=270
1377499209.882425: Add randomness: count=385 entropy=271
1377499209.918565: Add randomness: count=386 entropy=272
1377499209.984808: Add randomness: count=387 entropy=273
1377499210.020931: Add randomness: count=388 entropy=274
1377499210.123271: Add randomness: count=389 entropy=275
1377499210.189602: Add randomness: count=390 entropy=276
1377499210.225584: Add randomness: count=391 entropy=277
1377499210.292011: Add randomness: count=392 entropy=278
1377499210.327923: Add randomness: count=393 entropy=279
1377499210.394392: Add randomness: count=394 entropy=280
1377499210.430267: Add randomness: count=395 entropy=281
1377499210.496818: Add randomness: count=396 entropy=282
1377499210.532628: Add randomness: count=397 entropy=283
1377499210.599219: Add randomness: count=398 entropy=284
1377499210.635515: Add randomness: count=399 entropy=285
1377499210.701634: Add randomness: count=400 entropy=286
1377499210.737925: Add randomness: count=401 entropy=287
1377499210.804036: Add randomness: count=402 entropy=288
1377499210.840333: Add randomness: count=403 entropy=289
1377499210.906431: Add randomness: count=404 entropy=290
1377499210.942443: Add randomness: count=405 entropy=291
1377499211.008820: Add randomness: count=406 entropy=292
1377499211.045100: Add randomness: count=407 entropy=293
1377499211.111216: Add randomness: count=408 entropy=294
1377499211.213614: Add randomness: count=409 entropy=295
1377499211.249923: Add randomness: count=410 entropy=296
1377499211.316044: Add randomness: count=411 entropy=297
1377499211.352325: Add randomness: count=412 entropy=298
1377499211.418421: Add randomness: count=413 entropy=299
1377499211.454712: Add randomness: count=414 entropy=300
1377499211.520841: Add randomness: count=415 entropy=301
1377499211.557128: Add randomness: count=416 entropy=302
1377499211.623230: Add randomness: count=417 entropy=303
1377499211.659529: Add randomness: count=418 entropy=304
1377499211.725644: Add randomness: count=419 entropy=305
1377499211.761931: Add randomness: count=420 entropy=306
1377499211.828008: Add randomness: count=421 entropy=307
1377499211.864346: Add randomness: count=422 entropy=308
1377499211.930433: Add randomness: count=423 entropy=309
1377499211.966743: Add randomness: count=424 entropy=310
1377499212.032851: Add randomness: count=425 entropy=311
1377499212.069132: Add randomness: count=426 entropy=312
1377499212.135226: Add randomness: count=427 entropy=313
1377499212.171548: Add randomness: count=428 entropy=314
1377499212.237639: Add randomness: count=429 entropy=315
1377499212.274458: Add randomness: count=430 entropy=316
1377499212.340034: Add randomness: count=431 entropy=317
1377499212.376371: Add randomness: count=432 entropy=318
1377499212.442422: Add randomness: count=433 entropy=319
1377499212.478721: Add randomness: count=434 entropy=320
1377499212.545102: Add randomness: count=435 entropy=321
1377499212.581127: Add randomness: count=436 entropy=322
1377499212.647291: Add randomness: count=437 entropy=323
1377499212.683566: Add randomness: count=438 entropy=324
1377499212.749636: Add randomness: count=439 entropy=325
1377499212.785957: Add randomness: count=440 entropy=326
1377499212.852036: Add randomness: count=441 entropy=327
1377499212.888364: Add randomness: count=442 entropy=328
1377499212.954449: Add randomness: count=443 entropy=329
1377499212.990730: Add randomness: count=444 entropy=330
1377499213.056834: Add randomness: count=445 entropy=331
1377499213.093163: Add randomness: count=446 entropy=332
1377499213.159269: Add randomness: count=447 entropy=333
1377499213.195554: Add randomness: count=448 entropy=334
1377499213.261687: Add randomness: count=449 entropy=335
1377499213.297973: Add randomness: count=450 entropy=336
1377499213.364059: Add randomness: count=451 entropy=337
1377499213.400405: Add randomness: count=452 entropy=338
1377499213.466453: Add randomness: count=453 entropy=339
1377499213.502779: Add randomness: count=454 entropy=340
1377499213.568861: Add randomness: count=455 entropy=341
1377499213.605182: Add randomness: count=456 entropy=342
1377499213.671276: Add randomness: count=457 entropy=343
1377499213.707570: Add randomness: count=458 entropy=344
1377499213.773659: Add randomness: count=459 entropy=345
1377499213.809969: Add randomness: count=460 entropy=346
1377499213.876086: Add randomness: count=461 entropy=347
1377499213.912377: Add randomness: count=462 entropy=348
1377499213.978474: Add randomness: count=463 entropy=349
1377499214.014802: Add randomness: count=464 entropy=350
1377499214.080876: Add randomness: count=465 entropy=351
1377499214.117176: Add randomness: count=466 entropy=352
1377499214.183270: Add randomness: count=467 entropy=353
1377499214.219578: Add randomness: count=468 entropy=354
1377499214.285651: Add randomness: count=469 entropy=355
1377499214.321993: Add randomness: count=470 entropy=356
1377499214.388070: Add randomness: count=471 entropy=357
1377499214.424373: Add randomness: count=472 entropy=358
1377499214.490498: Add randomness: count=473 entropy=359
1377499214.526769: Add randomness: count=474 entropy=360
1377499214.592877: Add randomness: count=475 entropy=361
1377499214.629225: Add randomness: count=476 entropy=362
1377499214.695282: Add randomness: count=477 entropy=363
1377499214.731595: Add randomness: count=478 entropy=364
1377499214.797678: Add randomness: count=479 entropy=365
1377499214.833993: Add randomness: count=480 entropy=366
1377499214.900087: Add randomness: count=481 entropy=367
1377499214.936396: Add randomness: count=482 entropy=368
1377499215.002506: Add randomness: count=483 entropy=369
1377499215.038858: Add randomness: count=484 entropy=370
1377499215.104907: Add randomness: count=485 entropy=371
1377499215.141212: Add randomness: count=486 entropy=372
1377499215.207289: Add randomness: count=487 entropy=373
1377499215.243624: Add randomness: count=488 entropy=374
1377499215.309696: Add randomness: count=489 entropy=375
1377499215.346029: Add randomness: count=490 entropy=376
1377499215.412088: Add randomness: count=491 entropy=377
1377499215.448394: Add randomness: count=492 entropy=378
1377499215.514504: Add randomness: count=493 entropy=379
1377499215.550820: Add randomness: count=494 entropy=380
1377499215.616912: Add randomness: count=495 entropy=381
1377499215.653213: Add randomness: count=496 entropy=382
1377499215.719324: Add randomness: count=497 entropy=383
1377499215.755615: Add randomness: count=498 entropy=384
1377499215.821717: Add randomness: count=499 entropy=385
1377499215.857998: Add randomness: count=500 entropy=386
1377499215.924089: Add randomness: count=501 entropy=387
1377499215.960423: Add randomness: count=502 entropy=388
1377499216.026498: Add randomness: count=503 entropy=389
1377499216.062793: Add randomness: count=504 entropy=390
1377499216.128916: Add randomness: count=505 entropy=391
1377499216.165242: Add randomness: count=506 entropy=392
1377499216.231289: Add randomness: count=507 entropy=393
1377499216.267605: Add randomness: count=508 entropy=394
1377499216.333737: Add randomness: count=509 entropy=395
1377499216.370029: Add randomness: count=510 entropy=396
1377499216.436119: Add randomness: count=511 entropy=397
1377499216.472422: Add randomness: count=512 entropy=398
1377499216.538502: Add randomness: count=513 entropy=399
1377499216.574822: Add randomness: count=514 entropy=400
1377499216.640922: Add randomness: count=515 entropy=401
1377499216.677210: Add randomness: count=516 entropy=402
1377499216.743339: Add randomness: count=517 entropy=403
1377499216.779613: Add randomness: count=518 entropy=404
1377499216.845701: Add randomness: count=519 entropy=405
1377499216.882014: Add randomness: count=520 entropy=406
1377499216.948151: Add randomness: count=521 entropy=407
1377499216.984468: Add randomness: count=522 entropy=408
1377499217.050530: Add randomness: count=523 entropy=409
1377499217.086827: Add randomness: count=524 entropy=410
1377499217.152920: Add randomness: count=525 entropy=411
1377499217.189254: Add randomness: count=526 entropy=412
1377499217.255312: Add randomness: count=527 entropy=413
1377499217.291664: Add randomness: count=528 entropy=414
1377499217.357731: Add randomness: count=529 entropy=415
1377499217.394078: Add randomness: count=530 entropy=416
1377499217.460139: Add randomness: count=531 entropy=417
1377499217.496458: Add randomness: count=532 entropy=418
1377499217.562532: Add randomness: count=533 entropy=419
1377499217.598891: Add randomness: count=534 entropy=420
1377499217.664975: Add randomness: count=535 entropy=421
1377499217.701287: Add randomness: count=536 entropy=422
1377499217.767352: Add randomness: count=537 entropy=423
1377499217.803661: Add randomness: count=538 entropy=424
1377499217.869721: Add randomness: count=539 entropy=425
1377499217.906055: Add randomness: count=540 entropy=426
1377499217.972155: Add randomness: count=541 entropy=427
1377499218.008493: Add randomness: count=542 entropy=428
1377499218.074543: Add randomness: count=543 entropy=429
1377499218.110881: Add randomness: count=544 entropy=430
1377499218.176929: Add randomness: count=545 entropy=431
1377499218.213259: Add randomness: count=546 entropy=432
1377499218.279353: Add randomness: count=547 entropy=433
1377499218.315653: Add randomness: count=548 entropy=434
1377499218.381769: Add randomness: count=549 entropy=435
1377499218.418092: Add randomness: count=550 entropy=436
1377499218.484153: Add randomness: count=551 entropy=437
1377499218.520463: Add randomness: count=552 entropy=438
1377499218.586535: Add randomness: count=553 entropy=439
1377499218.622895: Add randomness: count=554 entropy=440
1377499218.688962: Add randomness: count=555 entropy=441
1377499218.725301: Add randomness: count=556 entropy=442
1377499218.791390: Add randomness: count=557 entropy=443
1377499218.827714: Add randomness: count=558 entropy=444
1377499218.893762: Add randomness: count=559 entropy=445
1377499218.930100: Add randomness: count=560 entropy=446
1377499218.996142: Add randomness: count=561 entropy=447
1377499219.032473: Add randomness: count=562 entropy=448
1377499219.098559: Add randomness: count=563 entropy=449
1377499219.134896: Add randomness: count=564 entropy=450
1377499219.200982: Add randomness: count=565 entropy=451
1377499219.237271: Add randomness: count=566 entropy=452
1377499219.303382: Add randomness: count=567 entropy=453
1377499219.339700: Add randomness: count=568 entropy=454
1377499219.405784: Add randomness: count=569 entropy=455
1377499219.442089: Add randomness: count=570 entropy=456
1377499219.508174: Add randomness: count=571 entropy=457
1377499219.544482: Add randomness: count=572 entropy=458
1377499219.610574: Add randomness: count=573 entropy=459
1377499219.646902: Add randomness: count=574 entropy=460
1377499219.712975: Add randomness: count=575 entropy=461
1377499219.749297: Add randomness: count=576 entropy=462
1377499219.815367: Add randomness: count=577 entropy=463
1377499219.851682: Add randomness: count=578 entropy=464
1377499219.917767: Add randomness: count=579 entropy=465
1377499219.954097: Add randomness: count=580 entropy=466
1377499220.020167: Add randomness: count=581 entropy=467
1377499220.056486: Add randomness: count=582 entropy=468
1377499220.122563: Add randomness: count=583 entropy=469
1377499220.158894: Add randomness: count=584 entropy=470
1377499220.224969: Add randomness: count=585 entropy=471
1377499220.261310: Add randomness: count=586 entropy=472
1377499220.327372: Add randomness: count=587 entropy=473
1377499220.363707: Add randomness: count=588 entropy=474
1377499220.429773: Add randomness: count=589 entropy=475
1377499220.466112: Add randomness: count=590 entropy=476
1377499220.532177: Add randomness: count=591 entropy=477
1377499220.568505: Add randomness: count=592 entropy=478
1377499220.634588: Add randomness: count=593 entropy=479
1377499220.670926: Add randomness: count=594 entropy=480
1377499220.736975: Add randomness: count=595 entropy=481
1377499220.773313: Add randomness: count=596 entropy=482
1377499220.839372: Add randomness: count=597 entropy=483
1377499220.875718: Add randomness: count=598 entropy=484
1377499220.941787: Add randomness: count=599 entropy=485
1377499220.978100: Add randomness: count=600 entropy=486
1377499221.044189: Add randomness: count=601 entropy=487
1377499221.080609: Add randomness: count=602 entropy=488
1377499221.146620: Add randomness: count=603 entropy=489
1377499221.182946: Add randomness: count=604 entropy=490
1377499221.249445: Add randomness: count=605 entropy=491
1377499221.273690: mgmt::action cb
1377499221.351267: Add randomness: count=606 entropy=492
1377499221.387808: Add randomness: count=607 entropy=493
1377499221.453511: Add randomness: count=608 entropy=494
1377499221.491384: Add randomness: count=609 entropy=495
1377499221.557210: Add randomness: count=610 entropy=496
1377499221.699607: Add randomness: count=611 entropy=497
1377499221.797384: Add randomness: count=612 entropy=498
1377499221.867694: Add randomness: count=613 entropy=499
1377499221.900422: Add randomness: count=614 entropy=500
1377499221.966363: Add randomness: count=615 entropy=501
1377499222.001649: Add randomness: count=616 entropy=502
1377499222.070194: Add randomness: count=617 entropy=503
1377499222.107705: Add randomness: count=618 entropy=504
1377499222.273703: Add randomness: count=619 entropy=505
1377499222.314941: Add randomness: count=620 entropy=506
1377499222.412366: Add randomness: count=621 entropy=507
1377499222.580248: Add randomness: count=622 entropy=508
1377499222.616578: Add randomness: count=623 entropy=509
1377499222.682645: Add randomness: count=624 entropy=510
1377499222.718971: Add randomness: count=625 entropy=511
1377499222.785044: Add randomness: count=626 entropy=512
1377499222.821375: Add randomness: count=627 entropy=513
1377499222.990687: Add randomness: count=628 entropy=514
1377499223.195212: Add randomness: count=629 entropy=515
1377499223.232166: Add randomness: count=630 entropy=516
1377499223.296604: Add randomness: count=631 entropy=517
1377499223.334510: Add randomness: count=632 entropy=518
1377499223.501866: Add randomness: count=633 entropy=519
1377499223.538193: Add randomness: count=634 entropy=520
1377499223.604251: Add randomness: count=635 entropy=521
1377499223.640605: Add randomness: count=636 entropy=522
1377499223.706657: Add randomness: count=637 entropy=523
1377499223.742963: Add randomness: count=638 entropy=524
1377499223.809056: Add randomness: count=639 entropy=525
1377499223.845374: Add randomness: count=640 entropy=526
1377499223.911433: Add randomness: count=641 entropy=527
1377499223.950341: Add randomness: count=642 entropy=528
1377499224.015833: Add randomness: count=643 entropy=529
1377499224.120805: Add randomness: count=644 entropy=530
1377499224.157437: Add randomness: count=645 entropy=531
1377499224.256538: Add randomness: count=646 entropy=532
1377499224.324491: Add randomness: count=647 entropy=533
1377499224.358423: Add randomness: count=648 entropy=534
1377499224.459444: Add randomness: count=649 entropy=535
1377499224.562224: Add randomness: count=650 entropy=536
1377499224.628273: Add randomness: count=651 entropy=537
1377499224.664633: Add randomness: count=652 entropy=538
1377499224.730644: Add randomness: count=653 entropy=539
1377499224.767023: Add randomness: count=654 entropy=540
1377499224.833052: Add randomness: count=655 entropy=541
1377499224.869107: Add randomness: count=656 entropy=542
1377499224.973770: Add randomness: count=657 entropy=543
1377499225.037907: Add randomness: count=658 entropy=544
1377499225.074230: Add randomness: count=659 entropy=545
1377499225.141675: Add randomness: count=660 entropy=546
1377499225.176464: Add randomness: count=661 entropy=547
1377499225.244331: Add randomness: count=662 entropy=548
1377499225.278663: Add randomness: count=663 entropy=549
1377499225.345135: Add randomness: count=664 entropy=550
1377499225.381455: Add randomness: count=665 entropy=551
1377499225.483921: Add randomness: count=666 entropy=552
1377499225.552196: Add randomness: count=667 entropy=553
1377499225.652250: Add randomness: count=668 entropy=554
1377499225.754695: Add randomness: count=669 entropy=555
1377499225.791050: Add randomness: count=670 entropy=556
1377499225.857081: Add randomness: count=671 entropy=557
1377499225.893427: Add randomness: count=672 entropy=558
1377499225.959480: Add randomness: count=673 entropy=559
1377499225.995827: Add randomness: count=674 entropy=560
1377499226.062134: Add randomness: count=675 entropy=561
1377499226.200403: Add randomness: count=676 entropy=562
1377499226.370244: Add randomness: count=677 entropy=563
1377499226.406297: Add randomness: count=678 entropy=564
1377499226.472264: Add randomness: count=679 entropy=565
1377499226.573918: Add randomness: count=680 entropy=566
1377499226.611722: Add randomness: count=681 entropy=567
1377499226.677112: Add randomness: count=682 entropy=568
1377499226.713559: Add randomness: count=683 entropy=569
1377499226.819305: Add randomness: count=684 entropy=570
1377499226.883185: Add randomness: count=685 entropy=571
1377499226.917479: Add randomness: count=686 entropy=572
1377499226.983523: Add randomness: count=687 entropy=573
1377499227.019842: Add randomness: count=688 entropy=574
1377499227.085884: Add randomness: count=689 entropy=575
1377499227.122270: Add randomness: count=690 entropy=576
1377499227.188294: Add randomness: count=691 entropy=577
1377499227.224646: Add randomness: count=692 entropy=578
1377499227.290691: Add randomness: count=693 entropy=579
1377499227.327038: Add randomness: count=694 entropy=580
1377499227.393084: Add randomness: count=695 entropy=581
1377499227.429462: Add randomness: count=696 entropy=582
1377499227.495500: Add randomness: count=697 entropy=583
1377499227.531896: Add randomness: count=698 entropy=584
1377499227.634542: Add randomness: count=699 entropy=585

[ath9k-devel] Hostapd+WMM causes packet loss

[Sujith Manoharan]

Rasto Sramek wrote:
> I'm attaching the log from start to the problem. There is nothing
> actually at the time of the problems apart from
> 1377499227.634542: Add randomness: count=699 entropy=585 messages.
> 
> Earlier on it says it switched channels because secondary contains 
> another BSS.
> 
> 1377499191.630232: nl80211: Set freq 5220 (ht_enabled=1 sec_channel_offset=1)
> 1377499191.630352: Failed to update rate sets in kernel module

This is the only message that is a bit suspicious. Can you try with
latest hostapd.git ? Also, make sure that background scan is not enabled
on the clients.

Sujith

[ath9k-devel] Hostapd+WMM causes packet loss

[michael-dev]

Hi,

I'm seeing a kind of similar issue, though I'm having trouble tracking 
it down and stopped when I thought it would be caused by the STAs. So 
what I'm seeing is an STA that "receives" (that is the packet is on air 
and there is a 802.11-ACK for it and wireshark can decrypt it) and 
transmits packets in low-load situations but than waits for ages to 
reply to them. It symptoms (websites load slowly) has been reported by 
different client devices, but I've only done packet inspection for one 
client so far.

Would you mind to sniff the connection between AP and one STA using the 
second STA in monitor mode with flags control and check whether the lost 
packets actually get 802.11-ACKed by the STA?
That would be running
  iw phy phyX interface add monX type monitor flags control
  ifconfig monX up
  wireshark -k -i monX
It likely helps the make wireshark decrypt the traffic, which it can do 
for WPA-PSK encrypted unicast traffic [1]. You need to catch the EAPOL 
frames with wireshark - I needed to stop wpa_supplicant on the sniffing 
client for that.
It would then be interesting to know
  - whether the packets get send out in a timely manner (i.e. with ping 
you'll see the 1s distance or sniff on the ap interface for that),
  - if wireshark can decrypt it (without decryption the 
source/destination mac is a good source to filter),
  - whether the STA replies to the packet later on,
  - if the STA sends other packets after it received the 
un-replied-to/lately replied-to packets (i.e. if it thinks that it 
cannot send).

Regards,
  M. Braun

[1] http://wiki.wireshark.org/HowToDecrypt802.11

Am 25.08.2013 17:57, schrieb Rasto Sramek:
> Hello,
> 
> I was redirected here from hostapd mailinglist. I am trying to
> run a 802.11n 5GHz AP from a tl-wdn4800 pci-e 3x3 adapter which seems 
> to
> contain a AR9380 chipset.
> 
> With disabled wmm (wmm_enabled=0), I get reliable connection at 54 
> Mb/s,
> 1-4ms ping with no apparent packet loss, and around 17 Mb/sec actual
> transfer rate:
> [  3]  0.0- 1.0 sec  2.12 MBytes  17.8 Mbits/sec
> [  3]  1.0- 2.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  2.0- 3.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  3.0- 4.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  4.0- 5.0 sec  2.12 MBytes  17.8 Mbits/sec
> [  3]  5.0- 6.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  6.0- 7.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  7.0- 8.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  8.0- 9.0 sec  2.00 MBytes  16.8 Mbits/sec
> [  3]  9.0-10.0 sec  2.12 MBytes  17.8 Mbits/sec
> 
> 
> When I enable wmm, I get faster connection (up to 270 Mbit/s)
> with significant packet loss which seems to come every few
> seconds for a second or more:
> 
> [  3]  0.0- 1.0 sec  9.12 MBytes  76.5 Mbits/sec
> [  3]  1.0- 2.0 sec  4.75 MBytes  39.8 Mbits/sec
> [  3]  2.0- 3.0 sec  3.38 MBytes  28.3 Mbits/sec
> [  3]  3.0- 4.0 sec  3.25 MBytes  27.3 Mbits/sec
> [  3]  4.0- 5.0 sec  6.25 MBytes  52.4 Mbits/sec
> [  3]  5.0- 6.0 sec  3.50 MBytes  29.4 Mbits/sec
> [  3]  6.0- 7.0 sec  3.12 MBytes  26.2 Mbits/sec
> [  3]  7.0- 8.0 sec  6.38 MBytes  53.5 Mbits/sec
> [  3]  8.0- 9.0 sec  0.00 Bytes  0.00 bits/sec
> [  3]  9.0-10.0 sec  6.62 MBytes  55.6 Mbits/sec
> [  3] 10.0-11.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 11.0-12.0 sec  1.12 MBytes  9.44 Mbits/sec
> [  3] 12.0-13.0 sec  5.25 MBytes  44.0 Mbits/sec
> [  3] 13.0-14.0 sec  6.38 MBytes  53.5 Mbits/sec
> [  3] 14.0-15.0 sec  3.25 MBytes  27.3 Mbits/sec
> [  3] 15.0-16.0 sec  3.75 MBytes  31.5 Mbits/sec
> [  3] 16.0-17.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 17.0-18.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 18.0-19.0 sec  6.88 MBytes  57.7 Mbits/sec
> [  3] 19.0-20.0 sec  0.00 Bytes  0.00 bits/sec
> [  3] 20.0-21.0 sec  0.00 Bytes  0.00 bits/sec
> [  3]  0.0-21.4 sec  73.1 MBytes  28.6 Mbits/sec
> 
> This doesn't seem to be just QoS load prioritizing,
> since ping in a no-load situation has similar issues:
> 
> 64 bytes from 10.0.0.1: icmp_seq=1 ttl=64 time=6.14 ms
> 64 bytes from 10.0.0.1: icmp_seq=2 ttl=64 time=2.85 ms
> 64 bytes from 10.0.0.1: icmp_seq=3 ttl=64 time=5.03 ms
> 64 bytes from 10.0.0.1: icmp_seq=4 ttl=64 time=3.79 ms
> 64 bytes from 10.0.0.1: icmp_seq=5 ttl=64 time=3.66 ms
> 64 bytes from 10.0.0.1: icmp_seq=6 ttl=64 time=2.50 ms
> 64 bytes from 10.0.0.1: icmp_seq=13 ttl=64 time=2.54 ms
> 64 bytes from 10.0.0.1: icmp_seq=17 ttl=64 time=2.96 ms
> 64 bytes from 10.0.0.1: icmp_seq=18 ttl=64 time=7.66 ms
> 64 bytes from 10.0.0.1: icmp_seq=19 ttl=64 time=3.22 ms
> 
> Both clients, running Intel Ultimate-N 6300 3x3 cards,
> are affected. The wireless spectrum seems to be more or less clear
> containing only 2 other weak 5GHz APs, both on Channel 44
> compared to my 48.
> 
> Is this a known issue? I couldn't find any mention of it.
> Can someone point me at what I should try? I tried
> using a different channel but I did not see any change
> in behavior.
> 
> Thanks!
> 
> Rasto
> 
> ====================================================
> Versions/Configuration:
> 
> Client:
> Debian, vanilla 3.9.8 kernel, wpa_supplicant 1.0-3+b2
> Server:
> Debian, debian 3.10-2-amd64 kernel, hostapd 1.0-3+b2
> 
> lspci -v:
> 01:00.0 Network controller: Qualcomm Atheros AR93xx Wireless Network
> Adapter (rev 01)
>         Subsystem: Qualcomm Atheros Device 3112
>         Physical Slot: 0
>         Flags: bus master, fast devsel, latency 0, IRQ 16
>         Memory at fe9e0000 (64-bit, non-prefetchable) [size=128K]
>         Expansion ROM at fe9d0000 [disabled] [size=64K]
>         Capabilities: [40] Power Management version 3
>         Capabilities: [50] MSI: Enable- Count=1/4 Maskable+ 64bit+
>         Capabilities: [70] Express Endpoint, MSI 00
>         Capabilities: [100] Advanced Error Reporting
>         Capabilities: [140] Virtual Channel
>         Capabilities: [300] Device Serial Number 
> 00-00-00-00-00-00-00-00
>         Kernel driver in use: ath9k
> 
> parts of hostapd.conf that seem relevant:
> 
> interface=wlan0
> bridge=br0
> driver=nl80211
> hw_mode=a
> channel=48
> rts_threshold=2347
> fragm_threshold=2346
> 
> wmm_enabled=1
> wmm_ac_bk_cwmin=4
> wmm_ac_bk_cwmax=10
> wmm_ac_bk_aifs=7
> wmm_ac_bk_txop_limit=0
> wmm_ac_bk_acm=0
> wmm_ac_be_aifs=3
> wmm_ac_be_cwmin=4
> wmm_ac_be_cwmax=10
> wmm_ac_be_txop_limit=0
> wmm_ac_be_acm=0
> wmm_ac_vi_aifs=2
> wmm_ac_vi_cwmin=3
> wmm_ac_vi_cwmax=4
> wmm_ac_vi_txop_limit=94
> wmm_ac_vi_acm=0
> wmm_ac_vo_aifs=2
> wmm_ac_vo_cwmin=2
> wmm_ac_vo_cwmax=3
> wmm_ac_vo_txop_limit=47
> wmm_ac_vo_acm=0
> ieee80211n=1
> ht_capab=[HT40-][SHORT-GI-40]
> 
> wpa=2
> wpa_key_mgmt=WPA-PSK WPA-EAP
> wpa_pairwise=CCMP TKIP
> rsn_pairwise=CCMP
> 
> _______________________________________________
> ath9k-devel mailing list
> ath9k-devel at lists.ath9k.org
> https://lists.ath9k.org/mailman/listinfo/ath9k-devel

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

On Mon, Aug 26, 2013 at 06:46:05PM +0200, michael-dev wrote:
> Hi,
> 
> I'm seeing a kind of similar issue, though I'm having trouble
> tracking it down and stopped when I thought it would be caused by
> the STAs. So what I'm seeing is an STA that "receives" (that is the
> packet is on air and there is a 802.11-ACK for it and wireshark can
> decrypt it) and transmits packets in low-load situations but than
> waits for ages to reply to them. It symptoms (websites load slowly)
> has been reported by different client devices, but I've only done
> packet inspection for one client so far.
> 
> Would you mind to sniff the connection between AP and one STA using
> the second STA in monitor mode with flags control and check whether
> the lost packets actually get 802.11-ACKed by the STA?
> That would be running
>  iw phy phyX interface add monX type monitor flags control
>  ifconfig monX up
>  wireshark -k -i monX
> It likely helps the make wireshark decrypt the traffic, which it can
> do for WPA-PSK encrypted unicast traffic [1]. You need to catch the
> EAPOL frames with wireshark - I needed to stop wpa_supplicant on the
> sniffing client for that.
> It would then be interesting to know
>  - whether the packets get send out in a timely manner (i.e. with
> ping you'll see the 1s distance or sniff on the ap interface for
> that),
>  - if wireshark can decrypt it (without decryption the
> source/destination mac is a good source to filter),
>  - whether the STA replies to the packet later on,
>  - if the STA sends other packets after it received the
> un-replied-to/lately replied-to packets (i.e. if it thinks that it
> cannot send).
> 
> Regards,
>  M. Braun
> 
> [1] http://wiki.wireshark.org/HowToDecrypt802.11

Thanks! 
I will try that and report back, might take a few days. 
 
Rasto

[ath9k-devel] Hostapd+WMM causes packet loss

[Rasto Sramek]

On Mon, Aug 26, 2013 at 01:35:33PM +0530, Sujith Manoharan wrote:
> Rasto Sramek wrote:
> > I'm attaching the log from start to the problem. There is nothing
> > actually at the time of the problems apart from
> > 1377499227.634542: Add randomness: count=699 entropy=585 messages.
> > 
> > Earlier on it says it switched channels because secondary contains 
> > another BSS.
> > 
> > 1377499191.630232: nl80211: Set freq 5220 (ht_enabled=1
> > sec_channel_offset=1)
> > 1377499191.630352: Failed to update rate sets in kernel module
> 
> This is the only message that is a bit suspicious. Can you try with
> latest hostapd.git ? Also, make sure that background scan is not
> enabled
> on the clients.
> 
> Sujith

I compiled hostapd from git and the problem is subjectively worse,
typical iperf:
[  3]  0.0- 0.5 sec   128 KBytes  2.10 Mbits/sec
[  3]  0.5- 1.0 sec  0.00 Bytes  0.00 bits/sec
[  3]  1.0- 1.5 sec  1.00 MBytes  16.8 Mbits/sec
[  3]  1.5- 2.0 sec  2.62 MBytes  44.0 Mbits/sec
[  3]  2.0- 2.5 sec   384 KBytes  6.29 Mbits/sec
[  3]  2.5- 3.0 sec  0.00 Bytes  0.00 bits/sec
[  3]  3.0- 3.5 sec  0.00 Bytes  0.00 bits/sec
[  3]  3.5- 4.0 sec  0.00 Bytes  0.00 bits/sec

It did not seem immediately obvious how to disable background scans,
I will look into that.

Rasto