Zigbee Stopped Working after Upgrade 2.5.7-1

I just performed an upgrade from 2.5.6-2 to 2.5.7-1 and my Zigbee Network stopped working. First everything startsup well but after a few minutes all devices stopped working. after a reboot and also clean Cache the same, short time everything is fine then all zigbee devices are not responding (both ways)

Openhabian 2.5.7-1
RPI3
CC2531

Part of the recent log:

2020-07-29 14:19:42.894 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00B01B36: Polling…
2020-07-29 14:19:50.236 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00033B76EA: Polling…
2020-07-29 14:20:31.137 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A0261E5: Polling…
2020-07-29 14:21:30.764 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446FE30: Polling…
2020-07-29 14:22:20.951 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000444680E: Polling…
2020-07-29 14:22:27.713 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d0003676654
2020-07-29 14:22:27.722 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003676654: Polling stopped
2020-07-29 14:22:27.729 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003676654: Polling initialised at 978406ms
2020-07-29 14:22:27.733 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d00033b76ea
2020-07-29 14:22:27.738 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00033B76EA: Polling stopped
2020-07-29 14:22:27.742 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00033B76EA: Polling initialised at 983521ms
2020-07-29 14:22:32.383 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:7cb03eaa0a002d8e
2020-07-29 14:22:32.388 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A002D8E: Polling stopped
2020-07-29 14:22:32.394 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A002D8E: Polling initialised at 959781ms
2020-07-29 14:23:08.411 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d0002c0d181
2020-07-29 14:23:08.414 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0002C0D181: Polling stopped
2020-07-29 14:23:08.417 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0002C0D181: Polling initialised at 927068ms
2020-07-29 14:23:11.113 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 84182600000B6A8D: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=false, lastReportTime=Wed Jul 29 14:23:11 CEST 2020, implemented=false]
2020-07-29 14:23:11.117 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 84182600000B6A8D: Channel zigbee:device:bd039d70:84182600000b6a8d:84182600000B6A8D_3_switch updated to OFF
2020-07-29 14:23:11.121 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000B6A8D: Updating ZigBee channel state zigbee:device:bd039d70:84182600000b6a8d:84182600000B6A8D_3_switch to OFF
2020-07-29 14:23:11.127 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:bd039d70:84182600000b6a8d
2020-07-29 14:23:11.132 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:bd039d70:84182600000b6a8d
2020-07-29 14:23:11.138 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:bd039d70:84182600000b6a8d in 1830 seconds
2020-07-29 14:23:15.786 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d000444680e
2020-07-29 14:23:15.794 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000444680E: Polling stopped
2020-07-29 14:23:15.799 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000444680E: Polling initialised at 913395ms
2020-07-29 14:23:16.767 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00041CC642: Polling…
2020-07-29 14:23:51.927 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d000446fe30
2020-07-29 14:23:51.930 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446FE30: Polling stopped
2020-07-29 14:23:51.933 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446FE30: Polling initialised at 905086ms
2020-07-29 14:24:23.168 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 7CB03EAA0A0261E5: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=false, lastReportTime=Wed Jul 29 14:24:23 CEST 2020, implemented=false]
2020-07-29 14:24:23.174 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA0A0261E5: Channel zigbee:device:bd039d70:7cb03eaa0a0261e5:7CB03EAA0A0261E5_3_switch updated to OFF
2020-07-29 14:24:23.178 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A0261E5: Updating ZigBee channel state zigbee:device:bd039d70:7cb03eaa0a0261e5:7CB03EAA0A0261E5_3_switch to OFF
2020-07-29 14:24:23.185 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:bd039d70:7cb03eaa0a0261e5
2020-07-29 14:24:23.188 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:bd039d70:7cb03eaa0a0261e5
2020-07-29 14:24:23.192 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:bd039d70:7cb03eaa0a0261e5 in 1830 seconds
2020-07-29 14:24:28.751 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A002D8E: Polling…
2020-07-29 14:24:42.402 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d000446ebd4
2020-07-29 14:24:42.407 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446EBD4: Polling stopped
2020-07-29 14:24:42.414 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446EBD4: Polling initialised at 987837ms
2020-07-29 14:24:52.034 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d0002c0d1ae
2020-07-29 14:24:52.038 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0002C0D1AE: Polling stopped
2020-07-29 14:24:52.040 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0002C0D1AE: Polling initialised at 983058ms
2020-07-29 14:25:22.430 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446EBD4: Polling…
2020-07-29 14:25:32.474 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:84182600000d7c3b
2020-07-29 14:25:32.478 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7C3B: Polling stopped
2020-07-29 14:25:32.482 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7C3B: Polling initialised at 966003ms
2020-07-29 14:25:42.850 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:00158d00041cc642
2020-07-29 14:25:42.853 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00041CC642: Polling stopped
2020-07-29 14:25:42.855 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00041CC642: Polling initialised at 919020ms
2020-07-29 14:26:03.337 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker LastChance Timeout has been reached for thingUID=zigbee:device:bd039d70:84182600000d7915
2020-07-29 14:26:03.344 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7915: Polling stopped
2020-07-29 14:26:03.351 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7915: Polling initialised at 980891ms
2020-07-29 14:27:27.071 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446FE30: Polling…
2020-07-29 14:29:22.234 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7C3B: Polling…
2020-07-29 14:30:55.159 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000B6A8D: Polling…
2020-07-29 14:33:35.111 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0002C0D1AE: Polling…
2020-07-29 14:33:41.901 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AE8E73: Command for channel zigbee:device:bd039d70:7cb03eaa00ae8e73:7CB03EAA00AE8E73_3_color --> OFF [OnOffType]
2020-07-29 14:33:43.046 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AE8E73: Command for channel zigbee:device:bd039d70:7cb03eaa00ae8e73:7CB03EAA00AE8E73_3_color --> OFF [OnOffType]
2020-07-29 14:33:44.273 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AE8E73: Command for channel zigbee:device:bd039d70:7cb03eaa00ae8e73:7CB03EAA00AE8E73_3_color --> OFF [OnOffType]
2020-07-29 14:33:44.421 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AE8E73: Command for channel zigbee:device:bd039d70:7cb03eaa00ae8e73:7CB03EAA00AE8E73_3_color --> OFF [OnOffType]
2020-07-29 14:33:46.559 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AE8E73: Command for channel zigbee:device:bd039d70:7cb03eaa00ae8e73:7CB03EAA00AE8E73_3_color --> ON [OnOffType]
2020-07-29 14:33:50.564 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA00AE8E73: Command for channel zigbee:device:bd039d70:7cb03eaa00ae8e73:7CB03EAA00AE8E73_3_color --> OFF [OnOffType]
2020-07-29 14:35:05.207 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00033B76EA: Polling…
2020-07-29 14:35:08.489 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7915: Polling…
2020-07-29 14:35:56.149 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A0261E5: Polling…
2020-07-29 14:36:42.282 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00041CC642: Polling…
2020-07-29 14:36:52.306 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0002C0D181: Polling…
2020-07-29 14:37:55.539 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0003676654: Polling…
2020-07-29 14:38:09.230 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 84182600000B6A8D: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=false, lastReportTime=Wed Jul 29 14:38:09 CEST 2020, implemented=false]
2020-07-29 14:38:09.232 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 84182600000B6A8D: Channel zigbee:device:bd039d70:84182600000b6a8d:84182600000B6A8D_3_switch updated to OFF
2020-07-29 14:38:09.235 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000B6A8D: Updating ZigBee channel state zigbee:device:bd039d70:84182600000b6a8d:84182600000B6A8D_3_switch to OFF
2020-07-29 14:38:09.238 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:bd039d70:84182600000b6a8d
2020-07-29 14:38:09.240 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:bd039d70:84182600000b6a8d
2020-07-29 14:38:09.243 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:bd039d70:84182600000b6a8d in 1830 seconds
2020-07-29 14:38:25.497 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000444680E: Polling…
2020-07-29 14:39:20.286 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 7CB03EAA0A0261E5: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=false, lastReportTime=Wed Jul 29 14:39:20 CEST 2020, implemented=false]
2020-07-29 14:39:20.288 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 7CB03EAA0A0261E5: Channel zigbee:device:bd039d70:7cb03eaa0a0261e5:7CB03EAA0A0261E5_3_switch updated to OFF
2020-07-29 14:39:20.293 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A0261E5: Updating ZigBee channel state zigbee:device:bd039d70:7cb03eaa0a0261e5:7CB03EAA0A0261E5_3_switch to OFF
2020-07-29 14:39:20.298 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:bd039d70:7cb03eaa0a0261e5
2020-07-29 14:39:20.302 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:bd039d70:7cb03eaa0a0261e5
2020-07-29 14:39:20.305 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:bd039d70:7cb03eaa0a0261e5 in 1830 seconds
2020-07-29 14:40:28.532 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 7CB03EAA0A002D8E: Polling…
2020-07-29 14:41:50.268 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446EBD4: Polling…
2020-07-29 14:42:32.157 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D000446FE30: Polling…
2020-07-29 14:43:05.168 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7C3B: Command for channel zigbee:device:bd039d70:84182600000d7c3b:84182600000D7C3B_3_switch --> ON [OnOffType]
2020-07-29 14:43:05.220 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000D7C3B: Command for channel zigbee:device:bd039d70:84182600000d7c3b:84182600000D7C3B_3_switch --> OFF [OnOffType]

Anybody any ideas or maybe same issues?

Greetings Andy

I do not use Zigbee but perhaps the log viewer can help while you are waiting.

https://www.cd-jackson.com/index.php/openhab/zigbee-log-viewer

1 Like

Hey Bruce,
thanks for the link, didnt know About this.
Unfortunately it didnt Show anything that helps me in this case.

Greetings Andy

Hopefully @chris will be along shortly.

The whole log file:
https://1drv.ms/u/s!AinkhR9eG80H4H3FYHGp_cYknsoD?e=8ZBBQb

The log doesn’t contain the debug data that would be needed to work out what is happening. Please check the binding docs for how to configure the logging.

1 Like

sorry missed this Point:
https://1drv.ms/u/s!AinkhR9eG80H4H3FYHGp_cYknsoD?e=ucJFTi

Hey Chris,
I have uploaden a new log, but dont investigate too much time in this case, cause I Setup my Zigbee Network new today (now everything is good on 2.5.7-1) cause most of my Plugs are controlled via zigbee, so I needed them :slight_smile:
One Thing i figured out was, that my Xiaomi Devices (Dooar and Window sensors) only work with the CC2531ZNP-Prod.hex Firmware, not with the CC2531ZNP-Pro-Secure_Standard.hex. To be true I didnt know which one was on the stick, so I flashed the Pro-Secure_Standard as mentioned in the docs and only my Osram devices were found. After flashing the Prod one everything is fine.

Greetings Andy

1 Like