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

Constant Disconnects on multiple chipsets, reason: 256(Disconnected) #1337

Open
audiophonicz opened this issue Sep 1, 2024 · 0 comments
Open

Constant Disconnects on multiple chipsets, reason: 256(Disconnected) #1337

audiophonicz opened this issue Sep 1, 2024 · 0 comments
Labels
bug Something isn't working

Comments

@audiophonicz
Copy link

Describe the bug
Constant Disconnected, reason: 256(Disconnected) in OpenBeken Web Application logs.
Constant Disconnect Messages from MQTT
Constant Unavailable Logs from Home Assistant

2 devices will disconnect with the 256 reason within milliseconds of each other, usually every 5 minutes, sometimes less. This has been occurring since first installed. adding the 2nd device 6mo later and it syncing up to disconnect almost simultaneously warranted investigation.

Firmware:

  • Version 1.17.579 thru current (1.17.670)
  • MagicHome RGB Led Controller + TreatLife SS01S Single Pole Smart Switch
  • Chip/model: BL602 + BK7231N (respectively)
  • Device config? N/A

To Reproduce
Steps to reproduce the behavior:

  1. Flash OpenBeken to a device and connect to wifi/mqtt

Logs ( !values have been changed to protect the innocent )
OpenBeken Web Application Logs:

Info:MAIN:Time 2289, idle 0/s, free 83304, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2290, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2291, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2292, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2293, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2294, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2295, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2296, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2297, idle 0/s, free 83304, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2298, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2299, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2300, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2301, idle 0/s, free 83304, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2302, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2303, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2304, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2305, idle 0/s, free 83304, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2306, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2307, idle 0/s, free 95072, MQTT 1(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:mqtt_connection_cb: Disconnected, reason: 256(Disconnected)
Info:MAIN:Time 2308, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2309, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2310, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2311, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2312, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2313, idle 0/s, free 83304, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2314, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2315, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2316, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2317, idle 0/s, free 83304, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2318, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2319, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2320, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2321, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2322, idle 0/s, free 95072, MQTT 0(9), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:mqtt_userName !username
mqtt_pass !password
mqtt_clientID led_strip/!BL602
mqtt_host !mqtt.example.com:1883
Info:MAIN:Time 2323, idle 0/s, free 95072, MQTT 0(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:mqtt_connection_cb: Successfully connected
Info:MQTT:mqtt_subscribed to led_strip/!BL602/+/set
Info:MQTT:mqtt_subscribed to !BL602s/+/set
Info:MQTT:mqtt_subscribed to cmnd/led_strip/!BL602/+
Info:MQTT:mqtt_subscribed to cmnd/!BL602s/+
Info:MQTT:mqtt_subscribed to led_strip/!BL602/+/get
Info:MQTT:Publishing val 40.11 to led_strip/!BL602/temp retain=0
Info:MAIN:Time 2324, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:Publishing val !WiFi_SSID to led_strip/!BL602/ssid retain=0
Info:MAIN:Time 2325, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:Publishing val 2 to led_strip/!BL602/sockets retain=0
Info:MAIN:Time 2326, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:Publishing val -56 to led_strip/!BL602/rssi retain=0
Info:MAIN:Time 2327, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:Publishing val 2327 to led_strip/!BL602/uptime retain=0
Info:MAIN:Time 2328, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:Publishing val 83304 to led_strip/!BL602/freeheap retain=0
Info:MAIN:Time 2329, idle 0/s, free 83304, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MQTT:Publishing val !192.168.1.10 to led_strip/!BL602/ip retain=0
Info:MAIN:Time 2330, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:Publishing val 0 to led_strip/!BL602/led_enableAll/get retain=0
Info:MAIN:Time 2331, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:MQTT client in mqtt_incoming_publish_cb topic led_strip/!BL602/led_enableAll/get
Debug:MQTT:channelSet topic 1107401700 with arg 0
Info:MQTT:Publishing val FF830F to led_strip/!BL602/led_basecolor_rgb/get retain=0
Info:MAIN:Time 2332, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:MQTT client in mqtt_incoming_publish_cb topic led_strip/!BL602/led_basecolor_rgb/get
Debug:MQTT:channelSet topic 1107401700 with arg FF830F
Info:MQTT:Publishing val 11 to led_strip/!BL602/led_dimmer/get retain=0
Info:MAIN:Time 2333, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MQTT:MQTT client in mqtt_incoming_publish_cb topic led_strip/!BL602/led_dimmer/get
Debug:MQTT:channelSet topic 1107401700 with arg 11
Info:MAIN:Time 2334, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2335, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2336, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2337, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2338, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2339, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2340, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2341, idle 0/s, free 83304, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 3/21 
Info:MAIN:Time 2342, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2343, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21 
Info:MAIN:Time 2344, idle 0/s, free 95072, MQTT 1(10), bWifi 1, secondsWithNoPing 1, socks 2/21

This occurs randomly up to every 5 minutes.

MQTT Events exerpt for BL602 device:

12:34:58 (-5.67 min)
online

12:34:57 (-1.02 sec)
offline

12:29:17 (-5.67 min)
online

12:29:15 (-1.16 sec)
offline

12:25:29 (-3.93 min)
online

12:25:19 (-0.9 sec)
offline

12:23:45 (-94.17 sec)
online

12:19:39 (-4.1 min)
online

1219:38 (-1 sec)
offline

12:25:58 (-3.66 min)
online

Screenshots
image
image

Additional context
I have one BL602 device that I've been using for over 6 months.
I recently purchased another BK7231N device 2 weeks ago.
I have just updated both devices to latest firmware as of today (9/1/24)
PowerSave has been turned off. WatchDog IP has been configured.

Both of these devices will disconnect with the 256 reason within milliseconds of each other, usually every 5 minutes, sometimes less. This has been occurring since first installed. adding the 2nd device 6mo later and it syncing up to disconnect almost simultaneously warranted investigation.

The BK7231N device is literally 6feet in direct line of sight from the UniFi AP. It is not a signal quality issue.

I have multiple Tasmota devices all connected to the same wifi AP that have never had this issue in thier 3 years of use.

This seems to be the issue/cause of these devices triggering on a schedule in HomeAssistant but not actually turning on/off. This is a problem with trying to automate lighting in my 6mo old's bedroom. Please help.
@openshwprojects openshwprojects added the bug Something isn't working label Sep 1, 2024
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
2 participants
@openshwprojects @audiophonicz