* Permanent disconnect when deauthenticated during a roam
@ 2022-11-09 16:42 Michael Johnson
2022-11-09 18:48 ` Denis Kenzior
0 siblings, 1 reply; 4+ messages in thread
From: Michael Johnson @ 2022-11-09 16:42 UTC (permalink / raw)
To: iwd
Hey all,
I've just had an issue with a client being permanently disconnected
after a failed roam. iwd detects the disconnect and prints a message
but doesn't seem to attempt to reconnect again. I don't see anything
about blacklists mentioned either.
Any idea why this isn't attempting to reconnect?
```
# Normal roam
Nov 08 17:59:41.880934 p3-3478 iwd[426]:
src/netdev.c:netdev_authenticate_event()
Nov 08 17:59:41.881248 p3-3478 kernel: wlan0: send auth to
ac:3a:67:5d:8b:2c (try 1/3)
Nov 08 17:59:41.881324 p3-3478 kernel: wlan0: authenticated
Nov 08 17:59:41.881374 p3-3478 iwd[426]:
src/netdev.c:netdev_get_oci_cb() Obtained OCI: freq: 5560, width: 1,
center1: 5560, center2: 0
Nov 08 17:59:41.889035 p3-3478 kernel: wlan0: associate with
ac:3a:67:5d:8b:2c (try 1/3)
Nov 08 17:59:41.889232 p3-3478 kernel: wlan0: RX ReassocResp from
ac:3a:67:5d:8b:2c (capab=0x1111 status=0 aid=10)
Nov 08 17:59:41.892719 p3-3478 iwd[426]:
src/netdev.c:netdev_mlme_notify() MLME notification Associate(38)
Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_associate_event()
Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_set_gtk() 5
Nov 08 17:59:41.892719 p3-3478 iwd[426]:
src/station.c:station_handshake_event() Setting keys
Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_set_tk() 5
Nov 08 17:59:41.892719 p3-3478 iwd[426]:
src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_connect_event()
Nov 08 17:59:41.893224 p3-3478 kernel: wlan0: associated
Nov 08 17:59:41.893271 p3-3478 kernel: ath: EEPROM regdomain: 0x8348
Nov 08 17:59:41.893307 p3-3478 kernel: ath: EEPROM indicates we should
expect a country code
Nov 08 17:59:41.893337 p3-3478 kernel: ath: doing EEPROM
country->regdmn map search
Nov 08 17:59:41.893363 p3-3478 kernel: ath: country maps to regdmn code: 0x3a
Nov 08 17:59:41.893390 p3-3478 kernel: ath: Country alpha2 being used: US
Nov 08 17:59:41.893416 p3-3478 kernel: ath: Regpair used: 0x3a
Nov 08 17:59:41.893457 p3-3478 kernel: ath: regdomain 0x8348
dynamically updated by country element
Nov 08 17:59:41.893483 p3-3478 kernel: wlan0: deauthenticated from
ac:3a:67:5d:8b:2c (Reason: 34=DISASSOC_LOW_ACK)
Nov 08 17:59:41.895990 p3-3478 iwd[426]:
src/netdev.c:netdev_link_notify() event 16 on ifindex 5
Nov 08 17:59:41.896099 p3-3478 iwd[426]:
src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
Nov 08 17:59:41.896099 p3-3478 iwd[426]:
src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for
(global) is US
Nov 08 17:59:41.896099 p3-3478 iwd[426]:
src/netdev.c:netdev_link_notify() event 16 on ifindex 5
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_mlme_notify() MLME notification Del Station(20)
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_link_notify() event 16 on ifindex 5
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_mlme_notify() MLME notification Deauthenticate(39)
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_deauthenticate_event()
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_link_notify() event 16 on ifindex 5
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
Nov 08 17:59:41.896282 p3-3478 iwd[426]: src/netdev.c:netdev_disconnect_event()
Nov 08 17:59:41.896282 p3-3478 iwd[426]: Received Deauthentication
event, reason: 34, from_ap: true
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/wiphy.c:wiphy_radio_work_done() Work item 493 done
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/station.c:station_disconnect_event() 5
Nov 08 17:59:41.896282 p3-3478 iwd[426]: Unexpected disconnect event
Nov 08 17:59:41.896282 p3-3478 iwd[426]:
src/netdev.c:netdev_link_notify() event 16 on ifindex 5
Nov 08 17:59:41.896867 p3-3478 iwd[426]:
src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
Nov 08 17:59:41.896867 p3-3478 iwd[426]:
src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for
(global) is XX
Nov 08 17:59:42.893123 p3-3478 iwd[426]:
src/netdev.c:netdev_link_notify() event 16 on ifindex 5
# Doesn't reconnect and needs to be rebooted ...
```
Regards,
Michael
^ permalink raw reply [flat|nested] 4+ messages in thread
* Re: Permanent disconnect when deauthenticated during a roam
2022-11-09 16:42 Permanent disconnect when deauthenticated during a roam Michael Johnson
@ 2022-11-09 18:48 ` Denis Kenzior
2022-11-10 9:20 ` Michael Johnson
0 siblings, 1 reply; 4+ messages in thread
From: Denis Kenzior @ 2022-11-09 18:48 UTC (permalink / raw)
To: Michael Johnson, iwd
Hi Michael,
On 11/9/22 10:42, Michael Johnson wrote:
> Hey all,
>
> I've just had an issue with a client being permanently disconnected
> after a failed roam. iwd detects the disconnect and prints a message
> but doesn't seem to attempt to reconnect again. I don't see anything
> about blacklists mentioned either.
> Any idea why this isn't attempting to reconnect?
Hmm, see if commit 8758cc89487b3 fixes this:
https://git.kernel.org/pub/scm/network/wireless/iwd.git/commit/src?id=8758cc89487b3304e4b96179a464fff3201e690d
>
> ```
> # Normal roam
> Nov 08 17:59:41.880934 p3-3478 iwd[426]:
> src/netdev.c:netdev_authenticate_event()
> Nov 08 17:59:41.881248 p3-3478 kernel: wlan0: send auth to
> ac:3a:67:5d:8b:2c (try 1/3)
> Nov 08 17:59:41.881324 p3-3478 kernel: wlan0: authenticated
> Nov 08 17:59:41.881374 p3-3478 iwd[426]:
> src/netdev.c:netdev_get_oci_cb() Obtained OCI: freq: 5560, width: 1,
> center1: 5560, center2: 0
> Nov 08 17:59:41.889035 p3-3478 kernel: wlan0: associate with
> ac:3a:67:5d:8b:2c (try 1/3)
> Nov 08 17:59:41.889232 p3-3478 kernel: wlan0: RX ReassocResp from
> ac:3a:67:5d:8b:2c (capab=0x1111 status=0 aid=10)
> Nov 08 17:59:41.892719 p3-3478 iwd[426]:
> src/netdev.c:netdev_mlme_notify() MLME notification Associate(38)
> Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_associate_event()
> Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_set_gtk() 5
> Nov 08 17:59:41.892719 p3-3478 iwd[426]:
> src/station.c:station_handshake_event() Setting keys
> Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_set_tk() 5
> Nov 08 17:59:41.892719 p3-3478 iwd[426]:
> src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
> Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_connect_event()
> Nov 08 17:59:41.893224 p3-3478 kernel: wlan0: associated
> Nov 08 17:59:41.893271 p3-3478 kernel: ath: EEPROM regdomain: 0x8348
> Nov 08 17:59:41.893307 p3-3478 kernel: ath: EEPROM indicates we should
> expect a country code
> Nov 08 17:59:41.893337 p3-3478 kernel: ath: doing EEPROM
> country->regdmn map search
> Nov 08 17:59:41.893363 p3-3478 kernel: ath: country maps to regdmn code: 0x3a
> Nov 08 17:59:41.893390 p3-3478 kernel: ath: Country alpha2 being used: US
> Nov 08 17:59:41.893416 p3-3478 kernel: ath: Regpair used: 0x3a
> Nov 08 17:59:41.893457 p3-3478 kernel: ath: regdomain 0x8348
> dynamically updated by country element
> Nov 08 17:59:41.893483 p3-3478 kernel: wlan0: deauthenticated from
> ac:3a:67:5d:8b:2c (Reason: 34=DISASSOC_LOW_ACK)
Looks like we deauthenticate very shortly after associated where the roam is
still in progress.
> Nov 08 17:59:41.895990 p3-3478 iwd[426]:
> src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> Nov 08 17:59:41.896099 p3-3478 iwd[426]:
> src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
> Nov 08 17:59:41.896099 p3-3478 iwd[426]:
> src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for
> (global) is US
> Nov 08 17:59:41.896099 p3-3478 iwd[426]:
> src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_mlme_notify() MLME notification Del Station(20)
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_mlme_notify() MLME notification Deauthenticate(39)
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_deauthenticate_event()
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
> Nov 08 17:59:41.896282 p3-3478 iwd[426]: src/netdev.c:netdev_disconnect_event()
> Nov 08 17:59:41.896282 p3-3478 iwd[426]: Received Deauthentication
> event, reason: 34, from_ap: true
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/wiphy.c:wiphy_radio_work_done() Work item 493 done
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/station.c:station_disconnect_event() 5
> Nov 08 17:59:41.896282 p3-3478 iwd[426]: Unexpected disconnect event
And we erroneously think that such an event can't happen (in 1.30).
> Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> Nov 08 17:59:41.896867 p3-3478 iwd[426]:
> src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
> Nov 08 17:59:41.896867 p3-3478 iwd[426]:
> src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for
> (global) is XX
> Nov 08 17:59:42.893123 p3-3478 iwd[426]:
> src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> # Doesn't reconnect and needs to be rebooted ...
> ```
>
Regards,
-Denis
^ permalink raw reply [flat|nested] 4+ messages in thread
* Re: Permanent disconnect when deauthenticated during a roam
2022-11-09 18:48 ` Denis Kenzior
@ 2022-11-10 9:20 ` Michael Johnson
2022-11-10 15:22 ` Denis Kenzior
0 siblings, 1 reply; 4+ messages in thread
From: Michael Johnson @ 2022-11-10 9:20 UTC (permalink / raw)
To: Denis Kenzior; +Cc: iwd
Thanks Denis,
I'll give the patch a try. When you specify 1.30 does that mean this
isn't likely to have an effect on 1.28?
Michael
On Wed, 9 Nov 2022 at 18:48, Denis Kenzior <denkenz@gmail.com> wrote:
>
> Hi Michael,
>
> On 11/9/22 10:42, Michael Johnson wrote:
> > Hey all,
> >
> > I've just had an issue with a client being permanently disconnected
> > after a failed roam. iwd detects the disconnect and prints a message
> > but doesn't seem to attempt to reconnect again. I don't see anything
> > about blacklists mentioned either.
> > Any idea why this isn't attempting to reconnect?
>
> Hmm, see if commit 8758cc89487b3 fixes this:
>
> https://git.kernel.org/pub/scm/network/wireless/iwd.git/commit/src?id=8758cc89487b3304e4b96179a464fff3201e690d
>
> >
> > ```
> > # Normal roam
> > Nov 08 17:59:41.880934 p3-3478 iwd[426]:
> > src/netdev.c:netdev_authenticate_event()
> > Nov 08 17:59:41.881248 p3-3478 kernel: wlan0: send auth to
> > ac:3a:67:5d:8b:2c (try 1/3)
> > Nov 08 17:59:41.881324 p3-3478 kernel: wlan0: authenticated
> > Nov 08 17:59:41.881374 p3-3478 iwd[426]:
> > src/netdev.c:netdev_get_oci_cb() Obtained OCI: freq: 5560, width: 1,
> > center1: 5560, center2: 0
> > Nov 08 17:59:41.889035 p3-3478 kernel: wlan0: associate with
> > ac:3a:67:5d:8b:2c (try 1/3)
> > Nov 08 17:59:41.889232 p3-3478 kernel: wlan0: RX ReassocResp from
> > ac:3a:67:5d:8b:2c (capab=0x1111 status=0 aid=10)
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]:
> > src/netdev.c:netdev_mlme_notify() MLME notification Associate(38)
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_associate_event()
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_set_gtk() 5
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]:
> > src/station.c:station_handshake_event() Setting keys
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_set_tk() 5
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]:
> > src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
> > Nov 08 17:59:41.892719 p3-3478 iwd[426]: src/netdev.c:netdev_connect_event()
> > Nov 08 17:59:41.893224 p3-3478 kernel: wlan0: associated
> > Nov 08 17:59:41.893271 p3-3478 kernel: ath: EEPROM regdomain: 0x8348
> > Nov 08 17:59:41.893307 p3-3478 kernel: ath: EEPROM indicates we should
> > expect a country code
> > Nov 08 17:59:41.893337 p3-3478 kernel: ath: doing EEPROM
> > country->regdmn map search
> > Nov 08 17:59:41.893363 p3-3478 kernel: ath: country maps to regdmn code: 0x3a
> > Nov 08 17:59:41.893390 p3-3478 kernel: ath: Country alpha2 being used: US
> > Nov 08 17:59:41.893416 p3-3478 kernel: ath: Regpair used: 0x3a
> > Nov 08 17:59:41.893457 p3-3478 kernel: ath: regdomain 0x8348
> > dynamically updated by country element
> > Nov 08 17:59:41.893483 p3-3478 kernel: wlan0: deauthenticated from
> > ac:3a:67:5d:8b:2c (Reason: 34=DISASSOC_LOW_ACK)
>
> Looks like we deauthenticate very shortly after associated where the roam is
> still in progress.
>
> > Nov 08 17:59:41.895990 p3-3478 iwd[426]:
> > src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> > Nov 08 17:59:41.896099 p3-3478 iwd[426]:
> > src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
> > Nov 08 17:59:41.896099 p3-3478 iwd[426]:
> > src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for
> > (global) is US
> > Nov 08 17:59:41.896099 p3-3478 iwd[426]:
> > src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_mlme_notify() MLME notification Del Station(20)
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_mlme_notify() MLME notification Deauthenticate(39)
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_deauthenticate_event()
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]: src/netdev.c:netdev_disconnect_event()
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]: Received Deauthentication
> > event, reason: 34, from_ap: true
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/wiphy.c:wiphy_radio_work_done() Work item 493 done
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/station.c:station_disconnect_event() 5
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]: Unexpected disconnect event
>
> And we erroneously think that such an event can't happen (in 1.30).
>
> > Nov 08 17:59:41.896282 p3-3478 iwd[426]:
> > src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> > Nov 08 17:59:41.896867 p3-3478 iwd[426]:
> > src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
> > Nov 08 17:59:41.896867 p3-3478 iwd[426]:
> > src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for
> > (global) is XX
> > Nov 08 17:59:42.893123 p3-3478 iwd[426]:
> > src/netdev.c:netdev_link_notify() event 16 on ifindex 5
> > # Doesn't reconnect and needs to be rebooted ...
> > ```
> >
>
> Regards,
> -Denis
^ permalink raw reply [flat|nested] 4+ messages in thread
* Re: Permanent disconnect when deauthenticated during a roam
2022-11-10 9:20 ` Michael Johnson
@ 2022-11-10 15:22 ` Denis Kenzior
0 siblings, 0 replies; 4+ messages in thread
From: Denis Kenzior @ 2022-11-10 15:22 UTC (permalink / raw)
To: Michael Johnson; +Cc: iwd
Hi Michael,
On 11/10/22 03:20, Michael Johnson wrote:
> Thanks Denis,
>
> I'll give the patch a try. When you specify 1.30 does that mean this
> isn't likely to have an effect on 1.28?
>
Should be applicable to 1.28 as well I think. I just didn't look that far back.
Regards,
-Denis
^ permalink raw reply [flat|nested] 4+ messages in thread
end of thread, other threads:[~2022-11-10 15:22 UTC | newest]
Thread overview: 4+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2022-11-09 16:42 Permanent disconnect when deauthenticated during a roam Michael Johnson
2022-11-09 18:48 ` Denis Kenzior
2022-11-10 9:20 ` Michael Johnson
2022-11-10 15:22 ` Denis Kenzior
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).