All of lore.kernel.org
 help / color / mirror / Atom feed
* iwd problems on Arch Linux
@ 2020-10-14 23:52 KeithG
  2020-10-15 16:48 ` Denis Kenzior
  0 siblings, 1 reply; 7+ messages in thread
From: KeithG @ 2020-10-14 23:52 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 2261 bytes --]

I have been struggling with this problem for a while and *think* I have
narrowed it down to an Arch Linux problem. My main focus is to get
iwd/connman to work on my RPis as iwd connects so much faster when it
works. Current versions on Arch Linux are Connman = 1.38 and iwd = 1.9. The
kernel I'm running is 5.4.70.

It does not behave absolutely consistently but there are themes. If I have
connman managing the connections, when the SSID goes away, iwd stops
scanning. This is very consistent. It does it first time every time. SSID
goes down, it disconnects and sits there. On Arch Linux, it does not seem
to be dependent on card (broadcom/intel) or hardware
(x86_64,armv6/armv7/aarch64). iwd quits scanning when the SSID goes away.
The monitor-iwd script shows 'Scanning = False' and it never rescans.
When I use systemd-networkd with iwd, I get better behavior, but it can
also get to a point where it quits scanning. This happens after a few times
of turning off then on the AP on the router. When it quits scanning,
monitor-iwd says "Scanning = False" and it never rescans. The last time I
ran it, the monitor-iwd script says Scanning = True then immediately
Scanning = False but nothing was scanned. It did this 13 times but never
'saw' anything nor connected. If I down then up the interface (ifconfig
wlan0 down; ifconfig wlan0 up), it immediately connects. I now have a
script that runs when the interface goes down to do this (down/up), but
shouldn't iwd eventually reconnect?

This contrasts with the same computer running RasPiOS. It is running the
same kernel and the same broadcom firmware (I updated both to be identical)
connman is 1.36 and iwd is 0.14, but it works every time all the time. I
cannot get it to not reconnect. In fact it connects faster than the web
page for the router registers it.

I built iwd 0.14 and connman 1.36 for the Arch install and it behaves the
same as iwd 1.9 or the iwd git master build. It just quits.

What underlying OS issues could cause this? I have built the kernel with
the same config options for both installations with the same computer doing
the building. Both installations are using the same kernel, firmware and
driver version (built with the kernel).

Keith

[-- Attachment #2: attachment.htm --]
[-- Type: text/html, Size: 2459 bytes --]

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: iwd problems on Arch Linux
  2020-10-14 23:52 iwd problems on Arch Linux KeithG
@ 2020-10-15 16:48 ` Denis Kenzior
  2020-10-15 18:54   ` KeithG
  0 siblings, 1 reply; 7+ messages in thread
From: Denis Kenzior @ 2020-10-15 16:48 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 2783 bytes --]

Hi Keith,

On 10/14/20 6:52 PM, KeithG wrote:
> I have been struggling with this problem for a while and *think* I have narrowed 
> it down to an Arch Linux problem. My main focus is to get iwd/connman to work on 
> my RPis as iwd connects so much faster when it works. Current versions on Arch 
> Linux are Connman = 1.38 and iwd = 1.9. The kernel I'm running is 5.4.70.
> 
> It does not behave absolutely consistently but there are themes. If I have 
> connman managing the connections, when the SSID goes away, iwd stops scanning. 
> This is very consistent. It does it first time every time. SSID goes down, it 
> disconnects and sits there. On Arch Linux, it does not seem to be dependent on 
> card (broadcom/intel) or hardware (x86_64,armv6/armv7/aarch64). iwd quits 
> scanning when the SSID goes away. The monitor-iwd script shows 'Scanning = 
> False' and it never rescans.

Connman invokes Station.Disconnect() which tells iwd to also not enter into its 
own Autoconnect phase.  I think the idea is for ConnMan to scan periodically, 
but I guess it doesn't do this for whatever reason.

> When I use systemd-networkd with iwd, I get better behavior, but it can also get 
> to a point where it quits scanning. This happens after a few times of turning 
> off then on the AP on the router. When it quits scanning, monitor-iwd says 
> "Scanning = False" and it never rescans. The last time I ran it, the monitor-iwd 
> script says Scanning = True then immediately Scanning = False but nothing was 
> scanned. It did this 13 times but never 'saw' anything nor connected. If I down 
> then up the interface (ifconfig wlan0 down; ifconfig wlan0 up), it immediately 
> connects. I now have a script that runs when the interface goes down to do this 
> (down/up), but shouldn't iwd eventually reconnect?

We would need to see iwd debug logs and iwmon logs to say much more...

https://iwd.wiki.kernel.org/debugging

> 
> This contrasts with the same computer running RasPiOS. It is running the same 
> kernel and the same broadcom firmware (I updated both to be identical) connman 
> is 1.36 and iwd is 0.14, but it works every time all the time. I cannot get it 
> to not reconnect. In fact it connects faster than the web page for the router 
> registers it.
> 
> I built iwd 0.14 and connman 1.36 for the Arch install and it behaves the same 
> as iwd 1.9 or the iwd git master build. It just quits.
> 
> What underlying OS issues could cause this? I have built the kernel with the 
> same config options for both installations with the same computer doing the 
> building. Both installations are using the same kernel, firmware and driver 
> version (built with the kernel).
> 

Hard to say without logs...

Regards,
-Denis

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: iwd problems on Arch Linux
  2020-10-15 16:48 ` Denis Kenzior
@ 2020-10-15 18:54   ` KeithG
  2020-10-15 19:58     ` Denis Kenzior
  0 siblings, 1 reply; 7+ messages in thread
From: KeithG @ 2020-10-15 18:54 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 4002 bytes --]

Denis,

Here are some logs.

This is on an aarch64 machine running Arch Linux. Kernel 5.4.70, iwd 1.9
and connman 1.38. The wifi driver is brcmfmac. The driver firmware is:
Firmware: BCM4345/6 wl0: Sep 18 2020 02:27:58 version 7.45.221 (3a6d3a0 CY)
FWID 01-bbd9282b

These logs contain an SSID off/on and a reconnect event (as desired) then
followed immediately with an SSID off/on and a no-reconnect event. I
grabbed the journal log from iwd and connman and also the pcap in case
any/all may be useful. I was able to see content that scrolled by on the
screen in the pcap with 'iwmon -F 0x17 -r no_reconnect.pcap' (I think you
mentioned this previously).

On a separate note, I was able to build and install iwd 1.9 for the RasPiOS
image. When running there, iwd 1.9 works as advertised with the same
performance as iwd 0.14. Reconnects almost immediately every time. Still
trying to update connman to 1.38 for the RasPiOS image.

Thanks for the help.

Keith


On Thu, Oct 15, 2020 at 11:48 AM Denis Kenzior <denkenz@gmail.com> wrote:

> Hi Keith,
>
> On 10/14/20 6:52 PM, KeithG wrote:
> > I have been struggling with this problem for a while and *think* I have
> narrowed
> > it down to an Arch Linux problem. My main focus is to get iwd/connman to
> work on
> > my RPis as iwd connects so much faster when it works. Current versions
> on Arch
> > Linux are Connman = 1.38 and iwd = 1.9. The kernel I'm running is 5.4.70.
> >
> > It does not behave absolutely consistently but there are themes. If I
> have
> > connman managing the connections, when the SSID goes away, iwd stops
> scanning.
> > This is very consistent. It does it first time every time. SSID goes
> down, it
> > disconnects and sits there. On Arch Linux, it does not seem to be
> dependent on
> > card (broadcom/intel) or hardware (x86_64,armv6/armv7/aarch64). iwd
> quits
> > scanning when the SSID goes away. The monitor-iwd script shows 'Scanning
> =
> > False' and it never rescans.
>
> Connman invokes Station.Disconnect() which tells iwd to also not enter
> into its
> own Autoconnect phase.  I think the idea is for ConnMan to scan
> periodically,
> but I guess it doesn't do this for whatever reason.
>
> > When I use systemd-networkd with iwd, I get better behavior, but it can
> also get
> > to a point where it quits scanning. This happens after a few times of
> turning
> > off then on the AP on the router. When it quits scanning, monitor-iwd
> says
> > "Scanning = False" and it never rescans. The last time I ran it, the
> monitor-iwd
> > script says Scanning = True then immediately Scanning = False but
> nothing was
> > scanned. It did this 13 times but never 'saw' anything nor connected. If
> I down
> > then up the interface (ifconfig wlan0 down; ifconfig wlan0 up), it
> immediately
> > connects. I now have a script that runs when the interface goes down to
> do this
> > (down/up), but shouldn't iwd eventually reconnect?
>
> We would need to see iwd debug logs and iwmon logs to say much more...
>
> https://iwd.wiki.kernel.org/debugging
>
> >
> > This contrasts with the same computer running RasPiOS. It is running the
> same
> > kernel and the same broadcom firmware (I updated both to be identical)
> connman
> > is 1.36 and iwd is 0.14, but it works every time all the time. I cannot
> get it
> > to not reconnect. In fact it connects faster than the web page for the
> router
> > registers it.
> >
> > I built iwd 0.14 and connman 1.36 for the Arch install and it behaves
> the same
> > as iwd 1.9 or the iwd git master build. It just quits.
> >
> > What underlying OS issues could cause this? I have built the kernel with
> the
> > same config options for both installations with the same computer doing
> the
> > building. Both installations are using the same kernel, firmware and
> driver
> > version (built with the kernel).
> >
>
> Hard to say without logs...
>
> Regards,
> -Denis
>

[-- Attachment #2: attachment.htm --]
[-- Type: text/html, Size: 4726 bytes --]

[-- Attachment #3: journal_connman_log.txt --]
[-- Type: text/plain, Size: 13758 bytes --]

-- Logs begin at Sat 2020-10-03 13:13:07 CDT, end at Thu 2020-10-15 13:35:54 CDT. --
Oct 15 13:28:43 rune64 systemd[1]: Starting Connection service...
Oct 15 13:28:43 rune64 systemd[1]: Started Connection service.
Oct 15 13:28:43 rune64 connmand[6490]: Connection Manager version 1.38
Oct 15 13:28:43 rune64 connmand[6490]: Adding configuration wifi_4f70656e5772745f3530
Oct 15 13:28:43 rune64 connmand[6490]: Ignore group named 'global' because prefix is not 'service_'
Oct 15 13:28:43 rune64 connmand[6490]: Adding service configuration 4f70656e5772745f3530
Oct 15 13:28:43 rune64 connmand[6490]: Adding configuration
Oct 15 13:28:43 rune64 connmand[6490]: Unknown configuration key Type in [global]
Oct 15 13:28:43 rune64 connmand[6490]: Unknown configuration key IPv4 in [global]
Oct 15 13:28:43 rune64 connmand[6490]: Unknown configuration key IPv6 in [global]
Oct 15 13:28:43 rune64 connmand[6490]: Unknown configuration key IPv6.Privacy in [global]
Oct 15 13:28:43 rune64 connmand[6490]: Ignore group named 'global' because prefix is not 'service_'
Oct 15 13:28:43 rune64 connmand[6490]: Config file /var/lib/connman/.config does not contain any configuration that can be provisioned!
Oct 15 13:28:44 rune64 connmand[6490]: Checking loopback interface settings
Oct 15 13:28:44 rune64 connmand[6490]: System hostname is rune64
Oct 15 13:28:44 rune64 connmand[6490]: lo {newlink} index 1 address 00:00:00:00:00:00 mtu 65536
Oct 15 13:28:44 rune64 connmand[6490]: lo {newlink} index 1 operstate 0 <UNKNOWN>
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {create} index 2 type 1 <ETHER>
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {RX} 2132 packets 255103 bytes
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {TX} 1600 packets 519108 bytes
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {update} flags 36866 <DOWN>
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:28:44 rune64 connmand[6490]: Adding interface wlan0 [ wifi ]
Oct 15 13:28:44 rune64 connmand[6490]: eth0 {create} index 3 type 1 <ETHER>
Oct 15 13:28:44 rune64 connmand[6490]: eth0 {update} flags 4099 <UP>
Oct 15 13:28:44 rune64 connmand[6490]: eth0 {newlink} index 3 address B8:27:EB:07:99:85 mtu 1500
Oct 15 13:28:44 rune64 connmand[6490]: eth0 {newlink} index 3 operstate 2 <DOWN>
Oct 15 13:28:44 rune64 connmand[6490]: Adding interface eth0 [ ethernet ]
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {RX} 2132 packets 255103 bytes
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {TX} 1600 packets 519108 bytes
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {update} flags 36867 <UP>
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:28:44 rune64 connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {RX} 2133 packets 255202 bytes
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {TX} 1600 packets 519108 bytes
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {update} flags 102403 <UP,LOWER_UP>
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {newlink} index 2 operstate 5 <DORMANT>
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {RX} 2134 packets 255357 bytes
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {TX} 1602 packets 519404 bytes
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:28:47 rune64 connmand[6490]: wlan0 {newlink} index 2 operstate 6 <UP>
Oct 15 13:28:51 rune64_wl connmand[6490]: Setting hostname to rune64_wl
Oct 15 13:28:51 rune64_wl connmand[6490]: Setting domainname to griders
Oct 15 13:28:51 rune64_wl connmand[6490]: wlan0 {add} address 192.168.2.41/24 label wlan0 family 2
Oct 15 13:28:51 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:28:51 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.1 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:28:51 rune64_wl connmand[6490]: wlan0 {add} route 0.0.0.0 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:28:51 rune64_wl connmand[6490]: wlan0 {add} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:28:51 rune64_wl connmand[6490]: wlan0 {del} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:29:01 rune64_wl connmand[6490]: nlmon {newlink} index 6 address 00:00:00:00:00:00 mtu 3776
Oct 15 13:29:01 rune64_wl connmand[6490]: nlmon {newlink} index 6 operstate 0 <UNKNOWN>
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {RX} 2200 packets 262874 bytes
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {TX} 1644 packets 529467 bytes
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {update} flags 36867 <UP>
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {del} address 192.168.2.41/24 label wlan0
Oct 15 13:29:10 rune64_wl connmand[6490]: wlan0 {del} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:30:12 rune64_wl connmand[6490]: nlmon {newlink} index 6 address 00:00:00:00:00:00 mtu 3776
Oct 15 13:30:12 rune64_wl connmand[6490]: nlmon {newlink} index 6 operstate 2 <DOWN>
Oct 15 13:30:12 rune64_wl connmand[6490]: nlmon {dellink} index 6 operstate 2 <DOWN>
Oct 15 13:30:21 rune64_wl connmand[6490]: wlan0 {RX} 2200 packets 262874 bytes
Oct 15 13:30:21 rune64_wl connmand[6490]: wlan0 {TX} 1644 packets 529467 bytes
Oct 15 13:30:21 rune64_wl connmand[6490]: wlan0 {update} flags 36866 <DOWN>
Oct 15 13:30:21 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:30:21 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:30:26 rune64_wl connmand[6490]: wlan0 {RX} 2200 packets 262874 bytes
Oct 15 13:30:26 rune64_wl connmand[6490]: wlan0 {TX} 1644 packets 529467 bytes
Oct 15 13:30:26 rune64_wl connmand[6490]: wlan0 {update} flags 36867 <UP>
Oct 15 13:30:26 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:30:26 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {RX} 2201 packets 262973 bytes
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {TX} 1644 packets 529467 bytes
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {update} flags 102403 <UP,LOWER_UP>
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 5 <DORMANT>
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {RX} 2202 packets 263128 bytes
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {TX} 1646 packets 529763 bytes
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:30:29 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 6 <UP>
Oct 15 13:30:32 rune64_wl connmand[6490]: Setting hostname to rune64_wl
Oct 15 13:30:32 rune64_wl connmand[6490]: Setting domainname to griders
Oct 15 13:30:32 rune64_wl connmand[6490]: wlan0 {add} address 192.168.2.41/24 label wlan0 family 2
Oct 15 13:30:32 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:30:32 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.1 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:30:32 rune64_wl connmand[6490]: wlan0 {add} route 0.0.0.0 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:30:33 rune64_wl connmand[6490]: wlan0 {add} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:30:33 rune64_wl connmand[6490]: wlan0 {del} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:31:51 rune64_wl connmand[6490]: nlmon {newlink} index 7 address 00:00:00:00:00:00 mtu 3776
Oct 15 13:31:51 rune64_wl connmand[6490]: nlmon {newlink} index 7 operstate 0 <UNKNOWN>
Oct 15 13:32:00 rune64_wl connmand[6490]: wlan0 {RX} 2408 packets 287121 bytes
Oct 15 13:32:00 rune64_wl connmand[6490]: wlan0 {TX} 1715 packets 553057 bytes
Oct 15 13:32:00 rune64_wl connmand[6490]: wlan0 {update} flags 36867 <UP>
Oct 15 13:32:01 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:32:01 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:32:01 rune64_wl connmand[6490]: wlan0 {del} address 192.168.2.41/24 label wlan0
Oct 15 13:32:01 rune64_wl connmand[6490]: wlan0 {del} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {RX} 2409 packets 287220 bytes
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {TX} 1715 packets 553057 bytes
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {update} flags 102403 <UP,LOWER_UP>
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 5 <DORMANT>
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {RX} 2410 packets 287375 bytes
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {TX} 1717 packets 553353 bytes
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:32:15 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 6 <UP>
Oct 15 13:32:21 rune64_wl connmand[6490]: Setting hostname to rune64_wl
Oct 15 13:32:21 rune64_wl connmand[6490]: Setting domainname to griders
Oct 15 13:32:22 rune64_wl connmand[6490]: wlan0 {add} address 192.168.2.41/24 label wlan0 family 2
Oct 15 13:32:22 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:32:22 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.1 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:32:22 rune64_wl connmand[6490]: wlan0 {add} route 0.0.0.0 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:32:22 rune64_wl connmand[6490]: wlan0 {add} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:32:22 rune64_wl connmand[6490]: wlan0 {del} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:32:28 rune64_wl connmand[6490]: wlan0 {RX} 2438 packets 290195 bytes
Oct 15 13:32:28 rune64_wl connmand[6490]: wlan0 {TX} 1744 packets 560641 bytes
Oct 15 13:32:28 rune64_wl connmand[6490]: wlan0 {update} flags 36867 <UP>
Oct 15 13:32:30 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:32:30 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:32:30 rune64_wl connmand[6490]: wlan0 {del} address 192.168.2.41/24 label wlan0
Oct 15 13:32:30 rune64_wl connmand[6490]: wlan0 {del} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:34:19 rune64_wl connmand[6490]: nlmon {newlink} index 7 address 00:00:00:00:00:00 mtu 3776
Oct 15 13:34:19 rune64_wl connmand[6490]: nlmon {newlink} index 7 operstate 2 <DOWN>
Oct 15 13:34:19 rune64_wl connmand[6490]: nlmon {dellink} index 7 operstate 2 <DOWN>
Oct 15 13:35:30 rune64_wl connmand[6490]: wlan0 {RX} 2438 packets 290195 bytes
Oct 15 13:35:30 rune64_wl connmand[6490]: wlan0 {TX} 1744 packets 560641 bytes
Oct 15 13:35:30 rune64_wl connmand[6490]: wlan0 {update} flags 36866 <DOWN>
Oct 15 13:35:30 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:35:30 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:35:34 rune64_wl connmand[6490]: wlan0 {RX} 2438 packets 290195 bytes
Oct 15 13:35:34 rune64_wl connmand[6490]: wlan0 {TX} 1744 packets 560641 bytes
Oct 15 13:35:34 rune64_wl connmand[6490]: wlan0 {update} flags 36867 <UP>
Oct 15 13:35:34 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:35:34 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 2 <DOWN>
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {RX} 2439 packets 290294 bytes
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {TX} 1744 packets 560641 bytes
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {update} flags 102403 <UP,LOWER_UP>
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 5 <DORMANT>
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {RX} 2440 packets 290449 bytes
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {TX} 1746 packets 560937 bytes
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {update} flags 102467 <UP,RUNNING,LOWER_UP>
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {newlink} index 2 address B8:27:EB:52:CC:D0 mtu 1500
Oct 15 13:35:36 rune64_wl connmand[6490]: wlan0 {newlink} index 2 operstate 6 <UP>
Oct 15 13:35:40 rune64_wl connmand[6490]: Setting hostname to rune64_wl
Oct 15 13:35:40 rune64_wl connmand[6490]: Setting domainname to griders
Oct 15 13:35:40 rune64_wl connmand[6490]: wlan0 {add} address 192.168.2.41/24 label wlan0 family 2
Oct 15 13:35:40 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.0 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:35:40 rune64_wl connmand[6490]: wlan0 {add} route 192.168.2.1 gw 0.0.0.0 scope 253 <LINK>
Oct 15 13:35:40 rune64_wl connmand[6490]: wlan0 {add} route 0.0.0.0 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:35:40 rune64_wl connmand[6490]: wlan0 {add} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>
Oct 15 13:35:40 rune64_wl connmand[6490]: wlan0 {del} route 212.227.81.55 gw 192.168.2.1 scope 0 <UNIVERSE>

[-- Attachment #4: journal_iwd_log.txt --]
[-- Type: text/plain, Size: 94517 bytes --]

Oct 15 13:28:35 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 3
Oct 15 13:28:44 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_free()
Oct 15 13:28:44 rune64 iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
Oct 15 13:28:44 rune64 iwd[5002]: src/netdev.c:netdev_disconnect_event()
Oct 15 13:28:44 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for (global) is XX
Oct 15 13:28:44 rune64 iwd[5002]: src/agent.c:agent_register() agent register called
Oct 15 13:28:44 rune64 iwd[5002]: src/agent.c:agent_register() agent :1.228 path /net/connman/iwd_agent
Oct 15 13:28:44 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 15
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 15
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_dbus_scan() Scan called from DBus
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 16
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:28:44 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4630, strength: -5000
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "OpenWrt_50" security psk
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_autoconnect_next() Considering autoconnecting to BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4630, strength: -5000
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: autoconnect_full
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_periodic_start() Starting periodic scan for wdev 1
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 15 done
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 16
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_dbus_scan_triggered() station_scan_triggered: 0
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_dbus_scan_triggered() Scan triggered for wlan0
Oct 15 13:28:44 rune64 iwd[5002]: src/network.c:network_connect()
Oct 15 13:28:44 rune64 iwd[5002]: src/network.c:network_connect_psk() ask_passphrase: false
Oct 15 13:28:44 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 17
Oct 15 13:28:44 rune64 iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_full, new state: connecting
Oct 15 13:28:44 rune64 iwd[5002]: src/scan.c:scan_periodic_stop() Stopping periodic scan for wdev 1
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 29/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 29/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 29/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 29/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 29/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 37/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 37/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 9/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 39/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 39/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 123/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 123/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 38/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 66/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 151/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 135/255
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:28:47 rune64 iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8227, strength: -5000
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "spg3" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5247, strength: -3200
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "OpenWrt_25" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5187, strength: -3700
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "spg2" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4630, strength: -5000
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3086, strength: -6000
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 2704, strength: -6600
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "Edventures" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 2523, strength: -7200
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "NETGEAR63" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 2477, strength: -6800
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ATTXIXISpi" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2051, strength: -7700
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "HANNA" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1868, strength: -7900
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1752, strength: -8000
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1631, strength: -7800
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "BidenHarris" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1631, strength: -7800
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1455, strength: -8000
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ElJefe" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 1387, strength: -8100
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "Edventures3" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1209, strength: -8600
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a5' with SSID: NETGEAR63-5G, freq: 5765, rank: 1123, strength: -8700
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "NETGEAR63-5G" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:89' with SSID: , freq: 5180, rank: 1123, strength: -8700
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1036, strength: -8800
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ATTeHeTKI2" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8e' with SSID: BidenHarris, freq: 5180, rank: 950, strength: -8900
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:8b' with SSID: , freq: 5180, rank: 950, strength: -8900
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:8a' with SSID: XFINITY, freq: 5180, rank: 950, strength: -8900
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "XFINITY" security 8021x
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 936, strength: -8700
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:4e:01:43' with SSID: ATTxZIQ4wa, freq: 5805, rank: 936, strength: -8700
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ATTxZIQ4wa" security psk
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:4e:01:43' with SSID: , freq: 5805, rank: 864, strength: -8800
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'fa:8f:ca:93:36:35' with SSID: , freq: 2412, rank: 225, strength: -8500
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:88' with SSID: xfinitywifi, freq: 5180, rank: 216, strength: -8800
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "xfinitywifi" security open
Oct 15 13:28:47 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 16 done
Oct 15 13:28:47 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 17
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_netdev_event() Associating
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_connect_event()
Oct 15 13:28:47 rune64 iwd[5002]: src/eapol.c:eapol_handle_ptk_1_of_4() ifindex=2
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:28:47 rune64 iwd[5002]: src/eapol.c:eapol_handle_ptk_3_of_4() ifindex=2
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_set_gtk() 2
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_handshake_event() Setting keys
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_set_tk() 2
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_connect_cb() 2, result: 0
Oct 15 13:28:47 rune64 iwd[5002]: src/station.c:station_enter_state() Old State: connecting, new state: connected
Oct 15 13:28:47 rune64 iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 17 done
Oct 15 13:28:47 rune64 iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:29:10 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:29:10 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
Oct 15 13:29:10 rune64_wl iwd[5002]: src/netdev.c:netdev_disconnect_event()
Oct 15 13:29:10 rune64_wl iwd[5002]: Received Deauthentication event, reason: 3, from_ap: false
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_disconnect_event() 2
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_disassociated() 2
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connected, new state: disconnected
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 18
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 18
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:29:10 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for (global) is XX
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:29:10 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 37/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 37/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 9/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 39/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 39/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 123/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 123/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 38/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 66/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 151/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 135/255
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8227, strength: -5000
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5247, strength: -3200
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5187, strength: -3700
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4722, strength: -4900
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3086, strength: -6000
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 2704, strength: -6600
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 2523, strength: -7200
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 2477, strength: -6800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2051, strength: -7700
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1868, strength: -7900
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1752, strength: -8000
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1631, strength: -7800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1631, strength: -7800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1455, strength: -8000
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 1387, strength: -8100
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1209, strength: -8600
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a5' with SSID: NETGEAR63-5G, freq: 5765, rank: 1123, strength: -8700
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1036, strength: -8800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8e' with SSID: BidenHarris, freq: 5180, rank: 1036, strength: -8800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:8b' with SSID: , freq: 5180, rank: 1036, strength: -8800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:8a' with SSID: XFINITY, freq: 5180, rank: 950, strength: -8900
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 936, strength: -8700
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:4e:01:43' with SSID: ATTxZIQ4wa, freq: 5805, rank: 936, strength: -8700
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:4e:01:43' with SSID: , freq: 5805, rank: 864, strength: -8800
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:89' with SSID: , freq: 5180, rank: 777, strength: -9100
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'fa:8f:ca:93:36:35' with SSID: , freq: 2412, rank: 225, strength: -8500
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:88' with SSID: xfinitywifi, freq: 5180, rank: 198, strength: -8900
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_autoconnect_next() Considering autoconnecting to BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4722, strength: -4900
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: autoconnect_full
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_periodic_start() Starting periodic scan for wdev 1
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 19
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 18 done
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 19
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_periodic_triggered() Periodic scan triggered for wdev 1
Oct 15 13:29:10 rune64_wl iwd[5002]: src/network.c:network_connect()
Oct 15 13:29:10 rune64_wl iwd[5002]: src/network.c:network_connect_psk() ask_passphrase: false
Oct 15 13:29:10 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 20
Oct 15 13:29:10 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_full, new state: connecting
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_periodic_stop() Stopping periodic scan for wdev 1
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Trying to cancel scan id 19 for wdev 1
Oct 15 13:29:10 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Scan is at the top of the queue and triggered
Oct 15 13:29:12 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:29:12 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 19 done
Oct 15 13:29:12 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 20
Oct 15 13:29:12 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_netdev_event() Associating
Oct 15 13:29:12 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
Oct 15 13:29:12 rune64_wl iwd[5002]: src/netdev.c:netdev_connect_event()
Oct 15 13:29:12 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 20 done
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_connect_cb() 2, result: 2
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_disassociated() 2
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connecting, new state: disconnected
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:29:12 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 21
Oct 15 13:29:12 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 21
Oct 15 13:29:12 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:29:12 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_dbus_disconnect()
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: disconnected
Oct 15 13:29:12 rune64_wl iwd[5002]: src/station.c:station_dbus_disconnect()
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 37/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 37/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 9/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 32/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 32/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 123/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 123/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 46/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 62/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 45/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 55/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 70/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 173/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 162/255
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:29:13 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8063, strength: -5100
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5187, strength: -3700
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5093, strength: -3400
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4722, strength: -4900
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3009, strength: -6100
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 2783, strength: -6500
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 2433, strength: -7300
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2343, strength: -7400
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 2140, strength: -7600
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1868, strength: -7900
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1868, strength: -7900
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 1858, strength: -7500
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1746, strength: -8000
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1631, strength: -7800
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1631, strength: -7800
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 1387, strength: -8100
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1209, strength: -8600
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a5' with SSID: NETGEAR63-5G, freq: 5765, rank: 1123, strength: -8700
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1036, strength: -8800
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8e' with SSID: BidenHarris, freq: 5180, rank: 1036, strength: -8800
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:8a' with SSID: XFINITY, freq: 5180, rank: 950, strength: -8900
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:89' with SSID: , freq: 5180, rank: 950, strength: -8900
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:4e:01:43' with SSID: ATTxZIQ4wa, freq: 5805, rank: 936, strength: -8700
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:4e:01:43' with SSID: , freq: 5805, rank: 864, strength: -8800
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:8b' with SSID: , freq: 5180, rank: 864, strength: -9000
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'fa:8f:ca:93:36:35' with SSID: , freq: 2412, rank: 225, strength: -8500
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:29:13 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:88' with SSID: xfinitywifi, freq: 5180, rank: 216, strength: -8800
Oct 15 13:29:13 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 21 done
Oct 15 13:29:13 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:21 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:21 rune64_wl iwd[5002]: src/station.c:station_free()
Oct 15 13:30:26 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:26 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:30:26 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 22
Oct 15 13:30:26 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 22
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:30:26 rune64_wl iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:30:26 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 6/255
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:30:26 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4630, strength: -5000
Oct 15 13:30:26 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "OpenWrt_50" security psk
Oct 15 13:30:26 rune64_wl iwd[5002]: src/station.c:station_autoconnect_next() Considering autoconnecting to BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4630, strength: -5000
Oct 15 13:30:26 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: autoconnect_full
Oct 15 13:30:26 rune64_wl iwd[5002]: src/scan.c:scan_periodic_start() Starting periodic scan for wdev 1
Oct 15 13:30:26 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 23
Oct 15 13:30:26 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 22 done
Oct 15 13:30:26 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 23
Oct 15 13:30:27 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:30:27 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:30:27 rune64_wl iwd[5002]: src/scan.c:scan_periodic_triggered() Periodic scan triggered for wdev 1
Oct 15 13:30:27 rune64_wl iwd[5002]: src/network.c:network_connect()
Oct 15 13:30:27 rune64_wl iwd[5002]: src/network.c:network_connect_psk() ask_passphrase: false
Oct 15 13:30:27 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 24
Oct 15 13:30:27 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_full, new state: connecting
Oct 15 13:30:27 rune64_wl iwd[5002]: src/scan.c:scan_periodic_stop() Stopping periodic scan for wdev 1
Oct 15 13:30:27 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Trying to cancel scan id 23 for wdev 1
Oct 15 13:30:27 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Scan is at the top of the queue and triggered
Oct 15 13:30:29 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:30:29 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 23 done
Oct 15 13:30:29 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 24
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/station.c:station_netdev_event() Associating
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_connect_event()
Oct 15 13:30:29 rune64_wl iwd[5002]: src/eapol.c:eapol_handle_ptk_1_of_4() ifindex=2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/eapol.c:eapol_handle_ptk_3_of_4() ifindex=2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_set_gtk() 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/station.c:station_handshake_event() Setting keys
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_set_tk() 2
Oct 15 13:30:29 rune64_wl iwd[5002]: src/station.c:station_connect_cb() 2, result: 0
Oct 15 13:30:29 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connecting, new state: connected
Oct 15 13:30:29 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 24 done
Oct 15 13:30:29 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:00 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:00 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
Oct 15 13:32:00 rune64_wl iwd[5002]: src/netdev.c:netdev_disconnect_event()
Oct 15 13:32:00 rune64_wl iwd[5002]: Received Deauthentication event, reason: 3, from_ap: false
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:station_disconnect_event() 2
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:station_disassociated() 2
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connected, new state: disconnected
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 25
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 25
Oct 15 13:32:00 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for (global) is XX
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:process_network() No remaining BSSs for SSID: OpenWrt_50 -- Removing network
Oct 15 13:32:00 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: autoconnect_full
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_periodic_start() Starting periodic scan for wdev 1
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 26
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 25 done
Oct 15 13:32:00 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 26
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:00 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:00 rune64_wl iwd[5002]: src/scan.c:scan_periodic_triggered() Periodic scan triggered for wdev 1
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 33/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 33/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 47/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 47/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 50/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 57/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 48/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 24/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 173/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 156/255
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:32:03 rune64_wl iwd[5002]: src/scan.c:scan_periodic_rearm() Arming periodic scan timer: 10
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8227, strength: -5000
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "spg3" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5170, strength: -3300
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "OpenWrt_25" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5104, strength: -3800
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "spg2" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3318, strength: -5700
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 2704, strength: -6600
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "Edventures" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 2433, strength: -7300
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "NETGEAR63" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2343, strength: -7400
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "HANNA" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 1952, strength: -7400
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ATTXIXISpi" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1868, strength: -7900
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1868, strength: -7900
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:8e' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1659, strength: -8100
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ElJefe" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1557, strength: -7900
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "BidenHarris" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 1382, strength: -8400
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ATTeHeTKI2" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 1314, strength: -8200
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "Edventures3" security psk
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1123, strength: -8700
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1036, strength: -8800
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:33' with SSID: , freq: 5220, rank: 777, strength: -9100
Oct 15 13:32:03 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:03 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 26 done
Oct 15 13:32:03 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:13 rune64_wl iwd[5002]: src/scan.c:scan_periodic_timeout() scan_periodic_timeout: 1
Oct 15 13:32:13 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 27
Oct 15 13:32:13 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 27
Oct 15 13:32:13 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:13 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:13 rune64_wl iwd[5002]: src/scan.c:scan_periodic_triggered() Periodic scan triggered for wdev 1
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 42/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 42/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 40/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 64/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 52/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 19/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 151/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 169/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 0/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_periodic_rearm() Arming periodic scan timer: 20
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8227, strength: -5000
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5170, strength: -3300
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5022, strength: -3900
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4537, strength: -5100
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "OpenWrt_50" security psk
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3086, strength: -6000
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 2783, strength: -6500
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2343, strength: -7400
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 2343, strength: -7400
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1941, strength: -7800
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1868, strength: -7900
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1752, strength: -8000
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:8e' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 1618, strength: -7800
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1557, strength: -7900
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 1382, strength: -8100
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1209, strength: -8600
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1209, strength: -8600
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '86:bb:69:71:19:33' with SSID: ElJefe3, freq: 5220, rank: 1036, strength: -8800
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "ElJefe3" security psk
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:33' with SSID: , freq: 5220, rank: 950, strength: -8900
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 936, strength: -8700
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_autoconnect_next() Considering autoconnecting to BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4537, strength: -5100
Oct 15 13:32:15 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 27 done
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/network.c:network_connect()
Oct 15 13:32:15 rune64_wl iwd[5002]: src/network.c:network_connect_psk() ask_passphrase: false
Oct 15 13:32:15 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 28
Oct 15 13:32:15 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 28
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_full, new state: connecting
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_periodic_stop() Stopping periodic scan for wdev 1
Oct 15 13:32:15 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Trying to cancel scan id 27 for wdev 1
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_netdev_event() Associating
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_connect_event()
Oct 15 13:32:15 rune64_wl iwd[5002]: src/eapol.c:eapol_handle_ptk_1_of_4() ifindex=2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/eapol.c:eapol_handle_ptk_3_of_4() ifindex=2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_set_gtk() 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_handshake_event() Setting keys
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_set_tk() 2
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_connect_cb() 2, result: 0
Oct 15 13:32:15 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connecting, new state: connected
Oct 15 13:32:15 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 28 done
Oct 15 13:32:15 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:28 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Disconnect(48)
Oct 15 13:32:28 rune64_wl iwd[5002]: src/netdev.c:netdev_disconnect_event()
Oct 15 13:32:28 rune64_wl iwd[5002]: Received Deauthentication event, reason: 3, from_ap: false
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_disconnect_event() 2
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_disassociated() 2
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connected, new state: disconnected
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 29
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 29
Oct 15 13:32:28 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:28 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_reg_notify() Notification of command Reg Change(36)
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_update_reg_domain() New reg domain country code for (global) is XX
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:28 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 42/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 42/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 40/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 64/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 52/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 19/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 151/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 169/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 0/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 0/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8227, strength: -5000
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5170, strength: -3300
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5022, strength: -3900
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4537, strength: -5100
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3086, strength: -6000
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 2783, strength: -6500
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2343, strength: -7400
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 2343, strength: -7400
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1941, strength: -7800
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1868, strength: -7900
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1752, strength: -8000
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:8e' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 1618, strength: -7800
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1557, strength: -7900
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 1382, strength: -8100
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1209, strength: -8600
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1209, strength: -8600
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '86:bb:69:71:19:33' with SSID: ElJefe3, freq: 5220, rank: 1036, strength: -8800
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:33' with SSID: , freq: 5220, rank: 950, strength: -8900
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 936, strength: -8700
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_autoconnect_next() Considering autoconnecting to BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4537, strength: -5100
Oct 15 13:32:28 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: autoconnect_full
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_periodic_start() Starting periodic scan for wdev 1
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 30
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 29 done
Oct 15 13:32:28 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 30
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:28 rune64_wl iwd[5002]: src/scan.c:scan_periodic_triggered() Periodic scan triggered for wdev 1
Oct 15 13:32:30 rune64_wl iwd[5002]: src/network.c:network_connect()
Oct 15 13:32:30 rune64_wl iwd[5002]: src/network.c:network_connect_psk() ask_passphrase: false
Oct 15 13:32:30 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 31
Oct 15 13:32:30 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_full, new state: connecting
Oct 15 13:32:30 rune64_wl iwd[5002]: src/scan.c:scan_periodic_stop() Stopping periodic scan for wdev 1
Oct 15 13:32:30 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Trying to cancel scan id 30 for wdev 1
Oct 15 13:32:30 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Scan is at the top of the queue and triggered
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 30 done
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 31
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_netdev_event() Associating
Oct 15 13:32:31 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:31 rune64_wl iwd[5002]: src/netdev.c:netdev_mlme_notify() MLME notification Connect(46)
Oct 15 13:32:31 rune64_wl iwd[5002]: src/netdev.c:netdev_connect_event()
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 31 done
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_connect_cb() 2, result: 2
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_disassociated() 2
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: connecting, new state: disconnected
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: disconnected, new state: autoconnect_quick
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 32
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 32
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_quick_scan_triggered() Quick scan triggered for wlan0
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 43/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 43/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 99/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 95/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 65/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 34/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 56/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 56/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_advertisement_protocol()
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 69/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 151/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 168/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 0/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 0/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 8/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 49/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_parse_bss_information_elements() Load: 49/255
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_callback() get_scan_callback
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:get_scan_done() get_scan_done
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:97' with SSID: spg3, freq: 5825, rank: 8227, strength: -5000
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '08:02:8e:94:12:98' with SSID: spg2, freq: 2452, rank: 5187, strength: -3700
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:70' with SSID: OpenWrt_25, freq: 2462, rank: 5016, strength: -3500
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:0d:7f:52:8a:72' with SSID: OpenWrt_50, freq: 5180, rank: 4537, strength: -5100
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a0' with SSID: Edventures, freq: 2412, rank: 3104, strength: -6200
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'ac:3a:7a:0c:83:0b' with SSID: , freq: 2462, rank: 3086, strength: -6000
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '04:d9:f5:bb:b4:58' with SSID: HANNA, freq: 2427, rank: 2343, strength: -7400
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f4:c1:14:b2:4d:8d' with SSID: BidenHarris, freq: 2437, rank: 1957, strength: -7800
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:88' with SSID: , freq: 2437, rank: 1868, strength: -7900
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '46:2b:50:95:61:63' with SSID: ATTXIXISpi, freq: 5785, rank: 1868, strength: -7900
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:da:43:a0:f5:a4' with SSID: NETGEAR63, freq: 2412, rank: 1858, strength: -7500
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:60' with SSID: ATTXIXISpi, freq: 2462, rank: 1783, strength: -7600
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '40:2b:50:95:61:63' with SSID: , freq: 5785, rank: 1752, strength: -8000
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:b3:4d:8e' with SSID: , freq: 2437, rank: 1705, strength: -7700
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:30' with SSID: ElJefe, freq: 2412, rank: 1544, strength: -7900
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '6c:19:8f:ce:6f:a2' with SSID: Edventures3, freq: 5765, rank: 1224, strength: -8300
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '06:93:97:09:ca:93' with SSID: ATTeHeTKI2, freq: 5765, rank: 1209, strength: -8600
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:93' with SSID: , freq: 5765, rank: 1209, strength: -8600
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '10:93:97:09:ca:90' with SSID: ATTeHeTKI2, freq: 2412, rank: 1152, strength: -8400
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '86:bb:69:71:19:33' with SSID: ElJefe3, freq: 5220, rank: 1036, strength: -8800
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS '84:bb:69:71:19:33' with SSID: , freq: 5220, rank: 950, strength: -8900
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:89' with SSID: , freq: 5180, rank: 777, strength: -9100
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'fa:8f:ca:93:36:35' with SSID: , freq: 2412, rank: 270, strength: -8200
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() BSS has hidden SSID
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Processing BSS 'f6:c1:14:ba:4d:88' with SSID: xfinitywifi, freq: 5180, rank: 180, strength: -9000
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_add_seen_bss() Added new Network "xfinitywifi" security open
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_quick, new state: autoconnect_full
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_periodic_start() Starting periodic scan for wdev 1
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_insert() Inserting work item 33
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 32 done
Oct 15 13:32:31 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_next() Starting work item 33
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification Trigger Scan(33)
Oct 15 13:32:31 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_request_triggered() Passive scan triggered for wdev 1
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_periodic_triggered() Periodic scan triggered for wdev 1
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_dbus_disconnect()
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_enter_state() Old State: autoconnect_full, new state: disconnected
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_periodic_stop() Stopping periodic scan for wdev 1
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Trying to cancel scan id 33 for wdev 1
Oct 15 13:32:31 rune64_wl iwd[5002]: src/scan.c:scan_cancel() Scan is at the top of the queue and triggered
Oct 15 13:32:31 rune64_wl iwd[5002]: src/station.c:station_dbus_disconnect()
Oct 15 13:32:33 rune64_wl iwd[5002]: src/scan.c:scan_notify() Scan notification New Scan Results(34)
Oct 15 13:32:33 rune64_wl iwd[5002]: src/wiphy.c:wiphy_radio_work_done() Work item 33 done
Oct 15 13:32:33 rune64_wl iwd[5002]: src/netdev.c:netdev_link_notify() event 16 on ifindex 2

[-- Attachment #5: no_reconnect.pcap --]
[-- Type: application/octet-stream, Size: 93098 bytes --]

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: iwd problems on Arch Linux
  2020-10-15 18:54   ` KeithG
@ 2020-10-15 19:58     ` Denis Kenzior
  2020-10-16  7:16       ` Daniel Wagner
  0 siblings, 1 reply; 7+ messages in thread
From: Denis Kenzior @ 2020-10-15 19:58 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 821 bytes --]

Hi Keith,

> These logs contain an SSID off/on and a reconnect event (as desired) then 
> followed immediately with an SSID off/on and a no-reconnect event. I grabbed the 
> journal log from iwd and connman and also the pcap in case any/all may be 
> useful. I was able to see content that scrolled by on the screen in the pcap 
> with 'iwmon -F 0x17 -r no_reconnect.pcap' (I think you mentioned this previously).

As I've said before, connman is invoking Station.Disconnect which prevents iwd 
from reconnecting autonomously.  There's really nothing we can do about that. 
Any issues are likely in connman.git/plugins/iwd.c

Can you provide me logs of this scenario without connman (with systemd-networkd 
or iwd with EnableNetworkConfiguration=true).  That I might be able to help with.

Regards,
-Denis

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: iwd problems on Arch Linux
  2020-10-15 19:58     ` Denis Kenzior
@ 2020-10-16  7:16       ` Daniel Wagner
  2020-10-16 16:00         ` Denis Kenzior
  0 siblings, 1 reply; 7+ messages in thread
From: Daniel Wagner @ 2020-10-16  7:16 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 405 bytes --]

On Thu, Oct 15, 2020 at 02:58:04PM -0500, Denis Kenzior wrote:
> As I've said before, connman is invoking Station.Disconnect which prevents
> iwd from reconnecting autonomously.  There's really nothing we can do about
> that. Any issues are likely in connman.git/plugins/iwd.c

Does Station.Disconnect and AutoConnect=false disable the iwd's scan
engine? The pluning relies on getting SSIDs updates.

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: iwd problems on Arch Linux
  2020-10-16  7:16       ` Daniel Wagner
@ 2020-10-16 16:00         ` Denis Kenzior
  2020-10-19  7:04           ` Daniel Wagner
  0 siblings, 1 reply; 7+ messages in thread
From: Denis Kenzior @ 2020-10-16 16:00 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 1633 bytes --]

Hi Daniel,

On 10/16/20 2:16 AM, Daniel Wagner wrote:
> On Thu, Oct 15, 2020 at 02:58:04PM -0500, Denis Kenzior wrote:
>> As I've said before, connman is invoking Station.Disconnect which prevents
>> iwd from reconnecting autonomously.  There's really nothing we can do about
>> that. Any issues are likely in connman.git/plugins/iwd.c
> 
> Does Station.Disconnect and AutoConnect=false disable the iwd's scan
> engine? The pluning relies on getting SSIDs updates.
> 

So iwd is built to scan the least amount possible, since scanning interrupts 
your link (radio has to physically switch off the channel, so your packets are 
lost in the meantime).  We scan only when in 'Autoconnect' state and when signal 
strength gets so low we need to try and roam.  If the network supports neighbor 
reports or other advanced BSS mangement features, we use those to minimize the 
set of channels we scan.

In Autoconnect mode (and only in Autoconnect mode when we're not connected 
anywhere else) iwd scans periodically.  If a network is marked 
AutoConnect=false, then that network is not considered as a candidate in this stage.

Station.Disconnect tells iwd to disconnect from whatever it is connected to and 
not enter into its Autoconnect mode.  This is essentially used to tell iwd that 
some external application wants to control how scanning  & autoconnect logic is 
performed.

In general, I would encourage using iwd's autoconnect logic since it can 
optimize the scan parameters and reconnect quickly.  This is why you see iwd 
re-connect to networks about 10x faster than NM+wpa_s.

Regards,
-Denis

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: iwd problems on Arch Linux
  2020-10-16 16:00         ` Denis Kenzior
@ 2020-10-19  7:04           ` Daniel Wagner
  0 siblings, 0 replies; 7+ messages in thread
From: Daniel Wagner @ 2020-10-19  7:04 UTC (permalink / raw)
  To: iwd

[-- Attachment #1: Type: text/plain, Size: 1767 bytes --]

Hi Denis,

On Fri, Oct 16, 2020 at 11:00:32AM -0500, Denis Kenzior wrote:
> So iwd is built to scan the least amount possible, since scanning interrupts
> your link (radio has to physically switch off the channel, so your packets
> are lost in the meantime).  We scan only when in 'Autoconnect' state and
> when signal strength gets so low we need to try and roam.  If the network
> supports neighbor reports or other advanced BSS mangement features, we use
> those to minimize the set of channels we scan.

Okay, that explains the behavior Keith is reporting.

> In Autoconnect mode (and only in Autoconnect mode when we're not connected
> anywhere else) iwd scans periodically.  If a network is marked
> AutoConnect=false, then that network is not considered as a candidate in
> this stage.
> 
> Station.Disconnect tells iwd to disconnect from whatever it is connected to
> and not enter into its Autoconnect mode.  This is essentially used to tell
> iwd that some external application wants to control how scanning  &
> autoconnect logic is performed.
> 
> In general, I would encourage using iwd's autoconnect logic since it can
> optimize the scan parameters and reconnect quickly.  This is why you see iwd
> re-connect to networks about 10x faster than NM+wpa_s.

I get your point and it totally makes sense if you only consider only
one uplink (technology). I would even go further and say, don't use
ConnMan if you only want to use WiFi.

But it gets a bit more tricky for the use case: use WiFi if possible and
fallback to cellular network if it's not available. Maybe it is possible
to enable the autoconnect mode. I haven't really thought it through yet.

At least I know now why we have these problems.

Thanks,
Daniel

^ permalink raw reply	[flat|nested] 7+ messages in thread

end of thread, other threads:[~2020-10-19  7:04 UTC | newest]

Thread overview: 7+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2020-10-14 23:52 iwd problems on Arch Linux KeithG
2020-10-15 16:48 ` Denis Kenzior
2020-10-15 18:54   ` KeithG
2020-10-15 19:58     ` Denis Kenzior
2020-10-16  7:16       ` Daniel Wagner
2020-10-16 16:00         ` Denis Kenzior
2020-10-19  7:04           ` Daniel Wagner

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.