Repeating PREV_AUTH_NOT_VALID disconnects, *exactly* every 10 minutes. - hostapd and wpa_supplicant with linux bridging

[James Feeney]

Arch
Toshiba Satellite L655
linux 4.8.6-1
rtlwifi_new 2016Nov08
RTL8188CE 802.11b/g/n WiFi Adapter
Subsystem: Realtek Semiconductor Co., Ltd. Device 8181
Kernel driver in use: rtl8192ce
options rtl8192ce debug=5 ips=0 fwlps=0
iw dev wlp2s0 set 4addr on
wpa_supplicant v2.6
*not* using "-b br_ifname" old kernel bug work-around
*and* using "-b bridge0"

Access Point - Only one, no "roaming" -
Linksys EA3500
OpenWRT/LEDE r2112
Marvell Technology Group Ltd. 88W8366 [TopDog] 802.11n Wireless
wpad-mini/hostapd v2.6-devel
running with "wds_sta=1" for "four address mode"

I've set-up bridging over wireless and am seeing these "PREV_AUTH_NOT_VALID"
disconnects, *exactly*, on the second, every 10 minutes.  Since this is not at
all a random disconnect, I'm wondering, does someone knows why this is?

And then, including the "-b bridge0" option, these disconnects go away.  But, my
understanding was that this "-b" option was just a workaround for an old, and
presumably fixed, kernel bug.

Without "-b bridge0", after enabling debugging in wpa_supplicant, each
PREV_AUTH_NOT_VALID is preceded by:

wpa_supplicant[1527]: RTM_NEWLINK: ifi_index=3 ifname=wlp2s0 operstate=2
linkmode=1 master=5 ifi_family=0 ifi_flags=0x1803 ([UP])

and then:

kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx (Reason:
2=PREV_AUTH_NOT_VALID)

What I know about RTM_NEWLINK is limited to "man 7 rtnetlink".  If I understand,
in this RTM_NEWLINK message, wpa_supplicant is telling the kernel that there is
a new network interface - every 10 minutes - and this is wrong.

2: enp3s0: <NO-CARRIER,BROADCAST,MULTICAST,SLAVE,UP> mtu 1500 qdisc fq_codel
master bond0 state DOWN mode DEFAULT group default qlen 1000
3: wlp2s0: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master
bond0 state UP mode DORMANT group default qlen 1000
4: enp0s26u1u6: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc fq_codel
master bridge0 state DOWN mode DEFAULT group default qlen 1000
5: bond0: <BROADCAST,MULTICAST,MASTER,UP,LOWER_UP> mtu 1500 qdisc noqueue master
bridge0 state UP mode DEFAULT group default qlen 1000
6: bridge0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP
mode DEFAULT group default qlen 1000

Here, enp3s0 and wlp2s0 are on bond0, and enp0s26u1u6 and bond0 are on bridge0.

Where is this 600 second timer hiding?  Is this state-change coming from hostapd
on the Access Point?  Or from wpa_supplicant?  Or is there something going on
with the rtl8192ce driver?

I did find this, from 2004 August:
"Disconnecting EXACTLY every 10 minutes"
http://www.tomshardware.com/forum/13992-43-disconnecting-minutes
"Archived from groups: microsoft.public.windows.networking.wireless
I have been having the exact same 10 minute disconnect issue with my
Toshiba laptop and Linksys router. After playing around, I seemed to
have fixed it by unchecking the ENABLE IEEE802.1x AUTHENITICATION FOR
THIS NETWORK box in the AUTHENTICATION tab (from the PROPERTIES box for
my preffered network)."

This might suggest something to do with the authorization mechanism.

Note the times, to the second, in an example from the log, using "$ journalctl
-ab|grep DISCONNECTED", which, again, goes away when adding "-b bridge0" to
wpa_supplicant:

Nov 09 06:08:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 06:18:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 06:28:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 06:38:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 06:48:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 06:58:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:08:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:18:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:28:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:28:07 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:38:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:48:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 07:58:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 08:08:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 08:18:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)
Nov 09 08:28:03 lapis kernel: wlp2s0: deauthenticated from 48:f8:b3:xx:xx:xx
(Reason: 2=PREV_AUTH_NOT_VALID)