[Ovmsdev] WiFi client not switching to better AP

Tue Dec 15 13:57:57 HKT 2020

On Mon, 7 Dec 2020, Michael Balzer wrote:

> If you don't mind frequent disconnects in poor Wifi situations, a simple
> solution now is to attach a "wifi reconnect" command call to the
> "network.wifi.sta.bad" event. I added that command on the Wifi rework some
> months ago.

I tested this today with the three APs in my home but ran into
trouble.  It looks like the event was emitted again at the end of the
reconnection process so another reconnect was performed and it kept
looping.  See the attached log.  I have not investigated this
carefully.

> For a better, general solution, we could e.g. count the "network.wifi.sta.bad"
> events on the same network (or better: check the event frequency) and
> disconnect when reaching some (tunable) threshold. The next automatic scan
> then connects to the best network available. Getting stuck in the "bad signal"
> state for too long would also need to trigger a disconnect.

I'll look at this further to see whether a smooth transition from one
AP to another on the same network is possible.

                                                        -- Steve
-------------- next part --------------
OVMS# wifi status
Power: on
Mode: Client mode

STA SSID: Revelstoke (-50.1 dBm) [fixed]
  MAC: 30:ae:a4:37:1b:84
  IP: 192.168.1.95/255.255.255.0
  GW: 192.168.1.254
  AP: 78:d2:94:80:25:18
I (4195086) netmanager: WIFI client has bad signal quality (-89.3 dBm); disconnect
I (4195086) netmanager: WIFI client down (with MODEM down): network connectivity has been lost
I (4195096) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4195096) wifi:state: run -> init (0)
I (4195096) wifi:pm stop, total sleep time: 61595114 us / 76356942 us

I (4195096) wifi:new:<10,0>, old:<10,0>, ap:<255,255>, sta:<10,0>, prof:1
I (4195096) script: Starting Wifi client reconnect.
I (4195106) time: Stopping SNTP client
I (4195116) netmanager: WIFI client stop
I (4195116) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4195196) webserver: Stopping Web Server
I (4195196) telnet: Stopping Telnet Server
I (4195196) ssh: Stopping SSH Server
I (4200216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='14:59:c0:8f:ac:e0' chan=9 rssi=-73
I (4200726) wifi:new:<9,0>, old:<10,0>, ap:<255,255>, sta:<9,0>, prof:1
I (4201296) wifi:state: init -> auth (b0)
I (4201296) wifi:state: auth -> assoc (0)
I (4201306) wifi:state: assoc -> run (10)
I (4201306) housekeeping: 2020-12-14 18:43:51 PST (RAM: 8b=16412-28916 32b=36260)
I (4201916) wifi:connected with Revelstoke, aid = 4, channel 9, BW20, bssid = 14:59:c0:8f:ac:e0
I (4201926) wifi:security type: 3, phy: bgn, rssi: -75
I (4201926) wifi:pm start, type: 1

I (4201926) esp32wifi: STA connected with SSID: Revelstoke, BSSID: 14:59:c0:8f:ac:e0, Channel: 9, Auth: WPA2
I (4201936) wifi:AP's beacon interval = 102400 us, DTIM period = 2
I (4202976) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4202986) netmanager: WIFI client got IP
I (4202986) esp32wifi: STA got IP with SSID 'Revelstoke' AP 14:59:c0:8f:ac:e0: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4203006) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4203006) wifi:state: run -> init (0)
I (4203006) wifi:pm stop, total sleep time: 788144 us / 1085311 us

I (4203006) wifi:new:<9,0>, old:<9,0>, ap:<255,255>, sta:<9,0>, prof:1
I (4203016) script: Starting Wifi client reconnect.
I (4203016) netmanager: WIFI client stop
I (4203016) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4210816) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-70
I (4211326) wifi:new:<6,0>, old:<9,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4211326) wifi:state: init -> auth (b0)
I (4211336) wifi:state: auth -> assoc (0)
I (4211346) wifi:state: assoc -> run (10)
I (4211396) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4211396) wifi:security type: 3, phy: bgn, rssi: -70
I (4211396) wifi:pm start, type: 1

I (4211406) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4211446) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4212496) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4212506) netmanager: WIFI client got IP
I (4212506) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4212526) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4212526) wifi:state: run -> init (0)
I (4212526) wifi:pm stop, total sleep time: 766246 us / 1134250 us

I (4212526) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4212536) script: Starting Wifi client reconnect.
I (4212536) netmanager: WIFI client stop
I (4212536) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4219916) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='14:59:c0:8f:ac:e0' chan=9 rssi=-73
I (4220416) wifi:new:<9,0>, old:<6,0>, ap:<255,255>, sta:<9,0>, prof:1
I (4220426) wifi:state: init -> auth (b0)
I (4220426) wifi:state: auth -> assoc (0)
I (4220426) wifi:state: assoc -> run (10)
I (4221076) wifi:connected with Revelstoke, aid = 4, channel 9, BW20, bssid = 14:59:c0:8f:ac:e0
I (4221076) wifi:security type: 3, phy: bgn, rssi: -79
I (4221076) wifi:pm start, type: 1

I (4221086) esp32wifi: STA connected with SSID: Revelstoke, BSSID: 14:59:c0:8f:ac:e0, Channel: 9, Auth: WPA2
I (4221086) wifi:AP's beacon interval = 102400 us, DTIM period = 2
I (4222846) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4222856) netmanager: WIFI client got IP
I (4222856) esp32wifi: STA got IP with SSID 'Revelstoke' AP 14:59:c0:8f:ac:e0: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4222876) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4222876) wifi:state: run -> init (0)
I (4222876) wifi:pm stop, total sleep time: 1263543 us / 1803805 us

I (4222876) wifi:new:<9,0>, old:<9,0>, ap:<255,255>, sta:<9,0>, prof:1
I (4222886) script: Starting Wifi client reconnect.
I (4222886) netmanager: WIFI client stop
I (4222886) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4230216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-67
I (4230716) wifi:new:<6,0>, old:<9,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4230726) wifi:state: init -> auth (b0)
I (4230736) wifi:state: auth -> assoc (0)
I (4230736) wifi:state: assoc -> run (10)
I (4230776) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4230776) wifi:security type: 3, phy: bgn, rssi: -67
I (4230776) wifi:pm start, type: 1

I (4230786) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4230796) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4231936) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4231946) netmanager: WIFI client got IP
I (4231946) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4231966) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4231966) wifi:state: run -> init (0)
I (4231966) wifi:pm stop, total sleep time: 822959 us / 1188322 us

I (4231966) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4231976) script: Starting Wifi client reconnect.
I (4231976) netmanager: WIFI client stop
I (4231976) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4240216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-67
I (4240716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4240726) wifi:state: init -> auth (b0)
I (4240726) wifi:state: auth -> assoc (0)
I (4240736) wifi:state: assoc -> run (10)
I (4240766) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4240776) wifi:security type: 3, phy: bgn, rssi: -67
I (4240776) wifi:pm start, type: 1

I (4240776) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4240836) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4241906) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4241906) netmanager: WIFI client got IP
I (4241916) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4241926) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4241926) wifi:state: run -> init (0)
I (4241936) wifi:pm stop, total sleep time: 809677 us / 1158077 us

I (4241936) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4241936) script: Starting Wifi client reconnect.
I (4241936) netmanager: WIFI client stop
I (4241936) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4250216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-66
I (4250716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4250726) wifi:state: init -> auth (b0)
I (4250726) wifi:state: auth -> assoc (0)
I (4250736) wifi:state: assoc -> run (10)
I (4250776) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4250776) wifi:security type: 3, phy: bgn, rssi: -67
I (4250776) wifi:pm start, type: 1

I (4250786) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4250866) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4251936) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4251936) netmanager: WIFI client got IP
I (4251946) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4251956) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4251956) wifi:state: run -> init (0)
I (4251966) wifi:pm stop, total sleep time: 902435 us / 1185695 us

I (4251966) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4251966) script: Starting Wifi client reconnect.
I (4251966) netmanager: WIFI client stop
I (4251966) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4255096) event: station ip lost
I (4255096) esp32wifi: STA lost IP from SSID 'Revelstoke' AP 00:00:00:00:00:00
I (4260816) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-66
I (4261316) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4261326) wifi:state: init -> auth (b0)
I (4261336) wifi:state: auth -> assoc (0)
I (4261346) wifi:state: assoc -> run (10)
I (4261396) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4261396) wifi:security type: 3, phy: bgn, rssi: -66
I (4261396) wifi:pm start, type: 1

I (4261406) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4261416) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4262456) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4262466) netmanager: WIFI client got IP
I (4262466) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4262486) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4262486) wifi:state: run -> init (0)
I (4262486) wifi:pm stop, total sleep time: 749074 us / 1089599 us

I (4262486) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4262496) script: Starting Wifi client reconnect.
I (4262496) netmanager: WIFI client stop
I (4262496) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4270216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-59
I (4270716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4270726) wifi:state: init -> auth (b0)
I (4270736) wifi:state: auth -> assoc (0)
I (4270736) wifi:state: assoc -> run (10)
I (4270776) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4270776) wifi:security type: 3, phy: bgn, rssi: -70
I (4270776) wifi:pm start, type: 1

I (4270786) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4270836) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4271916) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4271926) netmanager: WIFI client got IP
I (4271926) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4271946) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4271946) wifi:state: run -> init (0)
I (4271946) wifi:pm stop, total sleep time: 798673 us / 1168537 us

I (4271946) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4271946) script: Starting Wifi client reconnect.
I (4271956) netmanager: WIFI client stop
I (4271956) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4279916) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-67
I (4280416) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4280416) wifi:state: init -> auth (b0)
I (4280426) wifi:state: auth -> assoc (0)
I (4280436) wifi:state: assoc -> run (10)
I (4280466) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4280466) wifi:security type: 3, phy: bgn, rssi: -59
I (4280466) wifi:pm start, type: 1

I (4280476) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4280566) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4281606) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4281606) netmanager: WIFI client got IP
I (4281616) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4281626) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4281626) wifi:state: run -> init (0)
I (4281636) wifi:pm stop, total sleep time: 888947 us / 1162641 us

I (4281636) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4281636) script: Starting Wifi client reconnect.
I (4281646) netmanager: WIFI client stop
I (4281646) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
OVMS# wifi status
Power: on
Mode: Client mode

STA SSID: Revelstoke (-127.0 dBm) [fixed]
  MAC: 30:ae:a4:37:1b:84
  IP: 0.0.0.0/0.0.0.0
  GW: 0.0.0.0
  AP: 00:00:00:00:00:00
I (4290216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-60
I (4290716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4290726) wifi:state: init -> auth (b0)
I (4290736) wifi:state: auth -> assoc (0)
I (4290736) wifi:state: assoc -> run (10)
I (4290766) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4290776) wifi:security type: 3, phy: bgn, rssi: -62
I (4290776) wifi:pm start, type: 1

I (4290776) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4290806) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4291846) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4291856) netmanager: WIFI client got IP
I (4291856) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4291876) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4291876) wifi:state: run -> init (0)
I (4291876) wifi:pm stop, total sleep time: 711194 us / 1104811 us

I (4291876) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4291886) script: Starting Wifi client reconnect.
I (4291886) netmanager: WIFI client stop
I (4291886) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4300216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-59
I (4300716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4300726) wifi:state: init -> auth (b0)
I (4300726) wifi:state: auth -> assoc (0)
I (4300736) wifi:state: assoc -> run (10)
I (4300786) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4300786) wifi:security type: 3, phy: bgn, rssi: -59
I (4300786) wifi:pm start, type: 1

I (4300796) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4300836) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4301936) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4301936) netmanager: WIFI client got IP
I (4301936) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4301956) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4301956) wifi:state: run -> init (0)
I (4301956) wifi:pm stop, total sleep time: 797689 us / 1173917 us

I (4301956) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4301966) script: Starting Wifi client reconnect.
I (4301966) netmanager: WIFI client stop
I (4301966) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
OVMS# wifi reconnect
Starting Wifi client reconnect.
I (4313216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-62
I (4313716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4313726) wifi:state: init -> auth (b0)
I (4313736) wifi:state: auth -> assoc (0)
I (4313736) wifi:state: assoc -> run (10)
I (4313766) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4313766) wifi:security type: 3, phy: bgn, rssi: -61
I (4313776) wifi:pm start, type: 1

I (4313776) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4313846) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4314916) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4314916) netmanager: WIFI client got IP
I (4314916) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4314936) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4314936) wifi:state: run -> init (0)
I (4314936) wifi:pm stop, total sleep time: 820198 us / 1166990 us

I (4314936) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4314946) script: Starting Wifi client reconnect.
I (4314956) netmanager: WIFI client stop
I (4314956) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4322486) event: station ip lost
I (4322486) esp32wifi: STA lost IP from SSID 'Revelstoke' AP 00:00:00:00:00:00
I (4322916) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-60
I (4323416) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4323416) wifi:state: init -> auth (b0)
I (4323436) wifi:state: auth -> assoc (0)
I (4323436) wifi:state: assoc -> run (10)
I (4323476) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4323476) wifi:security type: 3, phy: bgn, rssi: -61
I (4323476) wifi:pm start, type: 1

I (4323486) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4323576) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4324616) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4324616) netmanager: WIFI client got IP
I (4324616) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4324636) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4324636) wifi:state: run -> init (0)
I (4324636) wifi:pm stop, total sleep time: 907300 us / 1164134 us

I (4324646) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4324646) script: Starting Wifi client reconnect.
I (4324656) netmanager: WIFI client stop
I (4324656) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4333216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-61
I (4333716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4333726) wifi:state: init -> auth (b0)
I (4333736) wifi:state: auth -> assoc (0)
I (4333736) wifi:state: assoc -> run (10)
I (4333776) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4333776) wifi:security type: 3, phy: bgn, rssi: -59
I (4333776) wifi:pm start, type: 1

I (4333786) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4333816) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4334886) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4334886) netmanager: WIFI client got IP
I (4334886) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4334906) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4334906) wifi:state: run -> init (0)
I (4334906) wifi:pm stop, total sleep time: 722556 us / 1134494 us

I (4334906) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4334916) script: Starting Wifi client reconnect.
I (4334916) netmanager: WIFI client stop
I (4334916) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
OVMS# wifi mode off
Stopping wifi station...
I (4342606) esp32wifi: Stopping WIFI station
I (4342616) wifi:flush txq
I (4342616) wifi:stop sw txq
I (4342616) wifi:lmac stop hw txq
E (4342616) esp32wifi: EventWifiScanDone: can't get AP count, error=0x3002
OVMS# wifi mode client Revelstoke
Starting WIFI as a client to Revelstoke...
I (4365396) wifi:mode : sta (30:ae:a4:37:1b:84)
I (4369536) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-59
I (4370046) wifi:new:<6,0>, old:<1,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4370656) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4370656) wifi:security type: 3, phy: bgn, rssi: -59
I (4370666) wifi:pm start, type: 1

I (4370666) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4370676) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4371716) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4371726) netmanager: WIFI client got IP
I (4371726) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4371746) script: Running script /store/events/network.wifi.sta.bad/reconnect
I (4371746) wifi:state: run -> init (0)
I (4371746) wifi:pm stop, total sleep time: 820829 us / 1086209 us

I (4371746) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4371756) script: Starting Wifi client reconnect.
I (4371756) netmanager: WIFI client stop
I (4371756) esp32wifi: STA disconnected with reason 8 = ASSOC_LEAVE
I (4379216) esp32wifi: ScanDone: connect to ssid='Revelstoke' bssid='ac:5d:10:41:1a:96' chan=6 rssi=-58
I (4379716) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (4379716) wifi:state: init -> auth (b0)
I (4379736) wifi:state: auth -> assoc (0)
I (4379736) wifi:state: assoc -> run (10)
I (4379766) wifi:connected with Revelstoke, aid = 4, channel 6, BW20, bssid = ac:5d:10:41:1a:96
I (4379766) wifi:security type: 3, phy: bgn, rssi: -58
I (4379776) wifi:pm start, type: 1

I (4379786) esp32wifi: STA connected with SSID: Revelstoke, BSSID: ac:5d:10:41:1a:96, Channel: 6, Auth: WPA2
I (4379806) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4382756) event: sta ip: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4382766) netmanager: WIFI client got IP
I (4382766) esp32wifi: STA got IP with SSID 'Revelstoke' AP ac:5d:10:41:1a:96: MAC: 30:ae:a4:37:1b:84, IP: 192.168.1.95, mask: 255.255.255.0, gw: 192.168.1.254
I (4382776) netmanager: WIFI client has good signal quality (-76.1 dBm); connect
I (4382786) netmanager: Set DNS#1 0.0.0.0
I (4382786) netmanager: Set DNS#2 0.0.0.0
I (4382786) netmanager: WIFI client up (with MODEM down): starting network with WIFI client
I (4382796) time: Starting SNTP client
I (4382806) webserver: Launching Web Server
I (4382816) telnet: Launching Telnet Server
I (4382816) ssh: Launching SSH Server
I (4501086) housekeeping: 2020-12-14 18:48:51 PST (RAM: 8b=14896-17064 32b=36260)
OVMS# 