[FS#600] Random disconnection on 17.01

Sun Mar 5 17:55:46 PST 2017

A new Flyspray task has been opened.  Details are below. 

User who did this - Emmanuel Lepage Vallée (Elv13) 

Attached to Project - LEDE Project
Summary - Random disconnection on 17.01
Task Type - Bug Report
Category - Base system
Status - Unconfirmed
Assigned To - 
Operating System - All
Severity - Low
Priority - Very Low
Reported Version - Trunk
Due in Version - Undecided
Due Date - Undecided
Details - Problem:

I upgrade my router from some years old dd-wrt to LEDE. "It works" and both the throughput and latency are vastly superior. However, it disconnect every couple hours for 2 minutes. Both my phone and my thinkpad are affected, so it is not a suddent regression in the client side. The old firmware showed no such interruption and could run a single connection TCP for months. At first, all packets stop being received and all sent timeout. After a while NetworkManager drop the (dead) Wifi conenction. Restarting networkmanager doesn't restore the connection, only time does. The encryption and channel are the same as before.

Info:

Hostname	LEDE
Model	D-Link DIR-615 rev. I1
Firmware Version	LEDE Reboot 17.01.0 r3205-59508e3 / LuCI lede-17.01 branch (git-17.051.53299-a100738)
Kernel Version	4.4.50

SSHed into the rounter show nothing interesting in dmesg or the logs.

dmesg (client side)

[1750975.095083] wlp3s0: authenticate with c8:d3:a3:57:c1:0d
[1750975.097291] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 1/3)
[1750975.182387] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 2/3)
[1750975.340969] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 3/3)
[1750975.433683] wlp3s0: authentication with c8:d3:a3:57:c1:0d timed out
[1750986.745947] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1750986.783926] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1750986.784247] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1750986.973972] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1750986.974238] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1750986.988274] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1751024.037294] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1751024.037564] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1751024.232261] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1751024.232531] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1751024.246198] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1751035.933932] wlp3s0: authenticate with c8:d3:a3:57:c1:0d
[1751035.936358] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 1/3)
[1751035.941892] wlp3s0: authenticated
[1751035.942083] wlp3s0: associate with c8:d3:a3:57:c1:0d (try 1/3)
[1751035.946355] wlp3s0: RX AssocResp from c8:d3:a3:57:c1:0d (capab=0x431 status=0 aid=1)
[1751035.947757] wlp3s0: associated
[1751035.947784] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[1753524.568775] iwlwifi 0000:03:00.0: invalid BA notification: Q 16, tid 0, flow 65535
[1754028.350307] wlp3s0: deauthenticating from c8:d3:a3:57:c1:0d by local choice (Reason: 3=DEAUTH_LEAVING)
[1754029.418400] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
[1754029.423879] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754029.435347] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754029.435766] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754029.643875] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754029.644146] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754029.658379] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754029.708225] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754033.134519] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754033.134793] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754033.338532] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754033.338803] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754033.352845] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754058.434794] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754058.447119] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754058.447392] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754058.645058] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754058.645328] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754058.659098] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754062.836641] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754062.836915] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754063.041653] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754063.041914] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754063.055581] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754063.070786] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754066.513509] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754066.513776] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754066.712341] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754066.712663] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754066.727003] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754082.615329] wlp3s0: authenticate with c8:d3:a3:57:c1:0d
[1754082.617891] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 1/3)
[1754082.711318] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 2/3)
[1754082.800197] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 3/3)
[1754082.890379] wlp3s0: authentication with c8:d3:a3:57:c1:0d timed out
[1754092.431334] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754092.442752] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754092.443037] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754092.642763] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754092.643027] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754092.657436] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754096.103393] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754096.103667] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754096.305383] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754096.305650] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[1754096.320213] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[1754099.851495] wlp3s0: authenticate with c8:d3:a3:57:c1:0d
[1754099.853971] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 1/3)
[1754100.000973] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 2/3)
[1754100.100010] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 3/3)
[1754100.234954] wlp3s0: authentication with c8:d3:a3:57:c1:0d timed out
[1754104.050494] wlp3s0: authenticate with c8:d3:a3:57:c1:0d
[1754104.052754] wlp3s0: send auth to c8:d3:a3:57:c1:0d (try 1/3)
[1754104.126844] wlp3s0: authenticated
[1754104.127569] wlp3s0: associate with c8:d3:a3:57:c1:0d (try 1/3)
[1754104.135916] wlp3s0: RX AssocResp from c8:d3:a3:57:c1:0d (capab=0x431 status=0 aid=1)
[1754104.138461] wlp3s0: associated
[1754104.138810] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready

What should I do to further debug this? I can build new images if necessary.

More information can be found at the following URL:
https://bugs.lede-project.org/index.php?do=details&task_id=600