Modify

Opened 3 years ago

Last modified 2 years ago

#19531 reopened defect

4 WAY HANDSHAKE TIMEOUT

Reported by: hutamanzi Owned by: developers
Priority: highest Milestone:
Component: kernel Version: Barrier Breaker 14.07
Keywords: Cc:

Description

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

Attachments (1)

kernellogs.txt (13.9 KB) - added by hutamanzi 3 years ago.
kernel logs

Download all attachments as: .zip

Change History (7)

Changed 3 years ago by hutamanzi

kernel logs

comment:1 Changed 3 years ago by hutamanzi

I meant:

wlan0: deauthenticated from aa:bb:cc:dd:ee:ff (Reason: 15=4WAY_HANDSHAKE_TIMEOUT)

comment:2 Changed 3 years ago by anonymous

It should be Driver issues

comment:3 Changed 3 years ago by anonymous

Same issue,mine gets disconeced after 10 seconds :/

Any ideas?

comment:4 Changed 2 years ago by nbd

please try current trunk

comment:5 Changed 2 years ago by nbd

  • Resolution set to no_response
  • Status changed from new to closed

comment:6 Changed 2 years ago by Hutamanzi

  • Resolution no_response deleted
  • Status changed from closed to reopened

Nope. I still get this problem with the final chaos calmer.

Add Comment

Modify Ticket

Action
as reopened .
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.