Modify

Opened 5 years ago

Closed 4 years ago

Last modified 4 years ago

#12339 closed defect (fixed)

ath5k wds station keeps reconnecting

Reported by: duvi Owned by: developers
Priority: normal Milestone: Chaos Calmer 15.05
Component: base system Version: Trunk
Keywords: ath5k wds station reconnect Cc:

Description

I'm using some Foneras as WDS stations to connect parts of the network.
When I restart a Fonera, it stays connected for 1-2 hours, without any problem. But after that uptime, it starts to reconnect every 5-10 minutes.

This issue has been present in trunk for a long time.
I still have one station Fonera device with the same config in the same environment, that runs r28395, and this one never reconnects; so the problem appeared in a change after that.

I was hoping that r33821 would bring a fix to this issue, but it did not.

Is there a way I can provide more detailed log about the issue?

Please see the configs and logs attached.

AP: Mikrotik RB433UAH, trunk r33315
Station: Fonera, trunk r33853

Station config:

root@OpenWrt:~# cat /etc/config/wireless
config wifi-device  radio0
        option type     mac80211
        option channel  1
        option hwmode   11g
        option path     'platform/ar231x-wmac.0'
        option disabled 0
        option diversity 0
        option rxantenna 1
        option txantenna 1

config wifi-iface
        option device   radio0
        option network  lan
        option mode     sta
        option wds      1
        option ssid     myssid
        option encryption psk2
        option key      mypassword

AP config:

root@OpenWrt:~# cat /etc/config/wireless
config wifi-device  radio0
        option disabled 0
        option type     mac80211
        option channel  1
        option macaddr  00:19:e0:67:09:21
        option hwmode   11g
        option txpower  18

config wifi-iface
        option disabled 0
        option device   radio0
        option network  lan
        option mode     ap
        option wds      1
        option ssid     myssid
        option encryption psk-mixed
        option key      mypassword
        option wpa_group_rekey 3600

Station log, initial connection:

Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.190000] wlan0: authenticate with 00:19:e0:67:09:21
Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.320000] wlan0: send auth to 00:19:e0:67:09:21 (try 1/3)
Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.390000] wlan0: authenticated
Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.510000] wlan0: associate with 00:19:e0:67:09:21 (try 1/3)
Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.640000] wlan0: RX AssocResp from 00:19:e0:67:09:21 (capab=0x411 status=0 aid=2)
Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.730000] wlan0: associated
Jan  1 01:00:41 OpenWrt kern.info kernel: [   41.780000] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jan  1 01:00:42 OpenWrt kern.info kernel: [   41.920000] device wlan0 entered promiscuous mode
Jan  1 01:00:42 OpenWrt kern.info kernel: [   41.980000] br-lan: port 2(wlan0) entered forwarding state
Jan  1 01:00:42 OpenWrt kern.info kernel: [   42.050000] br-lan: port 2(wlan0) entered forwarding state
Jan  1 01:00:42 OpenWrt kern.info kernel: [   42.630000] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jan  1 01:00:43 OpenWrt authpriv.info dropbear[565]: Running in background
Jan  1 01:00:44 OpenWrt kern.info kernel: [   44.050000] br-lan: port 2(wlan0) entered forwarding state

Station log, reconnections start:

Oct 19 11:34:00 OpenWrt kern.info kernel: [ 8966.680000] wlan0: disassociated from 00:19:e0:67:09:21 (Reason: 4)
Oct 19 11:34:00 OpenWrt kern.info kernel: [ 8966.750000] br-lan: port 2(wlan0) entered disabled state
Oct 19 11:34:00 OpenWrt kern.info kernel: [ 8966.830000] wlan0: deauthenticating from 00:19:e0:67:09:21 by local choice (reason=3)
Oct 19 11:34:01 OpenWrt kern.info kernel: [ 8968.110000] wlan0: authenticate with 00:19:e0:67:09:21
Oct 19 11:34:01 OpenWrt kern.info kernel: [ 8968.180000] wlan0: send auth to 00:19:e0:67:09:21 (try 1/3)
Oct 19 11:34:01 OpenWrt kern.info kernel: [ 8968.250000] wlan0: authenticated
Oct 19 11:34:01 OpenWrt kern.info kernel: [ 8968.300000] wlan0: associate with 00:19:e0:67:09:21 (try 1/3)
Oct 19 11:34:02 OpenWrt kern.info kernel: [ 8968.370000] wlan0: RX AssocResp from 00:19:e0:67:09:21 (capab=0x411 status=0 aid=2)
Oct 19 11:34:02 OpenWrt kern.info kernel: [ 8968.470000] wlan0: associated
Oct 19 11:34:02 OpenWrt kern.info kernel: [ 8968.510000] br-lan: port 2(wlan0) entered forwarding state
Oct 19 11:34:02 OpenWrt kern.info kernel: [ 8968.570000] br-lan: port 2(wlan0) entered forwarding state
Oct 19 11:34:04 OpenWrt kern.info kernel: [ 8970.570000] br-lan: port 2(wlan0) entered forwarding state

Oct 19 11:39:08 OpenWrt kern.info kernel: [ 9275.380000] wlan0: disassociated from 00:19:e0:67:09:21 (Reason: 4)
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.460000] br-lan: port 2(wlan0) entered disabled state
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.530000] wlan0: deauthenticating from 00:19:e0:67:09:21 by local choice (reason=3)
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.660000] wlan0: authenticate with 00:19:e0:67:09:21
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.730000] wlan0: send auth to 00:19:e0:67:09:21 (try 1/3)
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.800000] wlan0: authenticated
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.850000] wlan0: associate with 00:19:e0:67:09:21 (try 1/3)
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9275.920000] wlan0: RX AssocResp from 00:19:e0:67:09:21 (capab=0x411 status=0 aid=2)
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9276.020000] wlan0: associated
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9276.060000] br-lan: port 2(wlan0) entered forwarding state
Oct 19 11:39:09 OpenWrt kern.info kernel: [ 9276.130000] br-lan: port 2(wlan0) entered forwarding state
Oct 19 11:39:11 OpenWrt kern.info kernel: [ 9278.130000] br-lan: port 2(wlan0) entered forwarding state

Oct 19 11:51:42 OpenWrt kern.info kernel: [10028.930000] wlan0: disassociated from 00:19:e0:67:09:21 (Reason: 4)
Oct 19 11:51:42 OpenWrt kern.info kernel: [10029.010000] br-lan: port 2(wlan0) entered disabled state
Oct 19 11:51:42 OpenWrt kern.info kernel: [10029.080000] wlan0: deauthenticating from 00:19:e0:67:09:21 by local choice (reason=3)
Oct 19 11:51:42 OpenWrt kern.info kernel: [10029.220000] wlan0: authenticate with 00:19:e0:67:09:21
Oct 19 11:51:42 OpenWrt kern.info kernel: [10029.290000] wlan0: send auth to 00:19:e0:67:09:21 (try 1/3)
Oct 19 11:51:42 OpenWrt kern.info kernel: [10029.360000] wlan0: authenticated
Oct 19 11:51:42 OpenWrt kern.info kernel: [10029.410000] wlan0: associate with 00:19:e0:67:09:21 (try 1/3)
Oct 19 11:51:43 OpenWrt kern.info kernel: [10029.480000] wlan0: RX AssocResp from 00:19:e0:67:09:21 (capab=0x411 status=0 aid=2)
Oct 19 11:51:43 OpenWrt kern.info kernel: [10029.580000] wlan0: associated
Oct 19 11:51:43 OpenWrt kern.info kernel: [10029.620000] br-lan: port 2(wlan0) entered forwarding state
Oct 19 11:51:43 OpenWrt kern.info kernel: [10029.690000] br-lan: port 2(wlan0) entered forwarding state
Oct 19 11:51:45 OpenWrt kern.info kernel: [10031.690000] br-lan: port 2(wlan0) entered forwarding state

AP log, reconnections:

Oct 19 11:34:00 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: disassociated due to inactivity
Oct 19 11:34:00 OpenWrt kern.info kernel: [1388373.260000] device wlan0.sta2 left promiscuous mode
Oct 19 11:34:00 OpenWrt kern.info kernel: [1388373.260000] br-lan: port 5(wlan0.sta2) entered disabled state
Oct 19 11:34:01 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: authenticated
Oct 19 11:34:01 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: associated (aid 2)
Oct 19 11:34:01 OpenWrt kern.info kernel: [1388374.800000] device wlan0.sta2 entered promiscuous mode
Oct 19 11:34:01 OpenWrt kern.info kernel: [1388374.830000] br-lan: port 5(wlan0.sta2) entered forwarding state
Oct 19 11:34:01 OpenWrt kern.info kernel: [1388374.830000] br-lan: port 5(wlan0.sta2) entered forwarding state
Oct 19 11:34:02 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 RADIUS: starting accounting session 0000001D-000000D5
Oct 19 11:34:02 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 WPA: pairwise key handshake completed (RSN)
Oct 19 11:34:03 OpenWrt kern.info kernel: [1388376.830000] br-lan: port 5(wlan0.sta2) entered forwarding state

Oct 19 11:39:08 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: disassociated due to inactivity
Oct 19 11:39:09 OpenWrt kern.info kernel: [1388681.950000] device wlan0.sta2 left promiscuous mode
Oct 19 11:39:09 OpenWrt kern.info kernel: [1388681.960000] br-lan: port 5(wlan0.sta2) entered disabled state
Oct 19 11:39:09 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: authenticated
Oct 19 11:39:09 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: associated (aid 2)
Oct 19 11:39:09 OpenWrt kern.info kernel: [1388682.350000] device wlan0.sta2 entered promiscuous mode
Oct 19 11:39:09 OpenWrt kern.info kernel: [1388682.380000] br-lan: port 5(wlan0.sta2) entered forwarding state
Oct 19 11:39:09 OpenWrt kern.info kernel: [1388682.380000] br-lan: port 5(wlan0.sta2) entered forwarding state
Oct 19 11:39:10 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 RADIUS: starting accounting session 0000001D-000000D6
Oct 19 11:39:10 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 WPA: pairwise key handshake completed (RSN)
Oct 19 11:39:11 OpenWrt kern.info kernel: [1388684.380000] br-lan: port 5(wlan0.sta2) entered forwarding state

Oct 19 11:51:42 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: disassociated due to inactivity
Oct 19 11:51:42 OpenWrt kern.info kernel: [1389435.510000] device wlan0.sta2 left promiscuous mode
Oct 19 11:51:42 OpenWrt kern.info kernel: [1389435.510000] br-lan: port 5(wlan0.sta2) entered disabled state
Oct 19 11:51:42 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: authenticated
Oct 19 11:51:42 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 IEEE 802.11: associated (aid 2)
Oct 19 11:51:43 OpenWrt kern.info kernel: [1389435.910000] device wlan0.sta2 entered promiscuous mode
Oct 19 11:51:43 OpenWrt kern.info kernel: [1389435.940000] br-lan: port 5(wlan0.sta2) entered forwarding state
Oct 19 11:51:43 OpenWrt kern.info kernel: [1389435.940000] br-lan: port 5(wlan0.sta2) entered forwarding state
Oct 19 11:51:44 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 RADIUS: starting accounting session 0000001D-000000D7
Oct 19 11:51:44 OpenWrt daemon.info hostapd: wlan0: STA 00:18:84:2a:e2:59 WPA: pairwise key handshake completed (RSN)
Oct 19 11:51:45 OpenWrt kern.info kernel: [1389437.940000] br-lan: port 5(wlan0.sta2) entered forwarding state

Attachments (0)

Change History (7)

comment:1 Changed 5 years ago by spamsales@…

Same here with a TP-WR81N and a wirless nic with ath9k (4addr mode)

comment:2 Changed 5 years ago by spamsales@…

Try to set wpa_group_rekey to 0

comment:3 Changed 5 years ago by duvi

wpa_group_rekey is set to 3600, so I don't think that's the problem.
The reconnect doesn't happen exactly each x minutes.

But thanks anyway!

comment:4 Changed 5 years ago by nbd

please try latest trunk

comment:5 Changed 5 years ago by duvi

I tried with r35819, and the results are the same.

What I really don't understand is this:

When I restart a Fonera, it stays connected for 1-2 hours, without any problem. But after that uptime, it starts to reconnect every 5-10 minutes.

And this is happening always like this.
Is there any way I could log more of what's going on?

comment:6 Changed 4 years ago by nbd

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

fixed in r38257, r38258

comment:7 Changed 4 years ago by duvi

5 hours uptime without reconnection, does indeed seem fixed, thank you!
I'll report back if it occurs again.

Add Comment

Modify Ticket

Action
as closed .
The resolution will be deleted. Next status will be 'reopened'.
Author


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

 
Note: See TracTickets for help on using tickets.