#22008: Wifi connection dropping randomly
--------------------------+---------------------------------------
Reporter: Mushoz | Owner: developers
Type: defect | Status: new
Priority: normal | Milestone: Designated Driver (Trunk)
Component: base system | Version: Trunk
Resolution: | Keywords:
--------------------------+---------------------------------------
Comment (by Mushoz):
Sorry for the late response. I had to wait for the issue to crop up again
to capture the logs during a hang. This is the kernel log as the wifi was
frozen (I accessed the router via ethernet):
{{{
[ 0.000000] Linux version 4.1.16 (openwrt@gb-10) (gcc version 5.3.0
(OpenWrt GCC 5.3.0 r48973) ) #1 Wed Mar 9 15:18:53 UTC 2016
[ 0.000000] MyLoader: sysp=e19624a7, boardp=ee8721a4, parts=64c787b7
[ 0.000000] bootconsole [early0] enabled
[ 0.000000] CPU0 revision is: 00019750 (MIPS 74Kc)
[ 0.000000] SoC: Qualcomm Atheros QCA9558 ver 1 rev 0
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 04000000 @ 00000000 (usable)
[ 0.000000] Initrd not found or empty - disabling initrd
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000000000000-0x0000000003ffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x0000000003ffffff]
[ 0.000000] Initmem setup node 0 [mem
0x0000000000000000-0x0000000003ffffff]
[ 0.000000] On node 0 totalpages: 16384
[ 0.000000] free_area_init_node: node 0, pgdat 803f1fc0, node_mem_map
81000000
[ 0.000000] Normal zone: 128 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 16384 pages, LIFO batch:3
[ 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: 16256
[ 0.000000] Kernel command line: board=TL-WR1043ND-v2
console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[ 0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768
bytes)
[ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384
bytes)
[ 0.000000] Writing ErrCtl register=00000000
[ 0.000000] Readback ErrCtl register=00000000
[ 0.000000] Memory: 60292K/65536K available (2914K kernel code, 143K
rwdata, 708K rodata, 256K init, 200K bss, 5244K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:83
[ 0.000000] Clocks: CPU:720.000MHz, DDR:600.000MHz, AHB:200.000MHz,
Ref:40.000MHz
[ 0.000000] clocksource MIPS: mask: 0xffffffff max_cycles: 0xffffffff,
max_idle_ns: 5309056796 ns
[ 0.000007] sched_clock: 32 bits at 360MHz, resolution 2ns, wraps every
5965232126ns
[ 0.007506] Calibrating delay loop... 358.80 BogoMIPS (lpj=1794048)
[ 0.070069] pid_max: default: 32768 minimum: 301
[ 0.074625] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.080952] Mountpoint-cache hash table entries: 1024 (order: 0, 4096
bytes)
[ 0.090015] clocksource jiffies: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.100397] NET: Registered protocol family 16
[ 0.105758] MIPS: machine is TP-LINK TL-WR1043ND v2
[ 0.559340] Switched to clocksource MIPS
[ 0.564187] NET: Registered protocol family 2
[ 0.569136] TCP established hash table entries: 1024 (order: 0, 4096
bytes)
[ 0.575847] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.581959] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.588142] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.593751] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.600018] NET: Registered protocol family 1
[ 0.604217] PCI: CLS 0 bytes, default 32
[ 0.605044] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.625384] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.631001] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME)
(CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.642366] io scheduler noop registered
[ 0.646090] io scheduler deadline registered (default)
[ 0.651318] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[ 0.659756] console [ttyS0] disabled
[ 0.683207] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud
= 2500000) is a 16550A
[ 0.691487] console [ttyS0] enabled
[ 0.698506] bootconsole [early0] disabled
[ 0.710721] m25p80 spi0.0: found s25fl064k, expected m25p80
[ 0.716387] m25p80 spi0.0: s25fl064k (8192 Kbytes)
[ 0.722347] 5 tp-link partitions found on MTD device spi0.0
[ 0.728005] Creating 5 MTD partitions on "spi0.0":
[ 0.732907] 0x000000000000-0x000000020000 : "u-boot"
[ 0.738670] 0x000000020000-0x00000015c190 : "kernel"
[ 0.744400] 0x00000015c190-0x0000007f0000 : "rootfs"
[ 0.750134] mtd: device 2 (rootfs) set to be root filesystem
[ 0.755921] 1 squashfs-split partitions found on MTD device rootfs
[ 0.762234] 0x000000340000-0x0000007f0000 : "rootfs_data"
[ 0.768377] 0x0000007f0000-0x000000800000 : "art"
[ 0.773868] 0x000000020000-0x0000007f0000 : "firmware"
[ 0.793705] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-
mdio.0
[ 0.888286] libphy: ag71xx_mdio: probed
[ 1.480848] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00
[uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[ 1.492203] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[ 2.080968] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:SGMII
[ 2.088885] NET: Registered protocol family 10
[ 2.096515] NET: Registered protocol family 17
[ 2.101143] bridge: automatic filtering via arp/ip/ip6tables has been
deprecated. Update your scripts to load br_netfilter if you need this.
[ 2.113993] Bridge firewalling registered
[ 2.118183] 8021q: 802.1Q VLAN Support v1.8
[ 2.130068] VFS: Mounted root (squashfs filesystem) readonly on device
31:2.
[ 2.138596] Freeing unused kernel memory: 256K (80410000 - 80450000)
[ 3.157586] init: Console is alive
[ 3.161278] init: - watchdog -
[ 4.141273] usbcore: registered new interface driver usbfs
[ 4.146909] usbcore: registered new interface driver hub
[ 4.152426] usbcore: registered new device driver usb
[ 4.162167] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 4.170117] ehci-platform: EHCI generic platform driver
[ 4.175503] ehci-platform ehci-platform.0: EHCI Host Controller
[ 4.181577] ehci-platform ehci-platform.0: new USB bus registered,
assigned bus number 1
[ 4.191865] ehci-platform ehci-platform.0: TX-TX IDP fix enabled
[ 4.197966] ehci-platform ehci-platform.0: irq 48, io mem 0x1b000000
[ 4.219373] ehci-platform ehci-platform.0: USB 2.0 started, EHCI 1.00
[ 4.226636] hub 1-0:1.0: USB hub found
[ 4.230747] hub 1-0:1.0: 1 port detected
[ 4.235054] ehci-platform ehci-platform.1: EHCI Host Controller
[ 4.241134] ehci-platform ehci-platform.1: new USB bus registered,
assigned bus number 2
[ 4.251450] ehci-platform ehci-platform.1: TX-TX IDP fix enabled
[ 4.257560] ehci-platform ehci-platform.1: irq 49, io mem 0x1b400000
[ 4.279363] ehci-platform ehci-platform.1: USB 2.0 started, EHCI 1.00
[ 4.286630] hub 2-0:1.0: USB hub found
[ 4.290756] hub 2-0:1.0: 1 port detected
[ 4.297251] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 4.304715] ohci-platform: OHCI generic platform driver
[ 5.169190] init: - preinit -
[ 5.713057] eth1: link up (1000Mbps/Full duplex)
[ 5.735352] random: procd urandom read with 9 bits of entropy available
[ 7.835207] mount_root: no usable overlay filesystem found, using tmpfs
overlay
[ 7.863955] eth1: link down
[ 7.875769] procd: - early -
[ 7.878779] procd: - watchdog -
[ 8.501733] procd: - ubus -
[ 9.508490] procd: - init -
[ 10.166158] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 10.183846] Loading modules backported from Linux version
v4.4-rc5-1913-gc8fdf68
[ 10.191400] Backport generated by backports.git
backports-20151218-0-g2f58d9d
[ 10.201544] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 10.216048] nf_conntrack version 0.5.0 (946 buckets, 3784 max)
[ 10.250117] xt_time: kernel timezone is -0000
[ 10.305515] PPP generic driver version 2.4.2
[ 10.312106] NET: Registered protocol family 24
[ 10.355710] ath: EEPROM regdomain: 0x0
[ 10.355728] ath: EEPROM indicates default country code should be used
[ 10.355736] ath: doing EEPROM country->regdmn map search
[ 10.355753] ath: country maps to regdmn code: 0x3a
[ 10.355761] ath: Country alpha2 being used: US
[ 10.355768] ath: Regpair used: 0x3a
[ 10.370263] ieee80211 phy0: Selected rate control algorithm
'minstrel_ht'
[ 10.373256] ieee80211 phy0: Atheros AR9550 Rev:0 mem=0xb8100000, irq=47
[ 16.319825] jffs2: notice: (964) jffs2_build_xattr_subsystem: complete
building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of
xref (0 dead, 0 orphan) found.
[ 19.351631] eth1: link up (1000Mbps/Full duplex)
[ 19.360892] device eth1 entered promiscuous mode
[ 19.383989] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[ 19.455083] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 20.339461] br-lan: port 1(eth1) entered forwarding state
[ 20.344984] br-lan: port 1(eth1) entered forwarding state
[ 20.389729] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 20.810809] eth0: link up (1000Mbps/Full duplex)
[ 20.815633] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 22.339356] br-lan: port 1(eth1) entered forwarding state
[ 22.811075] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is up
[ 22.818175] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 5 is up
[ 50.733832] random: nonblocking pool is initialized
[ 446.087159] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 446.133438] device wlan0 entered promiscuous mode
[ 446.183344] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 446.190073] br-lan: port 2(wlan0) entered forwarding state
[ 446.195674] br-lan: port 2(wlan0) entered forwarding state
[ 448.188621] br-lan: port 2(wlan0) entered forwarding state
[ 484.890329] device wlan0 left promiscuous mode
[ 484.895000] br-lan: port 2(wlan0) entered disabled state
[ 591.522640] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 591.570729] device wlan0 entered promiscuous mode
[ 591.940650] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 591.947250] br-lan: port 2(wlan0) entered forwarding state
[ 591.952895] br-lan: port 2(wlan0) entered forwarding state
[ 593.947887] br-lan: port 2(wlan0) entered forwarding state
[ 772.059604] device wlan0 left promiscuous mode
[ 772.064230] br-lan: port 2(wlan0) entered disabled state
[ 773.537044] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 773.582110] device wlan0 entered promiscuous mode
[ 773.943664] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 773.950325] br-lan: port 2(wlan0) entered forwarding state
[ 773.955939] br-lan: port 2(wlan0) entered forwarding state
[ 775.946832] br-lan: port 2(wlan0) entered forwarding state
[ 802.018944] device wlan0 left promiscuous mode
[ 802.023534] br-lan: port 2(wlan0) entered disabled state
[ 803.329044] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 803.453102] device wlan0 entered promiscuous mode
[ 804.443393] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 804.450099] br-lan: port 2(wlan0) entered forwarding state
[ 804.455700] br-lan: port 2(wlan0) entered forwarding state
[ 806.446566] br-lan: port 2(wlan0) entered forwarding state
[ 839.386348] device wlan0 left promiscuous mode
[ 839.390983] br-lan: port 2(wlan0) entered disabled state
[ 840.680580] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 840.808678] device wlan0 entered promiscuous mode
[ 841.843588] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 841.850197] br-lan: port 2(wlan0) entered forwarding state
[ 841.855815] br-lan: port 2(wlan0) entered forwarding state
[ 843.846407] br-lan: port 2(wlan0) entered forwarding state
[ 861.330716] device wlan0 left promiscuous mode
[ 861.335340] br-lan: port 2(wlan0) entered disabled state
[ 863.473111] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 863.546169] device wlan0 entered promiscuous mode
[ 863.899102] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 863.905749] br-lan: port 2(wlan0) entered forwarding state
[ 863.911410] br-lan: port 2(wlan0) entered forwarding state
[ 865.906312] br-lan: port 2(wlan0) entered forwarding state
[ 942.648010] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is
down
[34638.908057] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is up
[35072.483261] device wlan0 left promiscuous mode
[35072.488009] br-lan: port 2(wlan0) entered disabled state
[35072.949230] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[35072.997567] device wlan0 entered promiscuous mode
[35073.002465] br-lan: port 2(wlan0) entered forwarding state
[35073.008125] br-lan: port 2(wlan0) entered forwarding state
[35073.363231] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[35075.002728] br-lan: port 2(wlan0) entered forwarding state
[35093.164963] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is
down
[367606.379922] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is
up
[367787.299189] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is
down
[1058126.502012] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is
up
}}}
This was the state of the system log as the problem occurred. It crashed
at around 17:49. Notice the massive number of deauthentications:
{{{
Tue Mar 22 17:40:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:40:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:40:58 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:41:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:41:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:41:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:41:14 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:41:46 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:42:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:42:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:42:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:42:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:42:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:42:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:42:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:42:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:42:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:43:15 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:43:15 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:43:16 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:43:18 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:43:20 2016 daemon.info dnsmasq-dhcp[1192]: DHCPREQUEST(br-
lan) 192.168.1.141 b4:ae:2b:08:db:b1
Tue Mar 22 17:43:20 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.141 b4:ae:2b:08:db:b1 XboxOne
Tue Mar 22 17:43:20 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:43:21 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:43:24 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:43:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:43:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:43:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:43:38 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:43:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:43:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:43:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:43:58 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:44:32 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:45:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:45:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:45:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:45:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:45:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:45:34 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:45:56 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:45:56 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:46:02 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:46:02 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:46:02 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:46:02 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:46:06 2016 daemon.info dnsmasq-dhcp[1192]: DHCPREQUEST(br-
lan) 192.168.1.141 b4:ae:2b:08:db:b1
Tue Mar 22 17:46:06 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.141 b4:ae:2b:08:db:b1 XboxOne
Tue Mar 22 17:46:07 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:46:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:46:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:46:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:46:16 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:46:28 2016 daemon.info hostapd: wlan0: STA cc:08:e0:99:24:6e
IEEE 802.11: authenticated
Tue Mar 22 17:46:28 2016 daemon.info hostapd: wlan0: STA cc:08:e0:99:24:6e
IEEE 802.11: associated (aid 12)
Tue Mar 22 17:46:28 2016 daemon.info hostapd: wlan0: STA cc:08:e0:99:24:6e
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:46:31 2016 daemon.info dnsmasq-dhcp[1192]: DHCPDISCOVER(br-
lan) cc:08:e0:99:24:6e
Tue Mar 22 17:46:31 2016 daemon.info dnsmasq-dhcp[1192]: DHCPOFFER(br-lan)
192.168.1.106 cc:08:e0:99:24:6e
Tue Mar 22 17:46:31 2016 daemon.info dnsmasq-dhcp[1192]: DHCPDISCOVER(br-
lan) cc:08:e0:99:24:6e
Tue Mar 22 17:46:31 2016 daemon.info dnsmasq-dhcp[1192]: DHCPOFFER(br-lan)
192.168.1.106 cc:08:e0:99:24:6e
Tue Mar 22 17:46:32 2016 daemon.info dnsmasq-dhcp[1192]: DHCPREQUEST(br-
lan) 192.168.1.106 cc:08:e0:99:24:6e
Tue Mar 22 17:46:32 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.106 cc:08:e0:99:24:6e iPhone-van-Auke
Tue Mar 22 17:46:32 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:47:05 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:47:09 2016 daemon.info hostapd: wlan0: STA cc:25:ef:08:97:c2
IEEE 802.11: authenticated
Tue Mar 22 17:47:09 2016 daemon.info hostapd: wlan0: STA cc:25:ef:08:97:c2
IEEE 802.11: associated (aid 14)
Tue Mar 22 17:47:09 2016 daemon.info hostapd: wlan0: STA cc:25:ef:08:97:c2
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:47:10 2016 daemon.info dnsmasq-dhcp[1192]: DHCPREQUEST(br-
lan) 192.168.1.162 cc:25:ef:08:97:c2
Tue Mar 22 17:47:10 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.162 cc:25:ef:08:97:c2 iPhone-van-Auke
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA b8:ee:65:a2:75:33
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA c4:8e:8f:9a:48:45
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA 68:7f:74:bb:2b:8f
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA cc:25:ef:08:97:c2
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA 54:60:09:0b:b2:84
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA 34:68:95:1d:66:2c
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA 90:b9:31:ee:fa:2d
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA a4:c3:61:6e:85:5e
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA 38:94:96:98:36:4f
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:48 2016 daemon.info hostapd: wlan0: STA 64:76:ba:79:d9:5a
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:49 2016 daemon.info hostapd: wlan0: STA cc:08:e0:99:24:6e
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:49 2016 daemon.info hostapd: wlan0: STA c0:bd:d1:d4:2c:26
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:51 2016 daemon.info hostapd: wlan0: STA e8:50:8b:8b:9c:fd
WPA: group key handshake completed (RSN)
Tue Mar 22 17:47:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:47:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:47:54 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:48:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:48:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:48:14 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:48:25 2016 daemon.info dnsmasq-dhcp[1192]: DHCPINFORM(br-
lan) 192.168.1.103 68:7f:74:bb:2b:8f
Tue Mar 22 17:48:25 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.103 68:7f:74:bb:2b:8f Koen-PC
Tue Mar 22 17:48:36 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:48:36 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:48:43 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:48:43 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:48:43 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:48:43 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:48:43 2016 daemon.info dnsmasq-dhcp[1192]: DHCPREQUEST(br-
lan) 192.168.1.141 b4:ae:2b:08:db:b1
Tue Mar 22 17:48:43 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.141 b4:ae:2b:08:db:b1 XboxOne
Tue Mar 22 17:48:45 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:48:55 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:48:55 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:49:00 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:49:00 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:49:06 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:49:06 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:49:06 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:49:15 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: authenticated
Tue Mar 22 17:49:15 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: associated (aid 5)
Tue Mar 22 17:49:15 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
WPA: pairwise key handshake completed (RSN)
Tue Mar 22 17:49:19 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011b96507ab4ae2b08dbaf on br-lan: ok fd2c:9bee:2160::22c/128
Tue Mar 22 17:49:22 2016 daemon.info hostapd: wlan0: STA b8:ee:65:a2:75:33
IEEE 802.11: disassociated
Tue Mar 22 17:49:23 2016 daemon.info hostapd: wlan0: STA b8:ee:65:a2:75:33
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:53:30 2016 daemon.info hostapd: wlan0: STA 90:b9:31:ee:fa:2d
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:53:31 2016 daemon.info hostapd: wlan0: STA 90:b9:31:ee:fa:2d
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:11 2016 daemon.info hostapd: wlan0: STA a4:c3:61:6e:85:5e
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:12 2016 daemon.info hostapd: wlan0: STA a4:c3:61:6e:85:5e
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:27 2016 daemon.info hostapd: wlan0: STA 64:76:ba:79:d9:5a
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:28 2016 daemon.info hostapd: wlan0: STA 64:76:ba:79:d9:5a
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:30 2016 daemon.info hostapd: wlan0: STA c4:8e:8f:9a:48:45
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:31 2016 daemon.info hostapd: wlan0: STA c4:8e:8f:9a:48:45
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:35 2016 daemon.info hostapd: wlan0: STA 38:94:96:98:36:4f
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:36 2016 daemon.info hostapd: wlan0: STA 38:94:96:98:36:4f
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:37 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:38 2016 daemon.info hostapd: wlan0: STA b4:ae:2b:08:db:b1
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:39 2016 daemon.info hostapd: wlan0: STA 34:68:95:1d:66:2c
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:40 2016 daemon.info hostapd: wlan0: STA 54:60:09:0b:b2:84
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:40 2016 daemon.info hostapd: wlan0: STA 34:68:95:1d:66:2c
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:41 2016 daemon.info hostapd: wlan0: STA 54:60:09:0b:b2:84
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:43 2016 daemon.info hostapd: wlan0: STA cc:08:e0:99:24:6e
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:43 2016 daemon.info hostapd: wlan0: STA c0:bd:d1:d4:2c:26
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:44 2016 daemon.info hostapd: wlan0: STA cc:08:e0:99:24:6e
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:44 2016 daemon.info hostapd: wlan0: STA c0:bd:d1:d4:2c:26
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:47 2016 daemon.info hostapd: wlan0: STA e8:50:8b:8b:9c:fd
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:48 2016 daemon.info hostapd: wlan0: STA e8:50:8b:8b:9c:fd
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:48 2016 daemon.info hostapd: wlan0: STA cc:25:ef:08:97:c2
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:49 2016 daemon.info hostapd: wlan0: STA cc:25:ef:08:97:c2
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:54:49 2016 daemon.info hostapd: wlan0: STA 68:7f:74:bb:2b:8f
IEEE 802.11: disassociated due to inactivity
Tue Mar 22 17:54:50 2016 daemon.info hostapd: wlan0: STA 68:7f:74:bb:2b:8f
IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Tue Mar 22 17:58:42 2016 daemon.info dnsmasq-dhcp[1192]: DHCPDISCOVER(br-
lan) 192.168.1.219 1c:b7:2c:5e:07:96
Tue Mar 22 17:58:42 2016 daemon.info dnsmasq-dhcp[1192]: DHCPOFFER(br-lan)
192.168.1.219 1c:b7:2c:5e:07:96
Tue Mar 22 17:58:42 2016 daemon.info dnsmasq-dhcp[1192]: DHCPREQUEST(br-
lan) 192.168.1.219 1c:b7:2c:5e:07:96
Tue Mar 22 17:58:42 2016 daemon.info dnsmasq-dhcp[1192]: DHCPACK(br-lan)
192.168.1.219 1c:b7:2c:5e:07:96 Jaap-Laptop
Tue Mar 22 17:58:42 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011de950e21cb72c5e0796 on br-lan: ok fd2c:9bee:2160::81d/128
Tue Mar 22 17:58:43 2016 daemon.warn odhcpd[937]: DHCPV6 SOLICIT IA_NA
from 000100011de950e21cb72c5e0796 on br-lan: ok fd2c:9bee:2160::81d/128
Tue Mar 22 17:58:43 2016 kern.info kernel: [1058126.502012] Atheros
AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is up
Tue Mar 22 17:58:44 2016 daemon.warn odhcpd[937]: DHCPV6 REQUEST IA_NA
from 000100011de950e21cb72c5e0796 on br-lan: ok fd2c:9bee:2160::81d/128
Tue Mar 22 17:58:44 2016 daemon.info dnsmasq[1192]: read /etc/hosts - 4
addresses
Tue Mar 22 17:58:44 2016 daemon.info dnsmasq[1192]: read /tmp/hosts/dhcp -
2 addresses
Tue Mar 22 17:58:44 2016 daemon.info dnsmasq[1192]: read /tmp/hosts/odhcpd
- 4 addresses
Tue Mar 22 17:58:44 2016 daemon.info dnsmasq-dhcp[1192]: read /etc/ethers
- 0 addresses
}}}
And last but not least, the ath9k driver reset log:
{{{
root@OpenWrt:~# cat /sys/kernel/debug/ieee80211/phy0/ath9k/reset
Baseband Hang: 0
Baseband Watchdog: 842
Fatal HW Error: 0
TX HW error: 0
Transmit timeout: 0
TX Path Hang: 1
PLL RX Hang: 0
MAC Hang: 0
Stuck Beacon: 139
MCI Reset: 0
Calibration error: 1
Tx DMA stop error: 140
Rx DMA stop error: 0
}}}
Hopefully this information is useful for diagnosing the issue :)!
--
Ticket URL: <https://dev.openwrt.org/ticket/22008#comment:2>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
