#19531: 4 WAY HANDSHAKE TIMEOUT
-----------------------+-----------------------------------
Reporter: hutamanzi | Owner: developers
Type: defect | Status: new
Priority: highest | Milestone:
Component: kernel | Version: Barrier Breaker 14.07
Keywords: |
-----------------------+-----------------------------------
After I use TP-Link Archer C5 (hw upgrade from wdr4300) to connect to
organtization wlan (802.1x encryption). The Internet Connection always
gets disconnections, the Connection lost took some minutes (10-30 Min),
THEN I just got log said:
ath1: deauthenticated from wlan0: deauthenticated from aa:bb:cc:dd:ee:ff
(Reason: 15=4WAY_HANDSHAKE_TIMEOUT) (Reason: 15=4WAY_HANDSHAKE_TIMEOUT)
THEN my archer tried to connect again and it was successfully connected. I
should either wait long time or restart to get the Internet Connection
back!
* this happened every specific time interfall (after 7 or 8 hours from the
last successfull connection)
No more Information in kernel logs give me more Detail about this
Problem. . I thought It is because there was no Response from my tp link
for 4 way handshake initiated by the AP
I use:
AC-Band
Client mode (STA)
Encryption: 802.1x (WPA-2 Enterprise CCMP only, PEAP, auth="MSCHAPV2")
*The Problem happens just for wlan ac, the 2,4 ghz is fine.
kernel logs:
{{{
[ 0.000000] Linux version 3.10.49 (bb@builder1) (gcc version 4.8.3
(OpenWrt/Linaro GCC 4.8-2014.04 r42625) ) #3 Wed Oct 1 14:00:51 CEST 2014
[ 0.000000] MyLoader: sysp=01003c5c, boardp=00c004c2, parts=00202849
[ 0.000000] bootconsole [early0] enabled
[ 0.000000] CPU revision is: 00019750 (MIPS 74Kc)
[ 0.000000] SoC: Qualcomm Atheros QCA9558 rev 0
[ 0.000000] Clocks: CPU:720.000MHz, DDR:600.000MHz, AHB:200.000MHz,
Ref:40.000MHz
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 08000000 @ 00000000 (usable)
[ 0.000000] Initrd not found or empty - disabling initrd
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x00000000-0x07ffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x00000000-0x07ffffff]
[ 0.000000] On node 0 totalpages: 32768
[ 0.000000] free_area_init_node: node 0, pgdat 80334b60, node_mem_map
81000000
[ 0.000000] Normal zone: 256 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 32768 pages, LIFO batch:7
[ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32
bytes.
[ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases,
linesize 32 bytes
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on.
Total pages: 32512
[ 0.000000] Kernel command line: board=ARCHER-C5 console=ttyS0,115200
rootfstype=squashfs,jffs2 noinitrd
[ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536
bytes)
[ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768
bytes)
[ 0.000000] Writing ErrCtl register=00000000
[ 0.000000] Readback ErrCtl register=00000000
[ 0.000000] Memory: 126052k/131072k available (2369k kernel code, 5020k
reserved, 621k data, 272k init, 0k highmem)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:51
[ 0.060000] Calibrating delay loop... 358.80 BogoMIPS (lpj=1794048)
[ 0.060000] pid_max: default: 32768 minimum: 301
[ 0.060000] Mount-cache hash table entries: 512
[ 0.070000] NET: Registered protocol family 16
[ 0.070000] MIPS: machine is TP-LINK Archer C5
[ 0.080000] ar724x-pci ar724x-pci.0: PCIe link is down
[ 0.080000] registering PCI controller with io_map_base unset
[ 0.090000] registering PCI controller with io_map_base unset
[ 0.500000] bio: create slab <bio-0> at 0
[ 0.500000] PCI host bridge to bus 0000:00
[ 0.510000] pci_bus 0000:00: root bus resource [mem
0x10000000-0x11ffffff]
[ 0.510000] pci_bus 0000:00: root bus resource [io 0x0000]
[ 0.520000] pci_bus 0000:00: No busn resource found for root bus, will
use [bus 00-ff]
[ 0.520000] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[ 0.520000] PCI host bridge to bus 0000:01
[ 0.530000] pci_bus 0000:01: root bus resource [mem
0x12000000-0x13ffffff]
[ 0.530000] pci_bus 0000:01: root bus resource [io 0x0001]
[ 0.540000] pci_bus 0000:01: No busn resource found for root bus, will
use [bus 01-ff]
[ 0.540000] pci 0000:01:00.0: [168c:003c] type 00 class 0x028000
[ 0.540000] pci 0000:01:00.0: reg 10: [mem 0x00000000-0x001fffff 64bit]
[ 0.540000] pci 0000:01:00.0: reg 30: [mem 0x00000000-0x0000ffff pref]
[ 0.540000] pci 0000:01:00.0: supports D1 D2
[ 0.540000] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[ 0.540000] pci 0000:01:00.0: BAR 0: assigned [mem
0x12000000-0x121fffff 64bit]
[ 0.550000] pci 0000:01:00.0: BAR 6: assigned [mem
0x12200000-0x1220ffff pref]
[ 0.550000] pci 0000:01:00.0: using irq 41 for pin 1
[ 0.560000] Switching to clocksource MIPS
[ 0.560000] NET: Registered protocol family 2
[ 0.570000] TCP established hash table entries: 1024 (order: 1, 8192
bytes)
[ 0.570000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.570000] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.580000] TCP: reno registered
[ 0.580000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.590000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.600000] NET: Registered protocol family 1
[ 0.600000] PCI: CLS 0 bytes, default 32
[ 0.610000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.620000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME)
(CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.630000] msgmni has been set to 246
[ 0.630000] io scheduler noop registered
[ 0.640000] io scheduler deadline registered (default)
[ 0.640000] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[ 0.670000] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11) is a
16550A
[ 0.680000] console [ttyS0] enabled, bootconsole disabled
[ 0.690000] ath79-spi ath79-spi: master is unqueued, this is deprecated
[ 0.700000] m25p80 spi0.0: found s25fl129p1, expected m25p80
[ 0.710000] m25p80 spi0.0: s25fl129p1 (16384 Kbytes)
[ 0.710000] 5 tp-link partitions found on MTD device spi0.0
[ 0.720000] Creating 5 MTD partitions on "spi0.0":
[ 0.720000] 0x000000000000-0x000000020000 : "u-boot"
[ 0.730000] 0x000000020000-0x00000012e664 : "kernel"
[ 0.730000] mtd: partition "kernel" must either start or end on erase
block boundary or be smaller than an erase block -- forcing read-only
[ 0.750000] 0x00000012e664-0x000000ff0000 : "rootfs"
[ 0.750000] mtd: partition "rootfs" must either start or end on erase
block boundary or be smaller than an erase block -- forcing read-only
[ 0.770000] mtd: device 2 (rootfs) set to be root filesystem
[ 0.770000] 1 squashfs-split partitions found on MTD device rootfs
[ 0.780000] 0x000000390000-0x000000ff0000 : "rootfs_data"
[ 0.780000] 0x000000ff0000-0x000001000000 : "art"
[ 0.790000] 0x000000020000-0x000000ff0000 : "firmware"
[ 0.830000] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-
mdio.0
[ 2.070000] libphy: ag71xx_mdio: probed
[ 2.620000] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00
[uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[ 2.640000] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[ 3.190000] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:SGMII
[ 3.200000] TCP: cubic registered
[ 3.210000] NET: Registered protocol family 17
[ 3.210000] Bridge firewalling registered
[ 3.210000] 8021q: 802.1Q VLAN Support v1.8
[ 3.230000] VFS: Mounted root (squashfs filesystem) readonly on device
31:2.
[ 3.230000] Freeing unused kernel memory: 272K (8034c000 - 80390000)
[ 5.770000] usbcore: registered new interface driver usbfs
[ 5.780000] usbcore: registered new interface driver hub
[ 5.780000] usbcore: registered new device driver usb
[ 5.790000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 5.800000] ehci-platform: EHCI generic platform driver
[ 5.800000] ehci-platform ehci-platform.0: EHCI Host Controller
[ 5.810000] ehci-platform ehci-platform.0: new USB bus registered,
assigned bus number 1
[ 5.820000] ehci-platform ehci-platform.0: TX-TX IDP fix enabled
[ 5.830000] ehci-platform ehci-platform.0: irq 48, io mem 0x1b000000
[ 5.850000] ehci-platform ehci-platform.0: USB 2.0 started, EHCI 1.00
[ 5.850000] hub 1-0:1.0: USB hub found
[ 5.860000] hub 1-0:1.0: 1 port detected
[ 5.860000] ehci-platform ehci-platform.1: EHCI Host Controller
[ 5.870000] ehci-platform ehci-platform.1: new USB bus registered,
assigned bus number 2
[ 5.880000] ehci-platform ehci-platform.1: TX-TX IDP fix enabled
[ 5.880000] ehci-platform ehci-platform.1: irq 49, io mem 0x1b400000
[ 5.910000] ehci-platform ehci-platform.1: USB 2.0 started, EHCI 1.00
[ 5.910000] hub 2-0:1.0: USB hub found
[ 5.920000] hub 2-0:1.0: 1 port detected
[ 6.360000] eth1: link up (1000Mbps/Full duplex)
[ 9.690000] jffs2: notice: (361) jffs2_build_xattr_subsystem: complete
building xattr subsystem, 1 of xdatum (1 unchecked, 0 orphan) and 42 of
xref (0 dead, 26 orphan) found.
[ 9.780000] eth1: link down
[ 11.510000] NET: Registered protocol family 10
[ 11.530000] nf_conntrack version 0.5.0 (1973 buckets, 7892 max)
[ 11.540000] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 11.550000] Loading modules backported from Linux version
master-2014-05-22-0-gf2032ea
[ 11.560000] Backport generated by backports.git
backports-20140320-37-g5c33da0
[ 11.580000] cfg80211: Calling CRDA to update world regulatory domain
[ 11.580000] cfg80211: World regulatory domain updated:
[ 11.590000] cfg80211: DFS Master region: unset
[ 11.590000] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[ 11.600000] cfg80211: (2400000 KHz - 2483000 KHz @ 40000 KHz), (N/A,
3000 mBm), (N/A)
[ 11.610000] cfg80211: (5140000 KHz - 5860000 KHz @ 160000 KHz), (N/A,
3000 mBm), (N/A)
[ 11.650000] ath10k_pci 0000:01:00.0: BAR 0: assigned [mem
0x12000000-0x121fffff 64bit]
[ 11.660000] PCI: Enabling device 0000:01:00.0 (0000 -> 0002)
[ 11.970000] ath10k: pci irq legacy irq_mode 0 reset_mode 0
[ 12.060000] ath10k: otp stream is empty, using board.bin contents
[ 13.110000] ath10k: qca988x hw2.0 (0x4100016c, 0x043202ff) fw
10.1.467.2-1 api 2 htt 2.1
[ 13.640000] ath: EEPROM regdomain: 0x0
[ 13.640000] ath: EEPROM indicates default country code should be used
[ 13.640000] ath: doing EEPROM country->regdmn map search
[ 13.640000] ath: country maps to regdmn code: 0x3a
[ 13.640000] ath: Country alpha2 being used: US
[ 13.640000] ath: Regpair used: 0x3a
[ 13.650000] cfg80211: Calling CRDA for country: US
[ 13.660000] cfg80211: Regulatory domain changed to country: US
[ 13.660000] cfg80211: DFS Master region: unset
[ 13.670000] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[ 13.680000] cfg80211: (2400000 KHz - 2483000 KHz @ 40000 KHz), (N/A,
3000 mBm), (N/A)
[ 13.680000] cfg80211: (5140000 KHz - 5860000 KHz @ 160000 KHz), (N/A,
3000 mBm), (N/A)
[ 13.710000] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 13.790000] xt_time: kernel timezone is -0000
[ 13.810000] PPP generic driver version 2.4.2
[ 13.820000] NET: Registered protocol family 24
[ 13.830000] ath: EEPROM regdomain: 0x0
[ 13.830000] ath: EEPROM indicates default country code should be used
[ 13.830000] ath: doing EEPROM country->regdmn map search
[ 13.830000] ath: country maps to regdmn code: 0x3a
[ 13.830000] ath: Country alpha2 being used: US
[ 13.830000] ath: Regpair used: 0x3a
[ 13.840000] ieee80211 phy1: Selected rate control algorithm
'minstrel_ht'
[ 13.860000] ieee80211 phy1: Atheros AR9550 Rev:0 mem=0xb8100000, irq=47
[ 19.200000] eth1: link up (1000Mbps/Full duplex)
[ 19.200000] device eth1 entered promiscuous mode
[ 19.210000] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[ 19.220000] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 20.210000] br-lan: port 1(eth1) entered forwarding state
[ 20.210000] br-lan: port 1(eth1) entered forwarding state
[ 20.240000] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 20.340000] cfg80211: Calling CRDA for country: DE
[ 20.340000] cfg80211: Regulatory domain changed to country: DE
[ 20.350000] cfg80211: DFS Master region: ETSI
[ 20.350000] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[ 20.360000] cfg80211: (2400000 KHz - 2483000 KHz @ 40000 KHz), (N/A,
2000 mBm), (N/A)
[ 20.370000] cfg80211: (5150000 KHz - 5250000 KHz @ 80000 KHz), (N/A,
2000 mBm), (N/A)
[ 20.380000] cfg80211: (5250000 KHz - 5350000 KHz @ 80000 KHz), (N/A,
2000 mBm), (0 s)
[ 20.390000] cfg80211: (5470000 KHz - 5725000 KHz @ 80000 KHz), (N/A,
2700 mBm), (0 s)
[ 20.390000] cfg80211: (57240000 KHz - 65880000 KHz @ 2160000 KHz),
(N/A, 4000 mBm), (N/A)
[ 20.740000] eth0: link up (1000Mbps/Full duplex)
[ 20.870000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 22.210000] br-lan: port 1(eth1) entered forwarding state
[ 22.400000] ath10k: otp stream is empty, using board.bin contents
[ 23.240000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 23.760000] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[ 23.780000] device wlan1 entered promiscuous mode
[ 23.780000] br-lan: port 2(wlan1) entered forwarding state
[ 23.790000] br-lan: port 2(wlan1) entered forwarding state
[ 24.210000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 24.920000] wlan0: authenticate with aa:bb:cc:dd:ee:ff
[ 24.930000] wlan0: send auth to aa:bb:cc:dd:ee:ff (try 1/3)
[ 24.940000] wlan0: authenticated
[ 24.970000] wlan0: associate with aa:bb:cc:dd:ee:ff (try 1/3)
[ 24.970000] wlan0: RX AssocResp from aa:bb:cc:dd:ee:ff (capab=0x11
status=0 aid=1)
[ 24.990000] wlan0: associated
[ 24.990000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 25.790000] br-lan: port 2(wlan1) entered forwarding state
[32581.940000] wlan0: deauthenticated from aa:bb:cc:dd:ee:ff (Reason:
15=4WAY_HANDSHAKE_TIMEOUT)
[32583.010000] wlan0: authenticate with aa:bb:cc:dd:ee:ff
[32583.020000] wlan0: send auth to aa:bb:cc:dd:ee:ff (try 1/3)
[32583.020000] wlan0: authenticated
[32583.040000] wlan0: associate with aa:bb:cc:dd:ee:ff (try 1/3)
[32583.040000] wlan0: RX AssocResp from aa:bb:cc:dd:ee:ff (capab=0x11
status=0 aid=1)
[32583.050000] wlan0: associated
[43522.580000] wlan0: deauthenticated from aa:bb:cc:dd:ee:ff (Reason:
15=4WAY_HANDSHAKE_TIMEOUT)
[43523.670000] wlan0: authenticate with aa:bb:cc:dd:ee:ff
[43523.680000] wlan0: send auth to aa:bb:cc:dd:ee:ff (try 1/3)
[43523.680000] wlan0: authenticated
[43523.700000] wlan0: associate with aa:bb:cc:dd:ee:ff (try 1/3)
[43523.700000] wlan0: RX AssocResp from aa:bb:cc:dd:ee:ff (capab=0x11
status=0 aid=1)
[43523.710000] wlan0: associated
}}}
--
Ticket URL: <https://dev.openwrt.org/ticket/19531>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
