#20351: WRT1200AC intermittent cpu stall
-----------------------+----------------------------------
Reporter: anonymous | Owner: developers
Type: defect | Status: new
Priority: normal | Milestone: Chaos Calmer (trunk)
Component: other | Version: Trunk
Keywords: |
-----------------------+----------------------------------
Router: WRT1200ACv1
{{{
BusyBox v1.23.2 (2015-08-17 01:32:41 CEST) built-in shell (ash)
_______ ________ __
| |.-----.-----.-----.| | | |.----.| |_
| - || _ | -__| || | | || _|| _|
|_______|| __|_____|__|__||________||__| |____|
|__| W I R E L E S S F R E E D O M
-----------------------------------------------------
CHAOS CALMER (Bleeding Edge, r46609)
-----------------------------------------------------
* 1 1/2 oz Gin Shake with a glassful
* 1/4 oz Triple Sec of broken ice and pour
* 3/4 oz Lime Juice unstrained into a goblet.
* 1 1/2 oz Orange Juice
* 1 tsp. Grenadine Syrup
-----------------------------------------------------
Linux router 3.18.20 #1 SMP Mon Aug 17 01:59:08 CEST 2015 armv7l GNU/Linux
}}}
dmesg
{{{
[ 10.797327] ieee80211 phy0: 11 11 11 11 10 10 10 10 11 11 11 11 10 10
10 10
[ 10.804395] ieee80211 phy0: Channel: 140: 0x0 0x0 0xf
[ 10.809461] ieee80211 phy0: 11 11 11 11 10 10 10 10 11 11 11 11 10 10
10 10
[ 10.816529] ieee80211 phy0: Channel: 149: 0x0 0x0 0xf
[ 10.821598] ieee80211 phy0: 1a 1a 18 17 19 19 17 15 18 18 17 14 15 15
15 14
[ 10.828662] ieee80211 phy0: Channel: 153: 0x0 0x0 0xf
[ 10.833731] ieee80211 phy0: 1a 1a 18 17 1a 1a 17 15 1a 1a 17 14 15 15
15 14
[ 10.840796] ieee80211 phy0: Channel: 157: 0x0 0x0 0xf
[ 10.845865] ieee80211 phy0: 1a 1a 18 17 1a 1a 17 15 1a 1a 17 14 15 15
15 14
[ 10.852933] ieee80211 phy0: Channel: 161: 0x0 0x0 0xf
[ 10.857999] ieee80211 phy0: 1a 1a 18 17 1a 1a 17 15 1a 1a 17 14 15 15
15 14
[ 10.865066] ieee80211 phy0: Channel: 165: 0x0 0x0 0xf
[ 10.870132] ieee80211 phy0: 1a 1a 18 17 1a 1a 17 15 1a 1a 17 14 15 15
15 14
[ 10.877953] pci 0000:00:02.0: enabling device (0140 -> 0142)
[ 10.883722] ieee80211 phy1: priv->iobase0 = e1200000
[ 10.888719] ieee80211 phy1: priv->iobase1 = e1400000
[ 10.893753] ieee80211 phy1: priv->pcmd_buf = de2d0000
priv->pphys_cmd_buf = 1e2d0000
[ 10.902838] ieee80211 phy1: fw download start 88
[ 11.022751] ieee80211 phy1: FwSize = 116356 downloaded Size = 116356
curr_iteration 65510
[ 11.285341] ieee80211 phy1: 0;
[ 11.542783] ieee80211 phy1: 0;
[ 11.800218] ieee80211 phy1: 0;
[ 11.886079] ieee80211 phy1: complete
[ 11.889678] ieee80211 phy1: 2G: enable
[ 11.893438] ieee80211 phy1: 5G: disable
[ 11.897282] ieee80211 phy1: TX: 2 antennas
[ 11.901390] ieee80211 phy1: RX: 2 antennas
[ 11.906780] ieee80211 phy1: pcmd = de2d0000
[ 11.911783] ieee80211 phy1: mwl_fwcmd_set_hw_specs ...
[ 11.916934] ieee80211 phy1: -->pPhysTxRing[0] = 1e300000
[ 11.922437] ieee80211 phy1: -->pPhysTxRing[1] = 1e304000
[ 11.927934] ieee80211 phy1: -->pPhysTxRing[2] = 1e308000
[ 11.933439] ieee80211 phy1: -->pPhysTxRing[3] = 1e30c000
[ 11.938936] ieee80211 phy1: -->pPhysRxRing = 1e998000
[ 11.944438] ieee80211 phy1: -->numtxq 20 wcbperq 256 totalrxwcb 256
[ 11.955879] ieee80211 phy1: firmware version: 0x7020806
[ 11.975173] ieee80211 phy1: phy1: FCC
[ 11.978847] ieee80211 phy1: Channel: 1: 0x0 0x0 0xf
[ 11.983748] ieee80211 phy1: 19 14 14 14 13 13 13 13 10 10 10 10 0 0 0 0
[ 11.990464] ieee80211 phy1: Channel: 2: 0x0 0x0 0xf
[ 11.995361] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.002080] ieee80211 phy1: Channel: 3: 0x0 0x0 0xf
[ 12.006973] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.013694] ieee80211 phy1: Channel: 4: 0x0 0x0 0xf
[ 12.018586] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.025305] ieee80211 phy1: Channel: 5: 0x0 0x0 0xf
[ 12.030197] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.036920] ieee80211 phy1: Channel: 6: 0x0 0x0 0xf
[ 12.041816] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.048533] ieee80211 phy1: Channel: 7: 0x0 0x0 0xf
[ 12.053428] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.060146] ieee80211 phy1: Channel: 8: 0x0 0x0 0xf
[ 12.065044] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.071764] ieee80211 phy1: Channel: 9: 0x0 0x0 0xf
[ 12.076655] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.083374] ieee80211 phy1: Channel: 10: 0x0 0x0 0xf
[ 12.088353] ieee80211 phy1: 1a 19 18 17 19 19 17 16 14 14 14 14 0 0 0 0
[ 12.095072] ieee80211 phy1: Channel: 11: 0x0 0x0 0xf
[ 12.100050] ieee80211 phy1: 19 15 15 15 14 14 14 14 13 13 13 13 0 0 0 0
[ 12.108222] i2c /dev entries driver
[ 12.117070] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 12.126369] nf_conntrack version 0.5.0 (8049 buckets, 32196 max)
[ 12.245651] xt_time: kernel timezone is -0000
[ 12.263285] PPP generic driver version 2.4.2
[ 12.267984] NET: Registered protocol family 24
[ 14.676289] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 14.684200] device eth0.1 entered promiscuous mode
[ 14.689011] device eth0 entered promiscuous mode
[ 14.695142] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[ 14.707312] IPv6: ADDRCONF(NETDEV_UP): eth0.1000: link is not ready
[ 14.737181] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
[ 15.511283] ieee80211 phy1: interface: 3, change: 0xe
[ 15.546275] ieee80211 phy1: interface: 3, change: 0x2000
[ 15.551605] ieee80211 phy1: change: 0xffffffff
[ 15.627094] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[ 15.634112] device wlan1 entered promiscuous mode
[ 15.638861] br-lan: port 2(wlan1) entered forwarding state
[ 15.644385] br-lan: port 2(wlan1) entered forwarding state
[ 15.650134] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 15.688180] ieee80211 phy0: interface: 3, change: 0xe
[ 15.723169] ieee80211 phy0: interface: 3, change: 0x2000
[ 15.728495] ieee80211 phy0: change: 0xffffffff
[ 15.804009] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 15.810036] br-lan: port 2(wlan1) entered disabled state
[ 15.815476] ieee80211 phy1: change: 0x100
[ 15.824494] ieee80211 phy1: change: 0x42
[ 15.884260] ieee80211 phy1: interface: 3, change: 0x40000
[ 15.889673] ieee80211 phy1: interface: 3, change: 0x4000
[ 15.895024] ieee80211 phy1: interface: 3, change: 0xc8340
[ 15.900443] ieee80211 phy1: wmm:26, rsn:0, rsn48:22, ht:62, vht:0
[ 15.926609] br-lan: port 2(wlan1) entered forwarding state
[ 15.932125] br-lan: port 2(wlan1) entered forwarding state
[ 15.937659] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 15.944328] device wlan0 entered promiscuous mode
[ 15.949097] ieee80211 phy1: interface: 3, change: 0x3e
[ 15.979785] ieee80211 phy1: interface: 3, change: 0x2000
[ 15.992372] ieee80211 phy1: interface: 3, change: 0x2000
[ 16.009759] ieee80211 phy1: interface: 3, change: 0x2000
[ 16.020114] ieee80211 phy1: interface: 3, change: 0x2000
[ 16.086672] ieee80211 phy0: change: 0x100
[ 16.095722] ieee80211 phy0: change: 0x42
[ 16.155620] ieee80211 phy0: interface: 3, change: 0x40000
[ 16.161054] ieee80211 phy0: interface: 3, change: 0x4000
[ 16.166395] ieee80211 phy0: interface: 3, change: 0xc8340
[ 16.171841] ieee80211 phy0: wmm:26, rsn:0, rsn48:22, ht:62, vht:21
[ 16.198313] br-lan: port 3(wlan0) entered forwarding state
[ 16.203842] br-lan: port 3(wlan0) entered forwarding state
[ 16.209386] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 16.216919] ieee80211 phy0: interface: 3, change: 0x3e
[ 16.247740] ieee80211 phy0: interface: 3, change: 0x2000
[ 16.258114] ieee80211 phy0: interface: 3, change: 0x2000
[ 16.268450] ieee80211 phy0: interface: 3, change: 0x2000
[ 16.278782] ieee80211 phy0: interface: 3, change: 0x2000
[ 16.800934] mvneta f1034000.ethernet eth0: link up
[ 16.805942] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 16.812397] br-lan: port 1(eth0.1) entered forwarding state
[ 16.818015] br-lan: port 1(eth0.1) entered forwarding state
[ 16.823719] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready
[ 16.830322] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.1000: link becomes
ready
[ 17.055080] ieee80211 phy1: interface: 3, change: 0x100
[ 17.060336] ieee80211 phy1: wmm:26, rsn:0, rsn48:22, ht:62, vht:0
[ 17.081727] ieee80211 phy1: interface: 3, change: 0x3e
[ 17.930917] br-lan: port 2(wlan1) entered forwarding state
[ 18.200914] br-lan: port 3(wlan0) entered forwarding state
[ 18.810914] br-lan: port 1(eth0.1) entered forwarding state
[ 19.849428] ieee80211 phy0: interface: 3, change: 0x100
[ 19.854742] ieee80211 phy0: wmm:26, rsn:0, rsn48:22, ht:62, vht:21
[ 19.876151] ieee80211 phy0: interface: 3, change: 0x36
[ 20.452281] ieee80211 phy1: interface: 3, change: 0x100
[ 20.457635] ieee80211 phy1: wmm:26, rsn:0, rsn48:22, ht:62, vht:0
[ 20.479067] ieee80211 phy1: interface: 3, change: 0x3e
[ 444.046792] u32 classifier
[ 444.049526] input device check on
[ 444.053195] Actions configured
[ 444.060969] Mirror/redirect action on
[ 444.194732] netem: version 1.3
[ 1199.413656] nf_conntrack: automatic helper assignment is deprecated and
it will be removed soon. Use the iptables CT target to attach helpers
instead.
[38374.256590] ieee80211 phy0: result error
[52224.027853] ieee80211 phy0: interface: 3, change: 0x100
[52224.033115] ieee80211 phy0: wmm:26, rsn:0, rsn48:22, ht:62, vht:21
[52224.054350] ieee80211 phy0: interface: 3, change: 0x36
[53149.052786] ieee80211 phy0: interface: 3, change: 0x100
[53149.058085] ieee80211 phy0: wmm:26, rsn:0, rsn48:22, ht:62, vht:21
[53149.079267] ieee80211 phy0: interface: 3, change: 0x36
[53424.032936] ieee80211 phy0: interface: 3, change: 0x100
[53424.038201] ieee80211 phy0: wmm:26, rsn:0, rsn48:22, ht:62, vht:21
[53424.059429] ieee80211 phy0: interface: 3, change: 0x36
[60421.951250] ieee80211 phy1: interface: 3, change: 0x100
[60421.956501] ieee80211 phy1: wmm:26, rsn:0, rsn48:22, ht:62, vht:0
[60421.977666] ieee80211 phy1: interface: 3, change: 0x3e
[60495.726290] ieee80211 phy1: interface: 3, change: 0x100
[60495.731561] ieee80211 phy1: wmm:26, rsn:0, rsn48:22, ht:62, vht:0
[60495.752739] ieee80211 phy1: interface: 3, change: 0x3e
}}}
logread
{{{
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA 6c:ad:f8:84:74:27
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA 24:0a:64:04:6e:e7
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA f4:81:39:96:64:d5
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA 00:03:2a:1c:84:b9
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA f4:09:d8:92:92:10
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: EAPOL-Key timeout
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:20:35 2015 daemon.info hostapd: wlan1: STA a0:02:dc:cc:85:c3
WPA: group key handshake completed (RSN)
Mon Aug 17 21:20:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key 2/2 Group with unexpected replay
counter
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: WPA rekeying GTK
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA 24:0a:64:04:6e:e7
WPA: group key handshake completed (RSN)
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA 6c:ad:f8:84:74:27
WPA: group key handshake completed (RSN)
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA f4:81:39:96:64:d5
WPA: group key handshake completed (RSN)
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
WPA: group key handshake completed (RSN)
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA a0:02:dc:cc:85:c3
WPA: group key handshake completed (RSN)
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA 00:03:2a:1c:84:b9
WPA: group key handshake completed (RSN)
Mon Aug 17 21:30:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:30:35 2015 daemon.info hostapd: wlan1: STA f4:09:d8:92:92:10
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: WPA rekeying GTK
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA 24:0a:64:04:6e:e7
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA 6c:ad:f8:84:74:27
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA f4:81:39:96:64:d5
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA 00:03:2a:1c:84:b9
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: EAPOL-Key timeout
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: EAPOL-Key timeout
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA a0:02:dc:cc:85:c3
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key 2/2 Group with unexpected replay
counter
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:40:35 2015 daemon.info hostapd: wlan1: STA f4:09:d8:92:92:10
WPA: group key handshake completed (RSN)
Mon Aug 17 21:40:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key 2/2 Group with unexpected replay
counter
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: WPA rekeying GTK
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:35 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:35 2015 daemon.info hostapd: wlan1: STA 6c:ad:f8:84:74:27
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:35 2015 daemon.info hostapd: wlan1: STA 24:0a:64:04:6e:e7
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:35 2015 daemon.info hostapd: wlan1: STA f4:81:39:96:64:d5
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:35 2015 daemon.info hostapd: wlan1: STA a0:02:dc:cc:85:c3
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:35 2015 daemon.info hostapd: wlan1: STA 00:03:2a:1c:84:b9
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: EAPOL-Key timeout
Mon Aug 17 21:50:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:36 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: EAPOL-Key timeout
Mon Aug 17 21:50:36 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 21:50:36 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 21:50:36 2015 daemon.info hostapd: wlan1: STA f4:09:d8:92:92:10
WPA: group key handshake completed (RSN)
Mon Aug 17 21:50:37 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key 2/2 Group with unexpected replay
counter
Mon Aug 17 21:50:37 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key 2/2 Group with unexpected replay
counter
Mon Aug 17 21:53:32 2015 authpriv.info dropbear[21649]: Child connection
from 192.168.127.65:1709
Mon Aug 17 21:53:35 2015 authpriv.notice dropbear[21649]: Password auth
succeeded for 'root' from 192.168.127.65:1709
Mon Aug 17 21:56:28 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: event 2 notification
Mon Aug 17 21:56:28 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b IEEE 802.1X: unauthorizing port
Mon Aug 17 21:56:28 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
IEEE 802.11: disassociated
Mon Aug 17 21:56:28 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-DISASSOCIATE.indication(b8:f6:b1:12:f3:8b, 8)
Mon Aug 17 21:56:28 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-DELETEKEYS.request(b8:f6:b1:12:f3:8b)
Mon Aug 17 21:56:29 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Aug 17 21:56:29 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-DEAUTHENTICATE.indication(b8:f6:b1:12:f3:8b,
2)
Mon Aug 17 21:56:29 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-DELETEKEYS.request(b8:f6:b1:12:f3:8b)
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: WPA rekeying GTK
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: sending 1/2 msg of Group Key Handshake
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
24:0a:64:04:6e:e7 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 22:00:35 2015 daemon.info hostapd: wlan1: STA 24:0a:64:04:6e:e7
WPA: group key handshake completed (RSN)
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
6c:ad:f8:84:74:27 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 22:00:35 2015 daemon.info hostapd: wlan1: STA 6c:ad:f8:84:74:27
WPA: group key handshake completed (RSN)
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
f4:81:39:96:64:d5 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 22:00:35 2015 daemon.info hostapd: wlan1: STA f4:81:39:96:64:d5
WPA: group key handshake completed (RSN)
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
00:03:2a:1c:84:b9 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 22:00:35 2015 daemon.info hostapd: wlan1: STA 00:03:2a:1c:84:b9
WPA: group key handshake completed (RSN)
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
a0:02:dc:cc:85:c3 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 22:00:35 2015 daemon.info hostapd: wlan1: STA a0:02:dc:cc:85:c3
WPA: group key handshake completed (RSN)
Mon Aug 17 22:00:35 2015 daemon.debug hostapd: wlan1: STA
f4:09:d8:92:92:10 WPA: received EAPOL-Key frame (2/2 Group)
Mon Aug 17 22:00:35 2015 daemon.info hostapd: wlan1: STA f4:09:d8:92:92:10
WPA: group key handshake completed (RSN)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b IEEE 802.11: authentication OK (open system)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-AUTHENTICATE.indication(b8:f6:b1:12:f3:8b,
OPEN_SYSTEM)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-DELETEKEYS.request(b8:f6:b1:12:f3:8b)
Mon Aug 17 22:05:50 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
IEEE 802.11: authenticated
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b IEEE 802.11: association OK (aid 7)
Mon Aug 17 22:05:50 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
IEEE 802.11: associated (aid 7)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-ASSOCIATE.indication(b8:f6:b1:12:f3:8b)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b MLME: MLME-DELETEKEYS.request(b8:f6:b1:12:f3:8b)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: event 1 notification
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: start authentication
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b IEEE 802.1X: unauthorizing port
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: sending 1/4 msg of 4-Way Handshake
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: received EAPOL-Key frame (2/4 Pairwise)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: sending 3/4 msg of 4-Way Handshake
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: EAPOL-Key timeout
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: sending 3/4 msg of 4-Way Handshake
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b WPA: received EAPOL-Key frame (4/4 Pairwise)
Mon Aug 17 22:05:50 2015 daemon.debug hostapd: wlan1: STA
b8:f6:b1:12:f3:8b IEEE 802.1X: authorizing port
Mon Aug 17 22:05:50 2015 daemon.info hostapd: wlan1: STA b8:f6:b1:12:f3:8b
WPA: pairwise key handshake completed (RSN)
Mon Aug 17 22:05:52 2015 daemon.info dnsmasq-dhcp[20763]: DHCPREQUEST(br-
lan) 192.168.127.57 b8:f6:b1:12:f3:8b
Mon Aug 17 22:05:52 2015 daemon.info dnsmasq-dhcp[20763]: DHCPACK(br-lan)
192.168.127.57 b8:f6:b1:12:f3:8b Yosemite-MBP
}}}
For the most part router runs great on fresh reboot.
CPU stays near 0% usage and performance is great.
But after some time (I am still unsure of the trigger, I am guessing it is
related to "[38374.256590] ieee80211 phy0: result error" but this message
doesn't always trigger the stalls, my suspected cause of trouble is my
wife's MacBookPro or Samsung Galaxy Alpha), router begins to stall
randomly for few seconds. This significantly slows down Luci (partial page
display and hangs for few seconds before displaying the rest) or even SSH
(I have to wait for SSH to respond after every few keystrokes).
-Internet occasionally drops for few seconds continues to work at degraded
state (with higher than normal bufferbloat and spikes during the stalls of
course) but never completely hangs.
-I have tried putting radios on different CPUs, but it didn't fix the
issue
-Issues existed when I ran CC RC3 with new wifi firmware.
Are there any diagnostics I can provide to help track down the cause of
this condition?
--
Ticket URL: <https://dev.openwrt.org/ticket/20351>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
