[LEDE-DEV] Linksys WRT1900ACS wifi issues with recent LEDE build

Tue Jan 10 09:30:35 PST 2017

I had an older build of LEDE from sometime in December that was quite
stable (I don't know the exact version any more), but I upgraded to
r2718-fd718c5 recently and I've been having issues with the wifi on an
approximately daily basis.  I finally captured logs from one of these
events.  I've included the logs below.  I removed a bunch of lines
that are obviously not relevant and redacted IPs and MAC addresses.
The first section of logs below is what I saw after running "logread"
immediately after I noticed issues.  I then ran "/etc/init.d/network
restart", hoping it would fix things, but I was still seeing errors
and wifi didn't work until after I rebooted the device.

I see a couple commits (94030e86d5ea357f6c541d534c0ee1c8ab714fa5 and
24e58c3f72a94ebf659586eb876b06b59adb2bb2) that look suspicious simply
because they touched mwlwifi during the time period between my working
and non-working builds.  I haven't had a chance yet to test rolling
back either of those commits.

I also noticed that there's a very recent upstream commit in mwlwifi
titled "Modification of the code to truely support ACS." (commit id
0d5b9c26adbd984dee814453ab49914cc78bce19), which seems like something
that might be worth trying.

Any ideas?  Any additional information I can provide that might be useful?

Tue Jan 10 02:51:07 2017 kern.err kernel: [84609.492605] ieee80211
phy0: cmd 0x9125=BAStream timed out
Tue Jan 10 02:51:07 2017 kern.err kernel: [84609.498050] ieee80211
phy0: return code: 0x1125
Tue Jan 10 02:51:07 2017 kern.err kernel: [84609.502618] ieee80211
phy0: timeout: 0x1125
Tue Jan 10 02:51:07 2017 kern.err kernel: [84609.506838] ieee80211
phy0: destroy ba failed execution
Tue Jan 10 02:51:12 2017 daemon.info hostapd: wlan1: STA XXX WPA:
group key handshake completed (RSN)
Tue Jan 10 02:51:44 2017 kern.err kernel: [84646.102006] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:51:44 2017 kern.err kernel: [84646.108136] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:51:44 2017 kern.err kernel: [84646.112692] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:51:44 2017 kern.err kernel: [84646.116892] ieee80211
phy0: failed execution
Tue Jan 10 02:51:44 2017 kern.err kernel: [84646.121180] wlan0: failed
to remove key (0, XXX) from hardware (-5)
Tue Jan 10 02:51:48 2017 kern.err kernel: [84650.128059] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:51:48 2017 kern.err kernel: [84650.134193] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:51:48 2017 kern.err kernel: [84650.138744] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:51:48 2017 kern.err kernel: [84650.142961] ieee80211
phy0: failed execution
Tue Jan 10 02:51:48 2017 kern.err kernel: [84650.147253] wlan0: failed
to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-4)
Tue Jan 10 02:51:52 2017 kern.err kernel: [84654.153997] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:51:52 2017 kern.err kernel: [84654.160120] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:51:52 2017 kern.err kernel: [84654.164684] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:51:52 2017 kern.err kernel: [84654.168887] ieee80211
phy0: failed execution
Tue Jan 10 02:51:52 2017 kern.err kernel: [84654.173182] wlan0: failed
to set key (2, ff:ff:ff:ff:ff:ff) to hardware (-5)
Tue Jan 10 02:51:56 2017 kern.err kernel: [84658.179938] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:51:56 2017 kern.err kernel: [84658.186066] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:51:56 2017 kern.err kernel: [84658.190615] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:51:56 2017 kern.err kernel: [84658.194822] ieee80211
phy0: failed execution
Tue Jan 10 02:51:56 2017 kern.err kernel: [84658.199110] wlan0: failed
to remove key (0, XXX) from hardware (-5)
Tue Jan 10 02:52:00 2017 kern.err kernel: [84662.211881] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:52:00 2017 kern.err kernel: [84662.218003] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:52:00 2017 kern.err kernel: [84662.222561] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:52:00 2017 kern.err kernel: [84662.226760] ieee80211
phy0: failed execution
Tue Jan 10 02:52:00 2017 kern.err kernel: [84662.231046] wlan0-1:
failed to remove key (0, XXX) from hardware (-5)
Tue Jan 10 02:52:04 2017 kern.err kernel: [84666.239843] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:52:04 2017 kern.err kernel: [84666.245978] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:52:04 2017 kern.err kernel: [84666.250540] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:52:04 2017 kern.err kernel: [84666.254753] ieee80211
phy0: failed execution
Tue Jan 10 02:52:04 2017 kern.err kernel: [84666.259045] wlan0-1:
failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-5)
Tue Jan 10 02:52:08 2017 kern.err kernel: [84670.267782] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:52:08 2017 kern.err kernel: [84670.273925] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:52:08 2017 kern.err kernel: [84670.278477] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:52:08 2017 kern.err kernel: [84670.282693] ieee80211
phy0: failed execution
Tue Jan 10 02:52:08 2017 kern.err kernel: [84670.286986] wlan0-1:
failed to set key (2, ff:ff:ff:ff:ff:ff) to hardware (-5)
Tue Jan 10 02:52:12 2017 kern.err kernel: [84674.293719] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:52:12 2017 kern.err kernel: [84674.299840] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:52:12 2017 kern.err kernel: [84674.304398] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:52:12 2017 kern.err kernel: [84674.308597] ieee80211
phy0: failed execution
Tue Jan 10 02:52:12 2017 kern.err kernel: [84674.312890] wlan0-1:
failed to remove key (0, XXX) from hardware (-5)
Tue Jan 10 02:52:16 2017 kern.err kernel: [84678.321664] ieee80211
phy0: cmd 0x9111=SetNewStation timed out
Tue Jan 10 02:52:16 2017 kern.err kernel: [84678.327531] ieee80211
phy0: return code: 0x1111
Tue Jan 10 02:52:16 2017 kern.err kernel: [84678.332095] ieee80211
phy0: timeout: 0x1111
Tue Jan 10 02:52:16 2017 kern.err kernel: [84678.336296] ieee80211
phy0: failed execution
Tue Jan 10 02:52:16 2017 daemon.info hostapd: wlan0: STA XXX IEEE
802.11: deauthenticated due to local deauth request
Tue Jan 10 02:52:20 2017 kern.err kernel: [84682.341609] ieee80211
phy0: cmd 0x9111=SetNewStation timed out
Tue Jan 10 02:52:20 2017 kern.err kernel: [84682.347475] ieee80211
phy0: return code: 0x1111
Tue Jan 10 02:52:20 2017 kern.err kernel: [84682.352039] ieee80211
phy0: timeout: 0x1111
Tue Jan 10 02:52:20 2017 kern.err kernel: [84682.356241] ieee80211
phy0: failed execution
Tue Jan 10 02:52:20 2017 daemon.info hostapd: wlan0: STA XXX IEEE
802.11: deauthenticated due to local deauth request
Tue Jan 10 02:52:24 2017 kern.err kernel: [84686.361552] ieee80211
phy0: cmd 0x9111=SetNewStation timed out
Tue Jan 10 02:52:24 2017 kern.err kernel: [84686.367411] ieee80211
phy0: return code: 0x1111
Tue Jan 10 02:52:24 2017 kern.err kernel: [84686.371967] ieee80211
phy0: timeout: 0x1111
Tue Jan 10 02:52:24 2017 daemon.info hostapd: wlan0-1: STA XXX IEEE
802.11: deauthenticated due to local deauth request
Tue Jan 10 02:52:24 2017 kern.err kernel: [84686.376166] ieee80211
phy0: failed execution
Tue Jan 10 02:52:28 2017 kern.err kernel: [84690.381499] ieee80211
phy0: cmd 0x9111=SetNewStation timed out
Tue Jan 10 02:52:28 2017 kern.err kernel: [84690.387365] ieee80211
phy0: return code: 0x1111
Tue Jan 10 02:52:28 2017 kern.err kernel: [84690.391931] ieee80211
phy0: timeout: 0x1111
Tue Jan 10 02:52:28 2017 kern.err kernel: [84690.396132] ieee80211
phy0: failed execution
Tue Jan 10 02:52:28 2017 daemon.info hostapd: wlan0-1: STA XXX IEEE
802.11: deauthenticated due to local deauth request
Tue Jan 10 02:52:32 2017 kern.err kernel: [84694.401442] ieee80211
phy0: cmd 0x8050=broadcast_ssid_enable timed out
Tue Jan 10 02:52:32 2017 kern.err kernel: [84694.408001] ieee80211
phy0: return code: 0x0050
Tue Jan 10 02:52:32 2017 kern.err kernel: [84694.412558] ieee80211
phy0: timeout: 0x0050
Tue Jan 10 02:52:32 2017 kern.err kernel: [84694.416757] ieee80211
phy0: failed execution
Tue Jan 10 02:52:36 2017 kern.err kernel: [84698.421386] ieee80211
phy0: cmd 0x8127=SetInformationElements timed out
Tue Jan 10 02:52:36 2017 kern.err kernel: [84698.428028] ieee80211
phy0: return code: 0x0127
Tue Jan 10 02:52:36 2017 kern.err kernel: [84698.432584] ieee80211
phy0: timeout: 0x0127
Tue Jan 10 02:52:36 2017 kern.err kernel: [84698.436783] ieee80211
phy0: failed execution
Tue Jan 10 02:52:40 2017 kern.err kernel: [84702.441331] ieee80211
phy0: cmd 0x801c=80211RadioControl timed out
Tue Jan 10 02:52:40 2017 kern.err kernel: [84702.447539] ieee80211
phy0: return code: 0x001c
Tue Jan 10 02:52:40 2017 kern.err kernel: [84702.452095] ieee80211
phy0: timeout: 0x001c
Tue Jan 10 02:52:40 2017 kern.err kernel: [84702.456293] ieee80211
phy0: failed execution
Tue Jan 10 02:52:44 2017 kern.err kernel: [84706.461275] ieee80211
phy0: cmd 0x8126=SetFixedRate timed out
Tue Jan 10 02:52:44 2017 kern.err kernel: [84706.467045] ieee80211
phy0: return code: 0x0126
Tue Jan 10 02:52:44 2017 kern.err kernel: [84706.471600] ieee80211
phy0: timeout: 0x0126
Tue Jan 10 02:52:44 2017 kern.err kernel: [84706.475799] ieee80211
phy0: failed execution
Tue Jan 10 02:52:48 2017 kern.err kernel: [84710.479234] ieee80211
phy0: cmd 0x8050=broadcast_ssid_enable timed out
Tue Jan 10 02:52:48 2017 kern.err kernel: [84710.485795] ieee80211
phy0: return code: 0x0050
Tue Jan 10 02:52:48 2017 kern.err kernel: [84710.490343] ieee80211
phy0: timeout: 0x0050
Tue Jan 10 02:52:48 2017 kern.err kernel: [84710.494550] ieee80211
phy0: failed execution
Tue Jan 10 02:52:52 2017 kern.err kernel: [84714.499178] ieee80211
phy0: cmd 0x8127=SetInformationElements timed out
Tue Jan 10 02:52:52 2017 kern.err kernel: [84714.505827] ieee80211
phy0: return code: 0x0127
Tue Jan 10 02:52:52 2017 kern.err kernel: [84714.510375] ieee80211
phy0: timeout: 0x0127
Tue Jan 10 02:52:52 2017 kern.err kernel: [84714.514581] ieee80211
phy0: failed execution
Tue Jan 10 02:52:56 2017 kern.err kernel: [84718.519124] ieee80211
phy0: cmd 0x801c=80211RadioControl timed out
Tue Jan 10 02:52:56 2017 kern.err kernel: [84718.525336] ieee80211
phy0: return code: 0x001c
Tue Jan 10 02:52:56 2017 kern.err kernel: [84718.529884] ieee80211
phy0: timeout: 0x001c
Tue Jan 10 02:52:56 2017 kern.err kernel: [84718.534090] ieee80211
phy0: failed execution
Tue Jan 10 02:53:00 2017 kern.err kernel: [84722.539068] ieee80211
phy0: cmd 0x8126=SetFixedRate timed out
Tue Jan 10 02:53:00 2017 kern.err kernel: [84722.544844] ieee80211
phy0: return code: 0x0126
Tue Jan 10 02:53:00 2017 kern.err kernel: [84722.549393] ieee80211
phy0: timeout: 0x0126
Tue Jan 10 02:53:00 2017 kern.err kernel: [84722.553600] ieee80211
phy0: failed execution

$ /etc/init.d/network restart

Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'guest' is now down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'guest' is disabled
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'lan' is now down
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.580440] br-lan: port
3(wlan0-1) entered disabled state
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.585984] br-lan: port
2(wlan0) entered disabled state
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.591366] br-lan: port
1(eth1) entered disabled state
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.599931] device eth1
left promiscuous mode
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.604343] br-lan: port
1(eth1) entered disabled state
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.616703] IPv6:
ADDRCONF(NETDEV_UP): eth1: link is not ready
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.624825] device wlan0
left promiscuous mode
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.629356] br-lan: port
2(wlan0) entered disabled state
Tue Jan 10 02:56:01 2017 daemon.info dnsmasq[1937]: read /etc/hosts -
4 addresses
Tue Jan 10 02:56:01 2017 daemon.info dnsmasq[1937]: read
/tmp/hosts/odhcpd - 0 addresses
Tue Jan 10 02:56:01 2017 daemon.info dnsmasq[1937]: read
/tmp/hosts/dhcp.cfg02411c - 2 addresses
Tue Jan 10 02:56:01 2017 daemon.info dnsmasq-dhcp[1937]: read
/etc/ethers - 0 addresses
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.635680] device
wlan0-1 left promiscuous mode
Tue Jan 10 02:56:01 2017 kern.info kernel: [84903.640390] br-lan: port
3(wlan0-1) entered disabled state
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'lan' is disabled
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Network device 'eth1'
link is down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: bridge 'br-lan' link is down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'lan' has
link connectivity loss
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'loopback' is now down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'loopback' is disabled
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Network device 'lo' link is down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'loopback'
has link connectivity loss
Tue Jan 10 02:56:01 2017 daemon.notice netifd: wan (1195): udhcpc:
received SIGTERM
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'wan' is now down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'wan' is disabled
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Network device 'eth0'
link is down
Tue Jan 10 02:56:01 2017 daemon.notice netifd: Interface 'wan' has
link connectivity loss
Tue Jan 10 02:56:02 2017 user.notice : Added device handler type: tunnel
Tue Jan 10 02:56:02 2017 user.notice : Added device handler type: Network device
Tue Jan 10 02:56:02 2017 user.notice : Added device handler type: bridge
Tue Jan 10 02:56:02 2017 user.notice : Added device handler type: macvlan
Tue Jan 10 02:56:02 2017 user.notice : Added device handler type: 8021ad
Tue Jan 10 02:56:02 2017 user.notice : Added device handler type: 8021q
Tue Jan 10 02:56:06 2017 kern.err kernel: [84908.998516] ieee80211
phy0: cmd 0x9100=BssStart timed out
Tue Jan 10 02:56:06 2017 kern.err kernel: [84909.003941] ieee80211
phy0: return code: 0x1100
Tue Jan 10 02:56:06 2017 kern.err kernel: [84909.008489] ieee80211
phy0: timeout: 0x1100
Tue Jan 10 02:56:06 2017 kern.err kernel: [84909.012699] ieee80211
phy0: failed execution
Tue Jan 10 02:56:10 2017 kern.err kernel: [84913.016474] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:56:10 2017 kern.err kernel: [84913.022619] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:56:10 2017 kern.err kernel: [84913.027171] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:56:10 2017 kern.err kernel: [84913.031386] ieee80211
phy0: failed execution
Tue Jan 10 02:56:10 2017 kern.err kernel: [84913.035675] wlan0-1:
failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-5)
Tue Jan 10 02:56:15 2017 kern.err kernel: [84917.044413] ieee80211
phy0: cmd 0x9111=SetNewStation timed out
Tue Jan 10 02:56:15 2017 kern.err kernel: [84917.050282] ieee80211
phy0: return code: 0x1111
Tue Jan 10 02:56:15 2017 kern.err kernel: [84917.054842] ieee80211
phy0: timeout: 0x1111
Tue Jan 10 02:56:15 2017 kern.err kernel: [84917.059057] ieee80211
phy0: failed execution
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'lan' is enabled
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'lan' is setting up now
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.106579] mvneta
f1070000.ethernet eth1: configuring for fixed link mode
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.113646] mvneta
f1070000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.117776] device eth1
entered promiscuous mode
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.118720] IPv6:
ADDRCONF(NETDEV_UP): br-lan: link is not ready
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'lan' is now up
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'loopback' is enabled
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'loopback' is
setting up now
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'loopback' is now up
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.132325] br-lan: port
1(eth1) entered forwarding state
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.137767] br-lan: port
1(eth1) entered forwarding state
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.144732] mvneta
f1034000.ethernet eth0: configuring for fixed link mode
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.151706] IPv6:
ADDRCONF(NETDEV_UP): eth0: link is not ready
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.157608] IPv6:
ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.164185] mvneta
f1034000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Tue Jan 10 02:56:15 2017 kern.info kernel: [84917.164225] IPv6:
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'wan' is enabled
Tue Jan 10 02:56:15 2017 daemon.notice netifd: bridge 'br-lan' link is up
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'lan' has
link connectivity
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Network device 'eth1' link is up
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Network device 'lo' link is up
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'loopback'
has link connectivity
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Network device 'eth0' link is up
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'wan' has
link connectivity
Tue Jan 10 02:56:15 2017 daemon.notice netifd: Interface 'wan' is setting up now
Tue Jan 10 02:56:15 2017 daemon.notice netifd: wan (4461): udhcpc:
started, v1.25.1
Tue Jan 10 02:56:15 2017 user.notice wondershaper: Error: Could not
find the device for network guest, aborting.
Tue Jan 10 02:56:15 2017 daemon.notice netifd: wan (4461): udhcpc:
sending discover
Tue Jan 10 02:56:15 2017 kern.debug kernel: [84917.255471] ieee80211
phy1: change: 0x40
Tue Jan 10 02:56:15 2017 user.notice firewall: Reloading firewall due
to ifup of lan (br-lan)
Tue Jan 10 02:56:15 2017 daemon.info dnsmasq[1937]: exiting on receipt
of SIGTERM
Tue Jan 10 02:56:15 2017 kern.debug kernel: [84917.345552] ieee80211
phy1: change: 0x100
Tue Jan 10 02:56:16 2017 daemon.notice netifd: wan (4461): udhcpc:
sending select for XXX
Tue Jan 10 02:56:16 2017 daemon.notice netifd: wan (4461): udhcpc:
lease of XXX obtained, lease time 7200
Tue Jan 10 02:56:17 2017 kern.info kernel: [84919.128372] br-lan: port
1(eth1) entered forwarding state
Tue Jan 10 02:56:19 2017 kern.err kernel: [84921.358346] ieee80211
phy0: cmd 0x9100=BssStart timed out
Tue Jan 10 02:56:19 2017 kern.err kernel: [84921.363771] ieee80211
phy0: return code: 0x1100
Tue Jan 10 02:56:19 2017 kern.err kernel: [84921.368320] ieee80211
phy0: timeout: 0x1100
Tue Jan 10 02:56:19 2017 kern.err kernel: [84921.372543] ieee80211
phy0: failed execution
Tue Jan 10 02:56:19 2017 kern.debug kernel: [84921.376837] ieee80211
phy0: change: 0x40
Tue Jan 10 02:56:23 2017 kern.err kernel: [84925.380295] ieee80211
phy0: cmd 0x9114=SetApMode timed out
Tue Jan 10 02:56:23 2017 kern.err kernel: [84925.385808] ieee80211
phy0: return code: 0x1114
Tue Jan 10 02:56:23 2017 kern.err kernel: [84925.390367] ieee80211
phy0: timeout: 0x1114
Tue Jan 10 02:56:23 2017 kern.err kernel: [84925.394566] ieee80211
phy0: failed execution
Tue Jan 10 02:56:27 2017 kern.err kernel: [84929.398234] ieee80211
phy0: cmd 0x8129=LinkAdaptCsMode timed out
Tue Jan 10 02:56:27 2017 kern.err kernel: [84929.404268] ieee80211
phy0: return code: 0x0129
Tue Jan 10 02:56:27 2017 kern.err kernel: [84929.408825] ieee80211
phy0: timeout: 0x0129
Tue Jan 10 02:56:27 2017 kern.err kernel: [84929.413025] ieee80211
phy0: failed execution
Tue Jan 10 02:56:31 2017 kern.err kernel: [84933.418178] ieee80211
phy0: cmd 0x8120=80211hDetectRadar timed out
Tue Jan 10 02:56:31 2017 kern.err kernel: [84933.424385] ieee80211
phy0: return code: 0x0120
Tue Jan 10 02:56:31 2017 kern.err kernel: [84933.428940] ieee80211
phy0: timeout: 0x0120
Tue Jan 10 02:56:31 2017 kern.err kernel: [84933.433139] ieee80211
phy0: failed execution
Tue Jan 10 02:56:35 2017 kern.err kernel: [84937.438123] ieee80211
phy0: cmd 0x810a=SetRfChannel timed out
Tue Jan 10 02:56:35 2017 kern.err kernel: [84937.443893] ieee80211
phy0: return code: 0x010a
Tue Jan 10 02:56:35 2017 kern.err kernel: [84937.448447] ieee80211
phy0: timeout: 0x010a
Tue Jan 10 02:56:35 2017 kern.err kernel: [84937.452645] ieee80211
phy0: failed execution
Tue Jan 10 02:56:35 2017 kern.debug kernel: [84937.456934] ieee80211
phy0: change: 0x100
Tue Jan 10 02:56:39 2017 kern.err kernel: [84941.456080] ieee80211
phy0: cmd 0x801c=80211RadioControl timed out
Tue Jan 10 02:56:39 2017 kern.err kernel: [84941.462291] ieee80211
phy0: return code: 0x001c
Tue Jan 10 02:56:39 2017 kern.err kernel: [84941.466839] ieee80211
phy0: timeout: 0x001c
Tue Jan 10 02:56:39 2017 kern.err kernel: [84941.471043] ieee80211
phy0: failed execution
Tue Jan 10 02:56:43 2017 kern.err kernel: [84945.476028] ieee80211
phy0: cmd 0x9122=UpdateEncryption timed out
Tue Jan 10 02:56:43 2017 kern.err kernel: [84945.482154] ieee80211
phy0: return code: 0x1122
Tue Jan 10 02:56:43 2017 kern.err kernel: [84945.486702] ieee80211
phy0: timeout: 0x1122
Tue Jan 10 02:56:43 2017 kern.err kernel: [84945.490907] ieee80211
phy0: failed execution
Tue Jan 10 02:56:43 2017 kern.err kernel: [84945.495194] wlan0: failed
to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-5)
Tue Jan 10 02:56:47 2017 kern.err kernel: [84949.501970] ieee80211
phy0: cmd 0x9111=SetNewStation timed out
Tue Jan 10 02:56:47 2017 kern.err kernel: [84949.507833] ieee80211
phy0: return code: 0x1111
Tue Jan 10 02:56:47 2017 kern.err kernel: [84949.512399] ieee80211
phy0: timeout: 0x1111
Tue Jan 10 02:56:47 2017 kern.err kernel: [84949.516601] ieee80211
phy0: failed execution
Tue Jan 10 02:56:51 2017 kern.err kernel: [84953.519390] ieee80211
phy0: cmd 0x801c=80211RadioControl timed out
Tue Jan 10 02:56:51 2017 kern.err kernel: [84953.525610] ieee80211
phy0: return code: 0x001c
Tue Jan 10 02:56:51 2017 kern.err kernel: [84953.530176] ieee80211
phy0: timeout: 0x001c
Tue Jan 10 02:56:51 2017 kern.err kernel: [84953.534377] ieee80211
phy0: failed execution
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: started, version
2.76 cachesize 150
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: compile time
options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua
TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: DNS service
limited to local subnets
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq-dhcp[4510]: DHCP, IP
range XXX -- XXX, lease time 12h
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: using nameserver XXX
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: using nameserver XXX
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: using nameserver XXX
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: using nameserver XXX
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: using nameserver XXX
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: using local
addresses only for domain lan
Tue Jan 10 02:56:51 2017 daemon.warn dnsmasq[4510]: no servers found
in /etc/resolv-crypt.conf, will retry
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: read /etc/hosts -
4 addresses
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: read
/tmp/hosts/dhcp.cfg02411c - 2 addresses
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq[4510]: read
/tmp/hosts/odhcpd - 0 addresses
Tue Jan 10 02:56:51 2017 daemon.info dnsmasq-dhcp[4510]: read
/etc/ethers - 0 addresses
Tue Jan 10 02:56:51 2017 daemon.notice netifd: Interface 'wan' is now up
Tue Jan 10 02:56:51 2017 daemon.notice netifd: wan (4461): Command
failed: Request timed out
Tue Jan 10 02:56:51 2017 user.notice wondershaper: Error: Could not
find the device for network guest, aborting.
Tue Jan 10 02:56:51 2017 user.notice wondershaper: Error: Could not
find the device for network guest, aborting.
Tue Jan 10 02:56:51 2017 user.notice firewall: Reloading firewall due
to ifup of wan (eth0)
Tue Jan 10 02:56:51 2017 daemon.notice netifd: radio0 (4429): command
failed: Not supported (-95)
Tue Jan 10 02:56:51 2017 daemon.notice netifd: radio1 (4430): command
failed: Not supported (-95)
Tue Jan 10 02:56:51 2017 daemon.notice netifd: radio1 (4430):
Configuration file: /var/run/hostapd-phy1.conf
Tue Jan 10 02:56:52 2017 user.notice ddns-scripts[4832]: myddns_ipv4:
PID '4832' started at 2017-01-10 02:56
Tue Jan 10 02:56:52 2017 kern.debug kernel: [84954.067918] ieee80211
phy1: change: 0xffffffff
Tue Jan 10 02:56:52 2017 user.warn ddns-scripts[4832]: myddns_ipv4:
Service section disabled! - TERMINATE
Tue Jan 10 02:56:52 2017 user.warn ddns-scripts[4832]: myddns_ipv4:
PID '4832' exit WITH ERROR '1' at 2017-01-10 02:56
Tue Jan 10 02:56:52 2017 daemon.notice netifd: radio1 (4430): wlan1:
interface state UNINITIALIZED->COUNTRY_UPDATE
Tue Jan 10 02:56:52 2017 daemon.notice netifd: radio1 (4430): Using
interface wlan1 with hwaddr XXX and ssid XXX
Tue Jan 10 02:56:52 2017 kern.info kernel: [84954.166135] IPv6:
ADDRCONF(NETDEV_UP): wlan1: link is not ready
Tue Jan 10 02:56:52 2017 daemon.notice netifd: radio0 (4429):
Configuration file: /var/run/hostapd-phy0.conf
Tue Jan 10 02:56:53 2017 daemon.info dnsmasq[4510]: exiting on receipt
of SIGTERM
Tue Jan 10 02:56:56 2017 kern.err kernel: [84958.239847] ieee80211
phy0: cmd 0x8203=SetRateAdaptationMode timed out
Tue Jan 10 02:56:56 2017 kern.err kernel: [84958.246406] ieee80211
phy0: return code: 0x0203
Tue Jan 10 02:56:56 2017 kern.err kernel: [84958.250963] ieee80211
phy0: timeout: 0x0203
Tue Jan 10 02:56:56 2017 kern.err kernel: [84958.255163] ieee80211
phy0: failed execution
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): Could
not set interface wlan0 flags (UP): I/O error
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): nl80211:
Could not set interface 'wlan0' UP
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): nl80211:
deinit ifname=wlan0 disabled_11b_rates=0
Tue Jan 10 02:56:56 2017 kern.debug kernel: [84958.259479] ieee80211
phy1: change: 0x100
Tue Jan 10 02:56:56 2017 kern.debug kernel: [84958.267843] ieee80211
phy1: change: 0x42
Tue Jan 10 02:56:56 2017 kern.info kernel: [84958.461991] IPv6:
ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio1 (4430): wlan1:
interface state COUNTRY_UPDATE->ENABLED
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio1 (4430): wlan1: AP-ENABLED
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: started, version
2.76 cachesize 150
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: compile time
options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua
TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: DNS service
limited to local subnets
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq-dhcp[4988]: DHCP, IP
range XXX -- XXX, lease time 12h
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: using nameserver XXX
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: using nameserver XXX
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: using nameserver XXX
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: using nameserver XXX
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: using nameserver XXX
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: using local
addresses only for domain lan
Tue Jan 10 02:56:56 2017 daemon.warn dnsmasq[4988]: no servers found
in /etc/resolv-crypt.conf, will retry
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: read /etc/hosts -
4 addresses
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: read
/tmp/hosts/dhcp.cfg02411c - 2 addresses
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq[4988]: read
/tmp/hosts/odhcpd - 0 addresses
Tue Jan 10 02:56:56 2017 daemon.info dnsmasq-dhcp[4988]: read
/etc/ethers - 0 addresses
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): nl80211
driver initialization failed.
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): wlan0:
interface state UNINITIALIZED->DISABLED
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): wlan0-1:
AP-DISABLED
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): wlan0-1:
CTRL-EVENT-TERMINATING
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429):
hostapd_free_hapd_data: Interface wlan0-1 wasn't started
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): wlan0: AP-DISABLED
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): wlan0:
CTRL-EVENT-TERMINATING
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429):
hostapd_free_hapd_data: Interface wlan0 wasn't started
Tue Jan 10 02:56:56 2017 daemon.notice netifd: radio0 (4429): Device
setup failed: HOSTAPD_START_FAILED
Tue Jan 10 02:56:56 2017 daemon.notice netifd: Network device 'wlan1' link is up
Tue Jan 10 02:56:56 2017 daemon.notice netifd: Interface 'guest' is enabled
Tue Jan 10 02:56:56 2017 daemon.notice netifd: Interface 'guest' is
setting up now
Tue Jan 10 02:56:56 2017 daemon.notice netifd: Interface 'guest' is now up
Tue Jan 10 02:56:56 2017 daemon.notice netifd: Interface 'guest' has
link connectivity
Tue Jan 10 02:56:56 2017 daemon.err insmod: module is already loaded -
sch_ingress
Tue Jan 10 02:56:56 2017 daemon.err insmod: module is already loaded - sch_sfq
Tue Jan 10 02:56:56 2017 daemon.err insmod: module is already loaded - sch_htb
Tue Jan 10 02:56:56 2017 daemon.err insmod: module is already loaded - cls_u32
Tue Jan 10 02:56:56 2017 daemon.err insmod: module is already loaded -
act_police
Tue Jan 10 02:56:56 2017 user.notice wondershaper: Wondershaper was
started on device wlan1.
Tue Jan 10 02:56:56 2017 user.notice firewall: Reloading firewall due
to ifup of guest (wlan1)
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[4988]: exiting on receipt
of SIGTERM
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: started, version
2.76 cachesize 150
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: compile time
options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua
TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: DNS service
limited to local subnets
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq-dhcp[5173]: DHCP, IP
range XXX -- XXX, lease time 1h
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq-dhcp[5173]: DHCP, IP
range XXX -- XXX, lease time 12h
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: using nameserver XXX
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: using nameserver XXX
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: using nameserver XXX
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: using nameserver XXX
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: using nameserver XXX
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: using local
addresses only for domain lan
Tue Jan 10 02:56:58 2017 daemon.warn dnsmasq[5173]: no servers found
in /etc/resolv-crypt.conf, will retry
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: read /etc/hosts -
4 addresses
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: read
/tmp/hosts/dhcp.cfg02411c - 2 addresses
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq[5173]: read
/tmp/hosts/odhcpd - 0 addresses
Tue Jan 10 02:56:58 2017 daemon.info dnsmasq-dhcp[5173]: read
/etc/ethers - 0 addresses
Tue Jan 10 02:57:06 2017 daemon.notice netifd: radio0 (5175): command
failed: Not supported (-95)
Tue Jan 10 02:57:06 2017 daemon.notice netifd: radio0 (5175):
Configuration file: /var/run/hostapd-phy0.conf
Tue Jan 10 02:57:10 2017 kern.err kernel: [84972.721652] ieee80211
phy0: cmd 0x8203=SetRateAdaptationMode timed out
Tue Jan 10 02:57:10 2017 kern.err kernel: [84972.728218] ieee80211
phy0: return code: 0x0203
Tue Jan 10 02:57:10 2017 kern.err kernel: [84972.732766] ieee80211
phy0: timeout: 0x0203
Tue Jan 10 02:57:10 2017 kern.err kernel: [84972.736965] ieee80211
phy0: failed execution
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): Could
not set interface wlan0 flags (UP): I/O error
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): nl80211:
Could not set interface 'wlan0' UP
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): nl80211:
deinit ifname=wlan0 disabled_11b_rates=0
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): nl80211
driver initialization failed.
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): wlan0:
interface state UNINITIALIZED->DISABLED
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): wlan0-1:
AP-DISABLED
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): wlan0-1:
CTRL-EVENT-TERMINATING
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175):
hostapd_free_hapd_data: Interface wlan0-1 wasn't started
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): wlan0: AP-DISABLED
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): wlan0:
CTRL-EVENT-TERMINATING
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175):
hostapd_free_hapd_data: Interface wlan0 wasn't started
Tue Jan 10 02:57:10 2017 daemon.info hostapd: wlan1: STA XXX IEEE
802.11: authenticated
Tue Jan 10 02:57:10 2017 daemon.notice netifd: radio0 (5175): Device
setup failed: HOSTAPD_START_FAILED
Tue Jan 10 02:57:17 2017 daemon.info hostapd: wlan1: STA XXX IEEE
802.11: authenticated
Tue Jan 10 02:57:18 2017 daemon.info hostapd: wlan1: STA XXX IEEE
802.11: associated (aid 1)
Tue Jan 10 02:57:18 2017 daemon.info hostapd: wlan1: STA XXX WPA:
pairwise key handshake completed (RSN)
Tue Jan 10 02:57:18 2017 daemon.info dnsmasq-dhcp[5173]: DHCPDISCOVER(wlan1) XXX
Tue Jan 10 02:57:18 2017 daemon.info dnsmasq-dhcp[5173]: DHCPOFFER(wlan1) XXX
Tue Jan 10 02:57:18 2017 daemon.info dnsmasq-dhcp[5173]: DHCPREQUEST(wlan1) XXX
Tue Jan 10 02:57:18 2017 daemon.info dnsmasq-dhcp[5173]: DHCPACK(wlan1) XXX
Tue Jan 10 02:57:26 2017 daemon.info hostapd: wlan1: STA XXX IEEE
802.11: authenticated
Tue Jan 10 02:57:27 2017 daemon.info hostapd: wlan1: STA XXX IEEE
802.11: associated (aid 2)
Tue Jan 10 02:57:27 2017 daemon.info hostapd: wlan1: STA XXX WPA:
pairwise key handshake completed (RSN)
Tue Jan 10 02:57:27 2017 daemon.info dnsmasq-dhcp[5173]: DHCPREQUEST(wlan1) XXX
Tue Jan 10 02:57:27 2017 daemon.info dnsmasq-dhcp[5173]: DHCPACK(wlan1) XXX