Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

<ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying... #2186

Closed
nliaudat opened this issue Mar 14, 2023 · 20 comments
Closed

<ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying... #2186

nliaudat opened this issue Mar 14, 2023 · 20 comments
Labels
bug Something isn't working

Comments

@nliaudat
Copy link
Contributor

The Problem

I've got the following error every 1h still upgrade to Release: v15.1.0 (Commit: 7d33c3e+) on both production devices :

2023-03-14T12:12:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...

WIFI Signal: Good (-66dBm) on device #1
WIFI Signal: Excellent (-43dBm) on device #2

Version

15.1.0

Logfile

[0d02h37m27s] 2023-03-14T01:01:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d03h38m27s] 2023-03-14T02:02:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d04h39m27s] 2023-03-14T03:03:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d05h40m19s] 2023-03-14T04:04:23 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d05h40m43s] 2023-03-14T04:04:47 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d06h41m27s] 2023-03-14T05:05:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d07h42m27s] 2023-03-14T06:06:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d08h43m19s] 2023-03-14T07:07:23 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d08h43m43s] 2023-03-14T07:07:48 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d09h44m28s] 2023-03-14T08:08:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d10h45m28s] 2023-03-14T09:09:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d11h46m28s] 2023-03-14T10:10:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d12h47m28s] 2023-03-14T11:11:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d13h48m28s] 2023-03-14T12:12:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d14h49m28s] 2023-03-14T13:13:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d15h50m28s] 2023-03-14T14:14:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d16h51m28s] 2023-03-14T15:15:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d17h52m20s] 2023-03-14T16:16:23 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d17h52m44s] 2023-03-14T16:16:47 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d18h53m28s] 2023-03-14T17:17:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...

Expected Behavior

No response

Screenshots

No response

Additional Context

No response
@nliaudat nliaudat added the bug Something isn't working label Mar 14, 2023
@caco3
Copy link
Collaborator

caco3 commented Mar 14, 2023

does it also happen wirth a different AP?

Are you using the new Wifi Roaming? See https://jomjol.github.io/AI-on-the-edge-device-docs/Parameters/#parameter-rssithreshold

@Slider0007
Copy link
Collaborator

Strange. Can you please set to INFO level to see if it's a logic or a real wlan disconnect issue. The error message occurs after 10 failed reconnection attempts. With INFO you see the single retries...

INFO: Error 201 means no access point in range.

@nliaudat
Copy link
Contributor Author

does it also happen wirth a different AP?

No same AP

Are you using the new Wifi Roaming? See https://jomjol.github.io/AI-on-the-edge-device-docs/Parameters/#parameter-rssithreshold

No rssithreshold defined

I've set the info log level.

@nliaudat
Copy link
Contributor Author

nliaudat commented Mar 14, 2023 •
edited
Loading

WIFI Signal: Excellent (-43dBm)

[0d00h00m04s] 2023-03-14T23:23:26 <INF> [MAIN] =================================================
[0d00h00m04s] 2023-03-14T23:23:26 <INF> [MAIN] ==================== Start ======================
[0d00h00m04s] 2023-03-14T23:23:26 <INF> [MAIN] =================================================
[0d00h00m04s] 2023-03-14T23:23:26 <INF> [SDCARD] Basic R/W check started...
[0d00h00m04s] 2023-03-14T23:23:26 <INF> [SDCARD] Basic R/W check successful
[0d00h00m04s] 2023-03-14T23:23:26 <INF> [SNTP] TimeServer not defined, using default: pool.ntp.org
[0d00h00m04s] 2023-03-14T23:23:26 <INF> [SNTP] Configuring NTP Client...
[0d00h00m36s] 2023-03-14T23:06:22 <INF> [SNTP] Time is synced with NTP Server pool.ntp.org: 2023-03-14 23:06:22
[0d00h00m47s] 2023-03-14T23:06:33 <INF> [POSTPROC] hot: Raw: 00124009, Value: 124009, Status: no error
[0d00h00m47s] 2023-03-14T23:06:33 <INF> [TFLITE SERVER] Round #1 completed (36 seconds)
[0d00h15m11s] 2023-03-14T23:20:57 <INF> [TFLITE SERVER] Round #2 started
[0d00h15m47s] 2023-03-14T23:21:33 <INF> [POSTPROC] hot: Raw: 00124009, Value: 124009, Status: no error
[0d00h15m47s] 2023-03-14T23:21:33 <INF> [TFLITE SERVER] Round #2 completed (36 seconds)
[0d00h17m24s] 2023-03-14T23:23:10 <WRN> [WIFI] Disconnected (200, Timeout)
[0d00h17m27s] 2023-03-14T23:23:13 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m29s] 2023-03-14T23:23:15 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m32s] 2023-03-14T23:23:17 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m34s] 2023-03-14T23:23:20 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m36s] 2023-03-14T23:23:22 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m39s] 2023-03-14T23:23:25 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m41s] 2023-03-14T23:23:27 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m44s] 2023-03-14T23:23:29 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m46s] 2023-03-14T23:23:32 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m46s] 2023-03-14T23:23:32 <ERR> [WIFI] Disconnected, multiple reconnect attempts failed (201), still retrying...
[0d00h17m48s] 2023-03-14T23:23:34 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m51s] 2023-03-14T23:23:37 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m53s] 2023-03-14T23:23:39 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m56s] 2023-03-14T23:23:42 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h17m58s] 2023-03-14T23:23:44 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h18m01s] 2023-03-14T23:23:46 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h18m03s] 2023-03-14T23:23:49 <WRN> [WIFI] Disconnected (201, No AP)
[0d00h18m06s] 2023-03-14T23:23:51 <INF> [WIFI] Connected to: IOT-8MJG75B7xqLqXxe68k3m, RSSI: -42
[0d00h18m06s] 2023-03-14T23:23:52 <INF> [WIFI] Assigned IP: 192.168.1.113
[0d00h30m11s] 2023-03-14T23:35:57 <INF> [TFLITE SERVER] Round #3 started
[0d00h30m35s] 2023-03-14T23:36:21 <INF> [POSTPROC] hot: Raw: 00124009, Value: 124009, Status: no error
[0d00h30m35s] 2023-03-14T23:36:21 <INF> [TFLITE SERVER] Round #3 completed (24 seconds)

@caco3
Copy link
Collaborator

caco3 commented Mar 14, 2023

was it working with v15.0.3?

@nliaudat
Copy link
Contributor Author

I have upgrraded from 15.0.1

@Slider0007
Copy link
Collaborator

Slider0007 commented Mar 14, 2023 •
edited
Loading

It seems logic is correct, for some reason your device really gets disconnected due to timeout which indicates AP is not responding in time (no beacon) then it's reconnecting after a while...
Is your AP available during these interruptions? Can you check AP logs.
In previous versions before 15.1 WLAN disconnect error was not logged in logfile at all, so not really obvious if it's occured also before this version.

@nliaudat
Copy link
Contributor Author

nliaudat commented Mar 14, 2023 •
edited
Loading

the strange thing is the disconnecting appears every 2.x minutes, but do not after sucessful reconnection till approx 10 minutes

My hassio is retrieving data from ai-on-the-edge every 120s still the beginning without error ...

@Slider0007
Copy link
Collaborator

the strange thing is the disconnecting appears every 2.x minutes, but do not after sucessful reconnection till approx 10 minutes

If you mean the repeating disconnected messages they occur every 2.x seconds and it's not a disconnect event it's a state which is still unchanged after another connect retry, therefore the word "disconnected"

@Slider0007
Copy link
Collaborator

Slider0007 commented Mar 14, 2023 •
edited
Loading

My hassio is retrieving data from ai-on-the-edge every 120s still the beginning without error ...

This could still possible even with the disconnects in the past because no error was visible before 15.1 in logfile (only on serial console) and it seems device is reconnecting without any interaction.

But still strange. I would check the AP log. There should also some Infos about the disconnect reason. As I said, error 201 means no AP is in range of the device.
Or you test with another AP, just to be sure...

@nliaudat
Copy link
Contributor Author

nliaudat commented Mar 14, 2023 •
edited
Loading

You are right every 2 seconds.

My SSID is hidden, perhaps it may break something.

Is it a way to disable the "WIFI roaming" function ? I mean it's not usefull as the cam are not moving.

I'll check tomorrow for the AP logs

Regards

@Slider0007
Copy link
Collaborator

Is it a way to disable the "WIFI roaming" function ? I mean it's not usefull as the cam are not moving.

It is disabled when RSSIThreshold is set to 0 and that's default.

@nliaudat
Copy link
Contributor Author

nliaudat commented Mar 14, 2023 •
edited by Slider0007
Loading

mine is not set at all :

;RSSIThreshold =

@Slider0007
Copy link
Collaborator

Slider0007 commented Mar 14, 2023 •
edited
Loading

My SSID is hidden, perhaps it may break something.

Haven't tested with hidden SSID. A scan of hidden ones is at least possible, so I assume it should not be an issue...

@Slider0007
Copy link
Collaborator

mine is not set at all :

;RSSIThreshold =

Then it's internally set to 0

@nliaudat
Copy link
Contributor Author

Haven't tested with hidden SSID. A scan of hidden ones is at least possible, so I assume it should not be an issue...

Not sure an hidden SSID can be scanned.

@Slider0007
Copy link
Collaborator

Haven't tested with hidden SSID. A scan of hidden ones is at least possible, so I assume it should not be an issue...

Not sure an hidden SSID can be scanned.

At least it's configured in the driver: https://github.com/jomjol/AI-on-the-edge-device/blob/rolling/code/components/jomjol_wlan/connect_wlan.cpp#L350

@nliaudat
Copy link
Contributor Author

Cannot reproduce the error today

@blue0mt
Copy link

blue0mt commented Mar 22, 2023

I have the exact same problem. For me the error comes in 30 minute intervals which is also the Round Interval. I cannot see any issues on the AP side and the watermeter UI is also accessible.

@cazxtc
Copy link

cazxtc commented Apr 17, 2023

have the exact same problem :(

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
5 participants
@caco3 @nliaudat @cazxtc @blue0mt @Slider0007