Topic: random ping spikes

The content of this topic has been archived on 3 May 2018. There are no obvious gaps in this topic, but there may still be some posts missing at the end.

Page 1 of 1

Post #1

mezo

6 Oct 2015, 15:16

hey,
since i switched to openenwrt i get random ping spikes all the time. i used many months dd-wrt before and never had this problem.
this also seems only happen to wifi connections.

thats how the spikes look like.

wireless config:

config wifi-device 'radio0'
        option type 'mac80211'
        option hwmode '11g'
        option path 'platform/ar934x_wmac'
        option htmode 'HT40'
        option channel '5'
        option txpower '20'
        option country 'DE'
        option noscan '1'

config wifi-iface
        option device 'radio0'
        option network 'lan'
        option mode 'ap'
        option ssid 'OpenWrt'
        option encryption 'psk2'
        option key '123'

config wifi-device 'radio1'
        option type 'mac80211'
        option channel '36'
        option hwmode '11a'
        option path 'pci0000:00/0000:00:00.0'
        option txpower '15'
        option country 'DE'
        option htmode 'HT40'

config wifi-iface
        option device 'radio1'
        option network 'lan'
        option mode 'ap'
        option encryption 'psk2'
        option key '123'
        option ssid 'OpenWrt_5GHz'
        option disabled '1'

setup: tp-link wdr3600 @ chaos calmer 15.05

Post #2

richbhanover

7 Oct 2015, 14:35

Anytime someone mentions "ping spikes", I recommend they check to see if there's bufferbloat. Here's an easy test: Go to www.dslreports.com/speedtest and follow the directions.

If the bufferbloat grade isn't an "A", then you have to suspect that the router is not handling traffic properly. Fortunately, you're using OpenWrt, so it's an easy fix.

Check out the SQM Howto at http://wiki.openwrt.org/doc/howto/sqm for instructions for de-bloating your router. Please let me know how it turns out.

Post #3

mezo

7 Oct 2015, 17:47

im running sqm, with zero bufferbloat. as i said i used ddwrt for many months and never had this problem a single time, also all the years before ddwrt didnt had this. it must be something openwrt related.

this two high ping looks like nothing in the ping, but check the game, this are extreme laggs, that i dont even get without any qos and full download.

(Last edited by mezo on 7 Oct 2015, 17:48)

Post #4

mezo

10 Oct 2015, 10:13

*bump*

Post #5

moeller0

10 Oct 2015, 19:54

mezo wrote:

im running sqm, with zero bufferbloat. as i said i used ddwrt for many months and never had this problem a single time, also all the years before ddwrt didnt had this. it must be something openwrt related.
this two high ping looks like nothing in the ping, but check the game, this are extreme laggs, that i dont even get without any qos and full download.

Hmm, could you revert back to ddwrt and confirm that you have no issues there, please? If you want to make progress on this we need a stable baseline. I do in no way doubt your observations, but we have a number of possible causes for the observed data, and hence should work methodically.
As long as you are still on openwrt ans sqm, it might help to run flent ( https://flent.org ) on your test machine, especially the RRUL test (run for several minutes at a time) is a great tool to saturate your network and any latency increase should be magnified there...
I guess the following information might help (with that I mean the output the following commands generate on your router's cli):

1) tc - d qdisc

2) /etc/init.d/sqm stop

3) tc - d qdisc

4) /etc/init.d/sqm start

5) tc - d qdisc

6) cat /etc/config/sqm

Also the following would be great to see before and after you observed your "random ping spikes"

A) logread

B) dmesg

Best Regards
M.

Post #6

mezo

12 Oct 2015, 12:19

hey,
i changed back to ddwrt for a while and everything is fine there. my brother is connected with lan and he told me he does not have this problem. so i assume its wifi related (both 2,4 and 5ghz).

is flent aviablo for windows?

before sqm stop

root@OpenWrt:~# tc -d qdisc
qdisc fq_codel 0: dev eth0 root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan0 root
qdisc fq_codel 0: dev wlan0 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc htb 1: dev pppoe-wan root refcnt 2 r2q 10 default 12 direct_packets_stat 0 ver 3.17 direct_qlen 3
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev pppoe-wan parent 1:11 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 120: dev pppoe-wan parent 1:12 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 130: dev pppoe-wan parent 1:13 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc ingress ffff: dev pppoe-wan parent ffff:fff1 ----------------
qdisc htb 1: dev ifb4pppoe-wan root refcnt 2 r2q 10 default 10 direct_packets_stat 0 ver 3.17 direct_qlen 32
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev ifb4pppoe-wan parent 1:10 limit 1001p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan1 root
qdisc fq_codel 0: dev wlan1 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn

after stop:

root@OpenWrt:~# tc -d qdisc
qdisc fq_codel 0: dev eth0 root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan0 root
qdisc fq_codel 0: dev wlan0 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev pppoe-wan root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan1 root
qdisc fq_codel 0: dev wlan1 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn

after starting it again:

root@OpenWrt:~# tc -d qdisc
qdisc fq_codel 0: dev eth0 root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan0 root
qdisc fq_codel 0: dev wlan0 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc htb 1: dev pppoe-wan root refcnt 2 r2q 10 default 12 direct_packets_stat 4 ver 3.17 direct_qlen 3
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev pppoe-wan parent 1:11 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 120: dev pppoe-wan parent 1:12 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 130: dev pppoe-wan parent 1:13 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc ingress ffff: dev pppoe-wan parent ffff:fff1 ----------------
qdisc mq 0: dev wlan1 root
qdisc fq_codel 0: dev wlan1 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc htb 1: dev ifb4pppoe-wan root refcnt 2 r2q 10 default 10 direct_packets_stat 0 ver 3.17 direct_qlen 32
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev ifb4pppoe-wan parent 1:10 limit 1001p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn

/etc/config/sqm

config queue 'eth1'
        option qdisc 'fq_codel'
        option linklayer 'atm'
        option overhead '44'
        option enabled '1'
        option download '14000'
        option upload '1500'
        option interface 'pppoe-wan'
        option script 'simple.qos'
        option qdisc_advanced '0'

system log

Mon Oct 12 12:21:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:21:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:22:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:22:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:22:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:22:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:22:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:22:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:22:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:24:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:24:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:24:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:24:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:24:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:24:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:24:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:25:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 12:26:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:26:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:26:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:26:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:26:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:26:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:26:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:31:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:31:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:31:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:31:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:31:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:31:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:31:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:35:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 12:37:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:37:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:37:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:37:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:37:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:37:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:37:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:39:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:39:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:39:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:39:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:39:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:39:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:39:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:41:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:41:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:41:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:41:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:41:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:41:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:41:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:41:29 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:41:29 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:41:29 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:41:30 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:41:30 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:41:40 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:41:41 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:45:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 12:51:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:51:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:51:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:51:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:51:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:51:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:51:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:52:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:52:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:52:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:52:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:52:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:52:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:52:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:54:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:54:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:54:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:54:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:54:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:54:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:54:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:55:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 13:01:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:01:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:01:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:01:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:01:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:01:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:01:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 13:05:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:05:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:05:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:05:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:05:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:05:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:05:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:05:26 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 13:11:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:11:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:11:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:11:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:11:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:11:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:11:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 13:15:26 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 13:16:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:16:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:16:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:16:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:16:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:16:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:16:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:18:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:18:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:18:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:18:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:18:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:18:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:18:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:21:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:21:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:21:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:21:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:21:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:21:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:21:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 13:24:48 2015 authpriv.info dropbear[28745]: Child connection from 192.168.1.199:58724
Mon Oct 12 13:24:53 2015 authpriv.notice dropbear[28745]: Password auth succeeded for 'root' from 192.168.1.199:58724

kernel log

[    0.000000] Linux version 3.18.20 (buildbot@builder1) (gcc version 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r46450) ) #1 Fri Sep 4 21:55:57 CEST 2015
[    0.000000] MyLoader: sysp=d41aa8a5, boardp=3bb52003, parts=bd952718
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
[    0.000000] SoC: Atheros AR9344 rev 2
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 08000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x00000000-0x07ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00000000-0x07ffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0x07ffffff]
[    0.000000] On node 0 totalpages: 32768
[    0.000000] free_area_init_node: node 0, pgdat 8034e2b0, node_mem_map 81000000
[    0.000000]   Normal zone: 256 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 32768 pages, LIFO batch:7
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line:  board=TL-WDR4300  console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 125980K/131072K available (2621K kernel code, 129K rwdata, 344K rodata, 224K init, 194K bss, 5092K reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:560.000MHz, DDR:450.000MHz, AHB:225.000MHz, Ref:40.000MHz
[    0.000000] Calibrating delay loop... 278.93 BogoMIPS (lpj=1394688)
[    0.070000] pid_max: default: 32768 minimum: 301
[    0.070000] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.080000] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.080000] NET: Registered protocol family 16
[    0.090000] MIPS: machine is TP-LINK TL-WDR3600/4300/4310
[    0.090000] registering PCI controller with io_map_base unset
[    0.330000] PCI host bridge to bus 0000:00
[    0.330000] pci_bus 0000:00: root bus resource [mem 0x10000000-0x13ffffff]
[    0.340000] pci_bus 0000:00: root bus resource [io  0x0000]
[    0.340000] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.350000] pci 0000:00:00.0: [168c:0033] type 00 class 0x028000
[    0.350000] pci 0000:00:00.0: invalid calibration data
[    0.350000] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
[    0.350000] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    0.350000] pci 0000:00:00.0: supports D1
[    0.350000] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    0.350000] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    0.350000] pci 0000:00:00.0: BAR 0: assigned [mem 0x10000000-0x1001ffff 64bit]
[    0.360000] pci 0000:00:00.0: BAR 6: assigned [mem 0x10020000-0x1002ffff pref]
[    0.360000] pci 0000:00:00.0: using irq 40 for pin 1
[    0.370000] Switched to clocksource MIPS
[    0.370000] NET: Registered protocol family 2
[    0.380000] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.380000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.390000] TCP: Hash tables configured (established 1024 bind 1024)
[    0.390000] TCP: reno registered
[    0.400000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.400000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.410000] NET: Registered protocol family 1
[    0.410000] PCI: CLS 0 bytes, default 32
[    0.410000] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.440000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.440000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.450000] msgmni has been set to 246
[    0.460000] io scheduler noop registered
[    0.460000] io scheduler deadline registered (default)
[    0.470000] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.470000] console [ttyS0] disabled
[    0.500000] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 2500000) is a 16550A
[    0.510000] console [ttyS0] enabled
[    0.510000] bootconsole [early0] disabled
[    0.530000] m25p80 spi0.0: found s25fl064k, expected m25p80
[    0.530000] m25p80 spi0.0: s25fl064k (8192 Kbytes)
[    0.540000] 5 tp-link partitions found on MTD device spi0.0
[    0.540000] Creating 5 MTD partitions on "spi0.0":
[    0.550000] 0x000000000000-0x000000020000 : "u-boot"
[    0.550000] 0x000000020000-0x00000012c5a4 : "kernel"
[    0.560000] 0x00000012c5a4-0x0000007f0000 : "rootfs"
[    0.570000] mtd: device 2 (rootfs) set to be root filesystem
[    0.570000] 1 squashfs-split partitions found on MTD device rootfs
[    0.580000] 0x000000360000-0x0000007f0000 : "rootfs_data"
[    0.590000] 0x0000007f0000-0x000000800000 : "art"
[    0.590000] 0x000000020000-0x0000007f0000 : "firmware"
[    0.610000] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-mdio.0
[    0.670000] libphy: ag71xx_mdio: probed
[    1.270000] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[    1.280000] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[    1.290000] TCP: cubic registered
[    1.290000] NET: Registered protocol family 17
[    1.290000] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.310000] Bridge firewalling registered
[    1.310000] 8021q: 802.1Q VLAN Support v1.8
[    1.320000] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.330000] Freeing unused kernel memory: 224K (80368000 - 803a0000)
[    2.330000] init: Console is alive
[    2.330000] init: - watchdog -
[    3.540000] usbcore: registered new interface driver usbfs
[    3.540000] usbcore: registered new interface driver hub
[    3.550000] usbcore: registered new device driver usb
[    3.560000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.570000] ehci-platform: EHCI generic platform driver
[    3.570000] ehci-platform ehci-platform: EHCI Host Controller
[    3.580000] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[    3.590000] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[    3.610000] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[    3.610000] hub 1-0:1.0: USB hub found
[    3.620000] hub 1-0:1.0: 1 port detected
[    3.940000] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    4.090000] hub 1-1:1.0: USB hub found
[    4.090000] hub 1-1:1.0: 4 ports detected
[    4.380000] init: - preinit -
[    4.980000] random: procd urandom read with 8 bits of entropy available
[    6.380000] eth0: link up (1000Mbps/Full duplex)
[    8.230000] jffs2: notice: (392) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[    8.250000] mount_root: switching to jffs2 overlay
[    8.290000] eth0: link down
[    8.310000] procd: - early -
[    8.310000] procd: - watchdog -
[    9.030000] procd: - ubus -
[   10.040000] procd: - init -
[   11.130000] NET: Registered protocol family 10
[   11.160000] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   11.190000] u32 classifier
[   11.190000]     input device check on
[   11.200000]     Actions configured
[   11.200000] Mirror/redirect action on
[   11.220000] netem: version 1.3
[   11.240000] Loading modules backported from Linux version master-2015-03-09-0-g141f155
[   11.250000] Backport generated by backports.git backports-20150129-0-gdd4a670
[   11.260000] ip_tables: (C) 2000-2006 Netfilter Core Team
[   11.280000] nf_conntrack version 0.5.0 (1971 buckets, 7884 max)
[   11.370000] xt_time: kernel timezone is -0000
[   11.410000] cfg80211: Calling CRDA to update world regulatory domain
[   11.410000] cfg80211: World regulatory domain updated:
[   11.420000] cfg80211:  DFS Master region: unset
[   11.420000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   11.430000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   11.440000] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   11.450000] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[   11.460000] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   11.460000] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   11.470000] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[   11.480000] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   11.490000] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[   11.560000] PPP generic driver version 2.4.2
[   11.570000] NET: Registered protocol family 24
[   11.650000] ath: EEPROM regdomain: 0x0
[   11.650000] ath: EEPROM indicates default country code should be used
[   11.650000] ath: doing EEPROM country->regdmn map search
[   11.650000] ath: country maps to regdmn code: 0x3a
[   11.650000] ath: Country alpha2 being used: US
[   11.650000] ath: Regpair used: 0x3a
[   11.670000] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   11.670000] ieee80211 phy0: Atheros AR9340 Rev:2 mem=0xb8100000, irq=47
[   11.680000] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[   11.690000] ath: EEPROM regdomain: 0x0
[   11.690000] ath: EEPROM indicates default country code should be used
[   11.690000] ath: doing EEPROM country->regdmn map search
[   11.690000] ath: country maps to regdmn code: 0x3a
[   11.690000] ath: Country alpha2 being used: US
[   11.690000] ath: Regpair used: 0x3a
[   11.700000] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   11.700000] ieee80211 phy1: Atheros AR9300 Rev:4 mem=0xb0000000, irq=40
[   11.720000] cfg80211: Calling CRDA for country: US
[   11.730000] cfg80211: Regulatory domain changed to country: US
[   11.730000] cfg80211:  DFS Master region: FCC
[   11.740000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   11.750000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A)
[   11.760000] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 1700 mBm), (N/A)
[   11.770000] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
[   11.780000] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
[   11.780000] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   20.370000] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   20.390000] device eth0.1 entered promiscuous mode
[   20.390000] device eth0 entered promiscuous mode
[   20.410000] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   20.420000] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
[   21.370000] cfg80211: Calling CRDA for country: DE
[   21.370000] cfg80211: Regulatory domain changed to country: DE
[   21.380000] cfg80211:  DFS Master region: ETSI
[   21.380000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   21.390000] cfg80211:   (2400000 KHz - 2483000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   21.400000] cfg80211:   (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   21.410000] cfg80211:   (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   21.420000] cfg80211:   (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s)
[   21.430000] cfg80211:   (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   21.790000] eth0: link up (1000Mbps/Full duplex)
[   21.790000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   21.830000] br-lan: port 1(eth0.1) entered forwarding state
[   21.830000] br-lan: port 1(eth0.1) entered forwarding state
[   21.840000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready
[   21.840000] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   23.830000] br-lan: port 1(eth0.1) entered forwarding state
[   24.270000] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[   24.310000] device wlan1 entered promiscuous mode
[   24.340000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   24.370000] device wlan0 entered promiscuous mode
[   24.370000] br-lan: port 3(wlan0) entered forwarding state
[   24.380000] br-lan: port 3(wlan0) entered forwarding state
[   25.280000] br-lan: port 3(wlan0) entered disabled state
[   26.030000] br-lan: port 3(wlan0) entered forwarding state
[   26.040000] br-lan: port 3(wlan0) entered forwarding state
[   26.040000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   26.130000] br-lan: port 2(wlan1) entered forwarding state
[   26.140000] br-lan: port 2(wlan1) entered forwarding state
[   26.140000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[   28.040000] br-lan: port 3(wlan0) entered forwarding state
[   28.140000] br-lan: port 2(wlan1) entered forwarding state
[   38.170000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up
[   39.820000] random: nonblocking pool is initialized
[   84.420000] pppoe-wan: renamed from ppp0
[ 2644.170000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[ 7109.700000] nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead.
[19193.800000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[23853.030000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[25725.340000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[26457.800000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[44107.050000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[81797.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[81813.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[81815.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[81839.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[81841.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[97134.690000] device wlan0 left promiscuous mode
[97134.690000] br-lan: port 3(wlan0) entered disabled state
[97136.480000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[97136.500000] device wlan0 entered promiscuous mode
[97137.710000] br-lan: port 3(wlan0) entered forwarding state
[97137.720000] br-lan: port 3(wlan0) entered forwarding state
[97137.720000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[97137.750000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[97137.780000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[97139.720000] br-lan: port 3(wlan0) entered forwarding state
[97175.130000] device wlan0 left promiscuous mode
[97175.140000] br-lan: port 3(wlan0) entered disabled state
[97176.850000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[97176.870000] device wlan0 entered promiscuous mode
[97176.880000] br-lan: port 3(wlan0) entered forwarding state
[97176.880000] br-lan: port 3(wlan0) entered forwarding state
[97177.130000] br-lan: port 3(wlan0) entered disabled state
[97178.230000] br-lan: port 3(wlan0) entered forwarding state
[97178.230000] br-lan: port 3(wlan0) entered forwarding state
[97178.240000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[97178.270000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[97179.430000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[97180.230000] br-lan: port 3(wlan0) entered forwarding state
[97440.330000] device wlan0 left promiscuous mode
[97440.340000] br-lan: port 3(wlan0) entered disabled state
[97442.230000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[97442.250000] device wlan0 entered promiscuous mode
[97442.250000] br-lan: port 3(wlan0) entered forwarding state
[97442.260000] br-lan: port 3(wlan0) entered forwarding state
[97442.330000] br-lan: port 3(wlan0) entered disabled state
[97443.650000] br-lan: port 3(wlan0) entered forwarding state
[97443.650000] br-lan: port 3(wlan0) entered forwarding state
[97443.660000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[97443.700000] device wlan0-1 entered promiscuous mode
[97443.700000] br-lan: port 4(wlan0-1) entered forwarding state
[97443.710000] br-lan: port 4(wlan0-1) entered forwarding state
[97443.720000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[97444.650000] br-lan: port 4(wlan0-1) entered disabled state
[97444.920000] br-lan: port 4(wlan0-1) entered forwarding state
[97444.920000] br-lan: port 4(wlan0-1) entered forwarding state
[97444.930000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[97445.650000] br-lan: port 3(wlan0) entered forwarding state
[97446.920000] br-lan: port 4(wlan0-1) entered forwarding state
[97659.920000] device wlan0-1.sta1 entered promiscuous mode
[97659.940000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97659.940000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97661.940000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97827.920000] device wlan0-1.sta1 left promiscuous mode
[97827.930000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[97832.820000] device wlan0-1.sta1 entered promiscuous mode
[97832.840000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97832.840000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97834.840000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97900.460000] device wlan0-1.sta1 left promiscuous mode
[97900.470000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[97906.420000] device wlan0-1.sta1 entered promiscuous mode
[97906.420000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97906.430000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97908.430000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97942.870000] device wlan0-1.sta1 left promiscuous mode
[97942.880000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[97949.260000] device wlan0-1.sta1 entered promiscuous mode
[97949.270000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97949.270000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97951.270000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[98257.020000] device wlan0-1.sta1 left promiscuous mode
[98257.020000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[98257.060000] br-lan: port 4(wlan0-1) entered disabled state
[98257.070000] device wlan0-1 left promiscuous mode
[98257.080000] br-lan: port 4(wlan0-1) entered disabled state
[98257.170000] device wlan0 left promiscuous mode
[98257.170000] br-lan: port 3(wlan0) entered disabled state
[98259.160000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[98259.190000] device wlan0 entered promiscuous mode
[98260.550000] br-lan: port 3(wlan0) entered forwarding state
[98260.550000] br-lan: port 3(wlan0) entered forwarding state
[98260.560000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[98260.590000] device wlan0-1 entered promiscuous mode
[98260.600000] br-lan: port 4(wlan0-1) entered forwarding state
[98260.600000] br-lan: port 4(wlan0-1) entered forwarding state
[98260.610000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[98261.550000] br-lan: port 4(wlan0-1) entered disabled state
[98261.730000] br-lan: port 4(wlan0-1) entered forwarding state
[98261.740000] br-lan: port 4(wlan0-1) entered forwarding state
[98261.740000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[98262.550000] br-lan: port 3(wlan0) entered forwarding state
[98263.420000] device wlan0-1.sta1 entered promiscuous mode
[98263.450000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[98263.450000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[98263.740000] br-lan: port 4(wlan0-1) entered forwarding state
[98265.450000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[99454.620000] device wlan1 left promiscuous mode
[99454.620000] br-lan: port 2(wlan1) entered disabled state
[100070.240000] device wlan0-1.sta1 left promiscuous mode
[100070.250000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[100281.850000] br-lan: port 4(wlan0-1) entered disabled state
[100281.860000] device wlan0-1 left promiscuous mode
[100281.870000] br-lan: port 4(wlan0-1) entered disabled state
[100281.910000] device wlan0 left promiscuous mode
[100281.910000] br-lan: port 3(wlan0) entered disabled state
[100283.240000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[100283.270000] device wlan0 entered promiscuous mode
[100283.270000] br-lan: port 2(wlan0) entered forwarding state
[100283.280000] br-lan: port 2(wlan0) entered forwarding state
[100283.900000] br-lan: port 2(wlan0) entered disabled state
[100283.990000] br-lan: port 2(wlan0) entered forwarding state
[100283.990000] br-lan: port 2(wlan0) entered forwarding state
[100284.000000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[100285.990000] br-lan: port 2(wlan0) entered forwarding state
[134119.590000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[161102.430000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[223218.820000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[245069.980000] pppoe-wan: renamed from ppp0
[245141.460000] pppoe-wan: renamed from ppp0
[245299.040000] pppoe-wan: renamed from ppp0
[252119.680000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[261023.840000] pppoe-wan: renamed from ppp0
[276408.110000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[280735.660000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[302164.860000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[321933.150000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[325744.990000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[329242.150000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[336899.070000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[351077.580000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[394867.730000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[431387.900000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[440434.580000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[441806.590000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[447454.380000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[447456.380000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[466952.370000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[473239.530000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[480399.960000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[501467.470000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[508584.020000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[535910.490000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[570877.490000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[590169.060000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[593579.440000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[609923.510000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[657133.040000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[675247.920000] pppoe-wan: renamed from ppp0
[676070.460000] pppoe-wan: renamed from ppp0
[691847.930000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[694520.940000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[694532.940000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[756425.970000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[784898.540000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[786849.980000] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[786850.040000] device wlan1 entered promiscuous mode
[786850.700000] br-lan: port 3(wlan1) entered forwarding state
[786850.710000] br-lan: port 3(wlan1) entered forwarding state
[786850.710000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[786852.710000] br-lan: port 3(wlan1) entered forwarding state
[827858.480000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down

(Last edited by mezo on 12 Oct 2015, 12:31)

Post #7

moeller0

12 Oct 2015, 13:08

Hi mezzo,

mezo wrote:

hey,
i changed back to ddwrt for a while and everything is fine there. my brother is connected with lan and he told me he does not have this problem. so i assume its wifi related (both 2,4 and 5ghz).

Okay that sounds like a reasonable theory. That also means I will not be of too much help, as I come from the sqm-scripts crowd, nit the wifi crowd...
But assuming it is an wifi issue maybe the following commands could help (store the output after boot, after some use without the issue and directly after having witnessed the problem and compare them):

cat /sys/kernel/debug/ieee80211/phy0/ath9k/xmit,
cat /sys/kernel/debug/ieee80211/phy0/ath9k/recv
cat /sys/kernel/debug/ieee80211/phy0/ath9k/reset
cat /sys/kernel/debug/ieee80211/phy0/ath9k/interrupt
cat /sys/kernel/debug/ieee80211/phy0/ath9k/queues

cat /sys/kernel/debug/ieee80211/phy1/ath9k/xmit,
cat /sys/kernel/debug/ieee80211/phy1/ath9k/recv
cat /sys/kernel/debug/ieee80211/phy1/ath9k/reset
cat /sys/kernel/debug/ieee80211/phy1/ath9k/interrupt
cat /sys/kernel/debug/ieee80211/phy1/ath9k/queues

Note I assume you have an ath9k radio with two PHYs. I believe especially the resets might be interesting... I have seen a setup where a rough 2.4GHz-AP in the neighborhood was able to affect both my 5 and 2GHz radios with a roughly 56 seconds period, the issue showed up as latency spikes just as you noticed. So it might be really a wifi driver/configuration difference between openwrt and ddwrt you are witnessing here, if I might speculate.

mezo wrote:

is flent aviablo for windows?

As far as I know, it is not, but it is possible to run it from a virtual machine under windows, say the free virtualbox.

mezo wrote:

before sqm stop

root@OpenWrt:~# tc -d qdisc
qdisc fq_codel 0: dev eth0 root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan0 root
qdisc fq_codel 0: dev wlan0 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc htb 1: dev pppoe-wan root refcnt 2 r2q 10 default 12 direct_packets_stat 0 ver 3.17 direct_qlen 3
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev pppoe-wan parent 1:11 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 120: dev pppoe-wan parent 1:12 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 130: dev pppoe-wan parent 1:13 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc ingress ffff: dev pppoe-wan parent ffff:fff1 ----------------
qdisc htb 1: dev ifb4pppoe-wan root refcnt 2 r2q 10 default 10 direct_packets_stat 0 ver 3.17 direct_qlen 32
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev ifb4pppoe-wan parent 1:10 limit 1001p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan1 root
qdisc fq_codel 0: dev wlan1 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn

after stop:

root@OpenWrt:~# tc -d qdisc
qdisc fq_codel 0: dev eth0 root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan0 root
qdisc fq_codel 0: dev wlan0 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev pppoe-wan root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan1 root
qdisc fq_codel 0: dev wlan1 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn

after starting it again:

root@OpenWrt:~# tc -d qdisc
qdisc fq_codel 0: dev eth0 root refcnt 2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc mq 0: dev wlan0 root
qdisc fq_codel 0: dev wlan0 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan0 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc htb 1: dev pppoe-wan root refcnt 2 r2q 10 default 12 direct_packets_stat 4 ver 3.17 direct_qlen 3
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev pppoe-wan parent 1:11 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 120: dev pppoe-wan parent 1:12 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc fq_codel 130: dev pppoe-wan parent 1:13 limit 1001p flows 1024 quantum 300 target 8.2ms interval 103.2ms ecn
qdisc ingress ffff: dev pppoe-wan parent ffff:fff1 ----------------
qdisc mq 0: dev wlan1 root
qdisc fq_codel 0: dev wlan1 parent :1 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :2 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :3 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc fq_codel 0: dev wlan1 parent :4 limit 1024p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn
qdisc htb 1: dev ifb4pppoe-wan root refcnt 2 r2q 10 default 10 direct_packets_stat 0 ver 3.17 direct_qlen 32
 linklayer atm overhead 44 mtu 2047 tsize 512
qdisc fq_codel 110: dev ifb4pppoe-wan parent 1:10 limit 1001p flows 1024 quantum 300 target 5.0ms interval 100.0ms ecn

/etc/config/sqm

config queue 'eth1'
        option qdisc 'fq_codel'
        option linklayer 'atm'
        option overhead '44'
        option enabled '1'
        option download '14000'
        option upload '1500'
        option interface 'pppoe-wan'
        option script 'simple.qos'
        option qdisc_advanced '0'

Mmh, these all look fine, and if the issue is restricted to wlan only, so I would say that for the time being sqm does not seem to be the most likely candidate.

mezo wrote:

system log

Mon Oct 12 12:21:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:21:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:22:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:22:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:22:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:22:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:22:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:22:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:22:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:22:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:24:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:24:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:24:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:24:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:24:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:24:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:24:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:25:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 12:26:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:26:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:26:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:26:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:26:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:26:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:26:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:31:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:31:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:31:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:31:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:31:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:31:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:31:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:32:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:35:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 12:37:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:37:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:37:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:37:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:37:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:37:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:37:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:39:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:39:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:39:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:39:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:39:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:39:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:39:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:41:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:41:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:41:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:41:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:41:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:41:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:41:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:41:29 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:41:29 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:41:29 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:41:30 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:41:30 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:41:40 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:41:41 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:42:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:45:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 12:51:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:51:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:51:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:51:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:51:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:51:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:51:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:52:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:52:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:52:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:52:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:52:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:52:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:52:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 12:52:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 12:54:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 12:54:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 12:54:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 12:54:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 12:54:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 12:54:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 12:54:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 12:55:25 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 13:01:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:01:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:01:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:01:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:01:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:01:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:01:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 13:02:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 13:05:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:05:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:05:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:05:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:05:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:05:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:05:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:05:26 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 13:11:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:11:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:11:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:11:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:11:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:11:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:11:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 13:12:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 13:15:26 2015 daemon.info hostapd: wlan1: STA 10:fe:ed:1f:2f:57 WPA: group key handshake completed (RSN)
Mon Oct 12 13:16:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:16:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:16:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:16:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:16:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:16:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:16:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:18:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:18:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:18:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:18:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:18:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:18:22 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:18:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:21:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: authenticated
Mon Oct 12 13:21:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: associated (aid 4)
Mon Oct 12 13:21:12 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d WPA: pairwise key handshake completed (RSN)
Mon Oct 12 13:21:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPREQUEST(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d 
Mon Oct 12 13:21:12 2015 daemon.info dnsmasq-dhcp[22961]: DHCPACK(br-lan) 192.168.1.123 2c:10:c1:e8:85:1d Wii
Mon Oct 12 13:21:23 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: disassociated
Mon Oct 12 13:21:24 2015 daemon.info hostapd: wlan0: STA 2c:10:c1:e8:85:1d IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA a0:f3:c1:17:d2:d8 WPA: group key handshake completed (RSN)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA 84:c9:b2:01:8c:13 WPA: group key handshake completed (RSN)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA 00:22:c0:a0:03:55 WPA: group key handshake completed (RSN)
Mon Oct 12 13:22:42 2015 daemon.info hostapd: wlan0: STA 14:30:c6:e0:63:f6 WPA: group key handshake completed (RSN)
Mon Oct 12 13:24:48 2015 authpriv.info dropbear[28745]: Child connection from 192.168.1.199:58724
Mon Oct 12 13:24:53 2015 authpriv.notice dropbear[28745]: Password auth succeeded for 'root' from 192.168.1.199:58724

kernel log

[    0.000000] Linux version 3.18.20 (buildbot@builder1) (gcc version 4.8.3 (OpenWrt/Linaro GCC 4.8-2014.04 r46450) ) #1 Fri Sep 4 21:55:57 CEST 2015
[    0.000000] MyLoader: sysp=d41aa8a5, boardp=3bb52003, parts=bd952718
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
[    0.000000] SoC: Atheros AR9344 rev 2
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 08000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x00000000-0x07ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00000000-0x07ffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0x07ffffff]
[    0.000000] On node 0 totalpages: 32768
[    0.000000] free_area_init_node: node 0, pgdat 8034e2b0, node_mem_map 81000000
[    0.000000]   Normal zone: 256 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 32768 pages, LIFO batch:7
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line:  board=TL-WDR4300  console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 125980K/131072K available (2621K kernel code, 129K rwdata, 344K rodata, 224K init, 194K bss, 5092K reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:560.000MHz, DDR:450.000MHz, AHB:225.000MHz, Ref:40.000MHz
[    0.000000] Calibrating delay loop... 278.93 BogoMIPS (lpj=1394688)
[    0.070000] pid_max: default: 32768 minimum: 301
[    0.070000] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.080000] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.080000] NET: Registered protocol family 16
[    0.090000] MIPS: machine is TP-LINK TL-WDR3600/4300/4310
[    0.090000] registering PCI controller with io_map_base unset
[    0.330000] PCI host bridge to bus 0000:00
[    0.330000] pci_bus 0000:00: root bus resource [mem 0x10000000-0x13ffffff]
[    0.340000] pci_bus 0000:00: root bus resource [io  0x0000]
[    0.340000] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.350000] pci 0000:00:00.0: [168c:0033] type 00 class 0x028000
[    0.350000] pci 0000:00:00.0: invalid calibration data
[    0.350000] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
[    0.350000] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    0.350000] pci 0000:00:00.0: supports D1
[    0.350000] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    0.350000] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    0.350000] pci 0000:00:00.0: BAR 0: assigned [mem 0x10000000-0x1001ffff 64bit]
[    0.360000] pci 0000:00:00.0: BAR 6: assigned [mem 0x10020000-0x1002ffff pref]
[    0.360000] pci 0000:00:00.0: using irq 40 for pin 1
[    0.370000] Switched to clocksource MIPS
[    0.370000] NET: Registered protocol family 2
[    0.380000] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.380000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.390000] TCP: Hash tables configured (established 1024 bind 1024)
[    0.390000] TCP: reno registered
[    0.400000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.400000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.410000] NET: Registered protocol family 1
[    0.410000] PCI: CLS 0 bytes, default 32
[    0.410000] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.440000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.440000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.450000] msgmni has been set to 246
[    0.460000] io scheduler noop registered
[    0.460000] io scheduler deadline registered (default)
[    0.470000] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.470000] console [ttyS0] disabled
[    0.500000] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 2500000) is a 16550A
[    0.510000] console [ttyS0] enabled
[    0.510000] bootconsole [early0] disabled
[    0.530000] m25p80 spi0.0: found s25fl064k, expected m25p80
[    0.530000] m25p80 spi0.0: s25fl064k (8192 Kbytes)
[    0.540000] 5 tp-link partitions found on MTD device spi0.0
[    0.540000] Creating 5 MTD partitions on "spi0.0":
[    0.550000] 0x000000000000-0x000000020000 : "u-boot"
[    0.550000] 0x000000020000-0x00000012c5a4 : "kernel"
[    0.560000] 0x00000012c5a4-0x0000007f0000 : "rootfs"
[    0.570000] mtd: device 2 (rootfs) set to be root filesystem
[    0.570000] 1 squashfs-split partitions found on MTD device rootfs
[    0.580000] 0x000000360000-0x0000007f0000 : "rootfs_data"
[    0.590000] 0x0000007f0000-0x000000800000 : "art"
[    0.590000] 0x000000020000-0x0000007f0000 : "firmware"
[    0.610000] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-mdio.0
[    0.670000] libphy: ag71xx_mdio: probed
[    1.270000] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[    1.280000] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[    1.290000] TCP: cubic registered
[    1.290000] NET: Registered protocol family 17
[    1.290000] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.310000] Bridge firewalling registered
[    1.310000] 8021q: 802.1Q VLAN Support v1.8
[    1.320000] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.330000] Freeing unused kernel memory: 224K (80368000 - 803a0000)
[    2.330000] init: Console is alive
[    2.330000] init: - watchdog -
[    3.540000] usbcore: registered new interface driver usbfs
[    3.540000] usbcore: registered new interface driver hub
[    3.550000] usbcore: registered new device driver usb
[    3.560000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.570000] ehci-platform: EHCI generic platform driver
[    3.570000] ehci-platform ehci-platform: EHCI Host Controller
[    3.580000] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[    3.590000] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[    3.610000] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[    3.610000] hub 1-0:1.0: USB hub found
[    3.620000] hub 1-0:1.0: 1 port detected
[    3.940000] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    4.090000] hub 1-1:1.0: USB hub found
[    4.090000] hub 1-1:1.0: 4 ports detected
[    4.380000] init: - preinit -
[    4.980000] random: procd urandom read with 8 bits of entropy available
[    6.380000] eth0: link up (1000Mbps/Full duplex)
[    8.230000] jffs2: notice: (392) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[    8.250000] mount_root: switching to jffs2 overlay
[    8.290000] eth0: link down
[    8.310000] procd: - early -
[    8.310000] procd: - watchdog -
[    9.030000] procd: - ubus -
[   10.040000] procd: - init -
[   11.130000] NET: Registered protocol family 10
[   11.160000] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   11.190000] u32 classifier
[   11.190000]     input device check on
[   11.200000]     Actions configured
[   11.200000] Mirror/redirect action on
[   11.220000] netem: version 1.3
[   11.240000] Loading modules backported from Linux version master-2015-03-09-0-g141f155
[   11.250000] Backport generated by backports.git backports-20150129-0-gdd4a670
[   11.260000] ip_tables: (C) 2000-2006 Netfilter Core Team
[   11.280000] nf_conntrack version 0.5.0 (1971 buckets, 7884 max)
[   11.370000] xt_time: kernel timezone is -0000
[   11.410000] cfg80211: Calling CRDA to update world regulatory domain
[   11.410000] cfg80211: World regulatory domain updated:
[   11.420000] cfg80211:  DFS Master region: unset
[   11.420000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   11.430000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   11.440000] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   11.450000] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[   11.460000] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   11.460000] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   11.470000] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[   11.480000] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   11.490000] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[   11.560000] PPP generic driver version 2.4.2
[   11.570000] NET: Registered protocol family 24
[   11.650000] ath: EEPROM regdomain: 0x0
[   11.650000] ath: EEPROM indicates default country code should be used
[   11.650000] ath: doing EEPROM country->regdmn map search
[   11.650000] ath: country maps to regdmn code: 0x3a
[   11.650000] ath: Country alpha2 being used: US
[   11.650000] ath: Regpair used: 0x3a
[   11.670000] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   11.670000] ieee80211 phy0: Atheros AR9340 Rev:2 mem=0xb8100000, irq=47
[   11.680000] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[   11.690000] ath: EEPROM regdomain: 0x0
[   11.690000] ath: EEPROM indicates default country code should be used
[   11.690000] ath: doing EEPROM country->regdmn map search
[   11.690000] ath: country maps to regdmn code: 0x3a
[   11.690000] ath: Country alpha2 being used: US
[   11.690000] ath: Regpair used: 0x3a
[   11.700000] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   11.700000] ieee80211 phy1: Atheros AR9300 Rev:4 mem=0xb0000000, irq=40
[   11.720000] cfg80211: Calling CRDA for country: US
[   11.730000] cfg80211: Regulatory domain changed to country: US
[   11.730000] cfg80211:  DFS Master region: FCC
[   11.740000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   11.750000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A)
[   11.760000] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 1700 mBm), (N/A)
[   11.770000] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
[   11.780000] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
[   11.780000] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   20.370000] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   20.390000] device eth0.1 entered promiscuous mode
[   20.390000] device eth0 entered promiscuous mode
[   20.410000] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   20.420000] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
[   21.370000] cfg80211: Calling CRDA for country: DE
[   21.370000] cfg80211: Regulatory domain changed to country: DE
[   21.380000] cfg80211:  DFS Master region: ETSI
[   21.380000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   21.390000] cfg80211:   (2400000 KHz - 2483000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   21.400000] cfg80211:   (5150000 KHz - 5250000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   21.410000] cfg80211:   (5250000 KHz - 5350000 KHz @ 80000 KHz, 200000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   21.420000] cfg80211:   (5470000 KHz - 5725000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s)
[   21.430000] cfg80211:   (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   21.790000] eth0: link up (1000Mbps/Full duplex)
[   21.790000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   21.830000] br-lan: port 1(eth0.1) entered forwarding state
[   21.830000] br-lan: port 1(eth0.1) entered forwarding state
[   21.840000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready
[   21.840000] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   23.830000] br-lan: port 1(eth0.1) entered forwarding state
[   24.270000] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[   24.310000] device wlan1 entered promiscuous mode
[   24.340000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   24.370000] device wlan0 entered promiscuous mode
[   24.370000] br-lan: port 3(wlan0) entered forwarding state
[   24.380000] br-lan: port 3(wlan0) entered forwarding state
[   25.280000] br-lan: port 3(wlan0) entered disabled state
[   26.030000] br-lan: port 3(wlan0) entered forwarding state
[   26.040000] br-lan: port 3(wlan0) entered forwarding state
[   26.040000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   26.130000] br-lan: port 2(wlan1) entered forwarding state
[   26.140000] br-lan: port 2(wlan1) entered forwarding state
[   26.140000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[   28.040000] br-lan: port 3(wlan0) entered forwarding state
[   28.140000] br-lan: port 2(wlan1) entered forwarding state
[   38.170000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up
[   39.820000] random: nonblocking pool is initialized
[   84.420000] pppoe-wan: renamed from ppp0
[ 2644.170000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[ 7109.700000] nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead.
[19193.800000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[23853.030000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[25725.340000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[26457.800000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[44107.050000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[81797.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[81813.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[81815.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[81839.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[81841.830000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[97134.690000] device wlan0 left promiscuous mode
[97134.690000] br-lan: port 3(wlan0) entered disabled state
[97136.480000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[97136.500000] device wlan0 entered promiscuous mode
[97137.710000] br-lan: port 3(wlan0) entered forwarding state
[97137.720000] br-lan: port 3(wlan0) entered forwarding state
[97137.720000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[97137.750000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[97137.780000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[97139.720000] br-lan: port 3(wlan0) entered forwarding state
[97175.130000] device wlan0 left promiscuous mode
[97175.140000] br-lan: port 3(wlan0) entered disabled state
[97176.850000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[97176.870000] device wlan0 entered promiscuous mode
[97176.880000] br-lan: port 3(wlan0) entered forwarding state
[97176.880000] br-lan: port 3(wlan0) entered forwarding state
[97177.130000] br-lan: port 3(wlan0) entered disabled state
[97178.230000] br-lan: port 3(wlan0) entered forwarding state
[97178.230000] br-lan: port 3(wlan0) entered forwarding state
[97178.240000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[97178.270000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[97179.430000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[97180.230000] br-lan: port 3(wlan0) entered forwarding state
[97440.330000] device wlan0 left promiscuous mode
[97440.340000] br-lan: port 3(wlan0) entered disabled state
[97442.230000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[97442.250000] device wlan0 entered promiscuous mode
[97442.250000] br-lan: port 3(wlan0) entered forwarding state
[97442.260000] br-lan: port 3(wlan0) entered forwarding state
[97442.330000] br-lan: port 3(wlan0) entered disabled state
[97443.650000] br-lan: port 3(wlan0) entered forwarding state
[97443.650000] br-lan: port 3(wlan0) entered forwarding state
[97443.660000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[97443.700000] device wlan0-1 entered promiscuous mode
[97443.700000] br-lan: port 4(wlan0-1) entered forwarding state
[97443.710000] br-lan: port 4(wlan0-1) entered forwarding state
[97443.720000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[97444.650000] br-lan: port 4(wlan0-1) entered disabled state
[97444.920000] br-lan: port 4(wlan0-1) entered forwarding state
[97444.920000] br-lan: port 4(wlan0-1) entered forwarding state
[97444.930000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[97445.650000] br-lan: port 3(wlan0) entered forwarding state
[97446.920000] br-lan: port 4(wlan0-1) entered forwarding state
[97659.920000] device wlan0-1.sta1 entered promiscuous mode
[97659.940000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97659.940000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97661.940000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97827.920000] device wlan0-1.sta1 left promiscuous mode
[97827.930000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[97832.820000] device wlan0-1.sta1 entered promiscuous mode
[97832.840000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97832.840000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97834.840000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97900.460000] device wlan0-1.sta1 left promiscuous mode
[97900.470000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[97906.420000] device wlan0-1.sta1 entered promiscuous mode
[97906.420000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97906.430000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97908.430000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97942.870000] device wlan0-1.sta1 left promiscuous mode
[97942.880000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[97949.260000] device wlan0-1.sta1 entered promiscuous mode
[97949.270000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97949.270000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[97951.270000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[98257.020000] device wlan0-1.sta1 left promiscuous mode
[98257.020000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[98257.060000] br-lan: port 4(wlan0-1) entered disabled state
[98257.070000] device wlan0-1 left promiscuous mode
[98257.080000] br-lan: port 4(wlan0-1) entered disabled state
[98257.170000] device wlan0 left promiscuous mode
[98257.170000] br-lan: port 3(wlan0) entered disabled state
[98259.160000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[98259.190000] device wlan0 entered promiscuous mode
[98260.550000] br-lan: port 3(wlan0) entered forwarding state
[98260.550000] br-lan: port 3(wlan0) entered forwarding state
[98260.560000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[98260.590000] device wlan0-1 entered promiscuous mode
[98260.600000] br-lan: port 4(wlan0-1) entered forwarding state
[98260.600000] br-lan: port 4(wlan0-1) entered forwarding state
[98260.610000] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[98261.550000] br-lan: port 4(wlan0-1) entered disabled state
[98261.730000] br-lan: port 4(wlan0-1) entered forwarding state
[98261.740000] br-lan: port 4(wlan0-1) entered forwarding state
[98261.740000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[98262.550000] br-lan: port 3(wlan0) entered forwarding state
[98263.420000] device wlan0-1.sta1 entered promiscuous mode
[98263.450000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[98263.450000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[98263.740000] br-lan: port 4(wlan0-1) entered forwarding state
[98265.450000] br-lan: port 5(wlan0-1.sta1) entered forwarding state
[99454.620000] device wlan1 left promiscuous mode
[99454.620000] br-lan: port 2(wlan1) entered disabled state
[100070.240000] device wlan0-1.sta1 left promiscuous mode
[100070.250000] br-lan: port 5(wlan0-1.sta1) entered disabled state
[100281.850000] br-lan: port 4(wlan0-1) entered disabled state
[100281.860000] device wlan0-1 left promiscuous mode
[100281.870000] br-lan: port 4(wlan0-1) entered disabled state
[100281.910000] device wlan0 left promiscuous mode
[100281.910000] br-lan: port 3(wlan0) entered disabled state
[100283.240000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[100283.270000] device wlan0 entered promiscuous mode
[100283.270000] br-lan: port 2(wlan0) entered forwarding state
[100283.280000] br-lan: port 2(wlan0) entered forwarding state
[100283.900000] br-lan: port 2(wlan0) entered disabled state
[100283.990000] br-lan: port 2(wlan0) entered forwarding state
[100283.990000] br-lan: port 2(wlan0) entered forwarding state
[100284.000000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[100285.990000] br-lan: port 2(wlan0) entered forwarding state
[134119.590000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[161102.430000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[223218.820000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[245069.980000] pppoe-wan: renamed from ppp0
[245141.460000] pppoe-wan: renamed from ppp0
[245299.040000] pppoe-wan: renamed from ppp0
[252119.680000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[261023.840000] pppoe-wan: renamed from ppp0
[276408.110000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[280735.660000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[302164.860000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[321933.150000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[325744.990000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[329242.150000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[336899.070000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[351077.580000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[394867.730000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[431387.900000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[440434.580000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[441806.590000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[447454.380000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[447456.380000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[466952.370000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[473239.530000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[480399.960000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[501467.470000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[508584.020000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[535910.490000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[570877.490000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[590169.060000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[593579.440000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[609923.510000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[657133.040000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[675247.920000] pppoe-wan: renamed from ppp0
[676070.460000] pppoe-wan: renamed from ppp0
[691847.930000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[694520.940000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[694532.940000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[756425.970000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down
[784898.540000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is up
[786849.980000] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[786850.040000] device wlan1 entered promiscuous mode
[786850.700000] br-lan: port 3(wlan1) entered forwarding state
[786850.710000] br-lan: port 3(wlan1) entered forwarding state
[786850.710000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[786852.710000] br-lan: port 3(wlan1) entered forwarding state
[827858.480000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 2 is down

I have to admit both logs look normal to me. It would really help to have a before and an after log, to be able to find differences...

Best Regards
M.

Post #8

mezo

12 Oct 2015, 14:38

hey, as you seems to know much about the sqm stuff, i want to ask you something.

in our network we watch much twitch and this works even, if a single download, like a single http download get started, but whenever multiple downloads get started, like steam download, battlenet download, multiple http files at once, twitch starts to lag.

so my question: is it possible to solve this?

Post #9

moeller0

12 Oct 2015, 18:03

Hi Mezo,

mezo wrote:

hey, as you seems to know much about the sqm stuff, i want to ask you something.
in our network we watch much twitch and this works even, if a single download, like a single http download get started, but whenever multiple downloads get started, like steam download, battlenet download, multiple http files at once, twitch starts to lag.
so my question: is it possible to solve this?

Not sure whether the problem is solvable at least not easily. I use neither twitch nor steam nor battle net, so can not easily recreate your traffic situation.
Simplest.qos basically relies on fq_codels' flow isolation, which typically is quite good, but can suffer if too many flows are active (several flows than hash into the same queue and the heavier flow will cause inappropriate "damage" to the lighter flow in the bin, as all packets in a bin/queue are treated like the worst behaving flow in that bin; the idea is to simply put each flow into its own bin to solve this problem, but too many active flows will make this harder; the new qdisc cake seems to deal much better with this issue.) If there are too many active flows that need servicing some will need to give.
Simple.qos in comparison to simplest.qos has the concept of three priority bands, in theory you could either move twitch traffic into the highest priority band, if that band has enough bandwidth on your link; or probably better longterm move the offending download traffic to the background queue. Ideally the software in question would use DSCP to mark its packets so sqm knows what to do with it; for example packets marked EF (expedited forwarding) are put into the priority queue, while packets marked CS1 are put into the background queue. So you could try marking twitch packets as EF and/or steam packets CS1. The tricky thing is typically how. In theory you should be able to have the applications make the proper marks in their packets, but that will not work if for example you use the browser to watch twitch (is that how that works btw?) and the same browser to initiate downloads (one is lucky if an application allows to set DSCP marks at all, so being able to set different marks for different flows probably is asking too much)?
If all latency critical traffic can be easily differentiated from the bulk traffic from simply looking at the packets header than you could teach your router to mark the packets appropriately, but that will not be too easy.

You could try nxt_routed_hfsc.qos as this tries to expedite known latency critical packet hill pushing anything else into the background queue. But I have a hunch that it will not be able to divine which packets are latency critical for you...

Best Regards & sorry to be of no great help here...

Post #10

mezo

19 Oct 2015, 13:20

hey
thanks. i tried nxt_routed_hfsc.qos but it gives me bufferbloat.

(Last edited by mezo on 19 Oct 2015, 13:20)

Post #11

moeller0

19 Oct 2015, 14:32

Hi mezo,

mezo wrote:

hey
thanks. i tried nxt_routed_hfsc.qos but it gives me bufferbloat.

Interesting, thanks for testing.

Best Regards
M.

Post #12

mezo

22 Oct 2015, 19:22

sorry for bumping this, but my problem is still present and really annoying.

Post #13

moeller0

23 Oct 2015, 09:31

mezo wrote:

sorry for bumping this, but my problem is still present and really annoying.

Well, as far as I can see the next best thing to do is taking packet captures (on your router) while you exercise your network and try to recreate the situations under which you encounter the latency issues. Then you can try to figure out whether there are simple ways of separating the latency critical data-flows you want to prioritize over the bulk flows that cause your issues. If you can separate these all tat is left is to create a few filters in simple.qos to make sure stuff is treated correctly. But the first step really is to figure out whether you can easily sort your packets... (Currently sqm basically uses DSCP marking in IP headers to sort packets into different priority bands, so the "easiest" would be to teach the offending applications to use different markings for interactive/twitch and the downloads, but I have a hunch you might be using a browser for both which will make differential marking be quite challenging...)
If this is too involved you might want to try gargoyle, as I believe their qos GUI has some options that might be worth testing; even if you do not want to fully switch to gargoyle it would be good to know if they work better for your issues.

Best Regards
M.

Post #14

valentt

10 Jan 2016, 23:43

Try using Gargoyle firmware, it is based upon OpenWrt but they could have getter qos setup, that is at least what they claim... so try and see for yourself.

(Last edited by valentt on 10 Jan 2016, 23:43)

The discussion might have continued from here.

Page 1 of 1