[ZHA] Coordinator was down a few days - no enddevice will reconnect

Hi :slight_smile:

I have HA 2022.12.8 (via Docker) and a Texas Instruments Z-Stack ZNP protocol: CC253x, CC26x2, CC13x2 coordinator with FW 20220219.

I set ZHA up with all my lights and sensors which worked well! I had to do some things and switched the whole system off a few days. Im done (some rebuilt, nothing on the HW/SW side) and started everything back on - since then, NO single ZigBee device is connecting anymore.

The nearest sensor is 50cm away from the coordinator, the next one switch some meters. But even the other sensors anywhere in the house (which were connecting before) are β€œlost”.

Switched on debug:

2023-01-02 11:56:14.540 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 241517.38078188896 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:56:14.541 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:56:26.665 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:56:26.669 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:56:30.533 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 241546.587941885 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:56:30.534 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:56:53.531 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): last_seen is 256771.02954888344 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:56:53.532 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:56:56.672 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:56:56.677 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:57:14.553 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): last_seen is 243965.6633284092 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:57:14.553 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:57:26.680 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:57:26.684 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:57:42.541 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 241605.38170862198 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:57:42.542 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:57:50.534 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 241626.58878564835 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:57:50.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:57:56.686 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:57:56.691 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:58:20.532 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): last_seen is 256858.03056168556 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:58:20.533 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:58:26.693 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:58:26.698 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:58:38.555 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): last_seen is 244049.6649875641 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:58:38.555 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:58:56.701 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:58:56.707 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:59:10.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 241706.58959174156 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:59:10.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:59:10.542 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 241693.38234114647 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:59:10.542 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:59:26.709 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:59:26.714 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 11:59:47.534 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): last_seen is 256945.032248497 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 11:59:47.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 11:59:56.716 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 11:59:56.720 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:00:02.556 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): last_seen is 244133.66583514214 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:00:02.556 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:00:26.723 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:00:26.727 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:00:30.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 241786.58983254433 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:00:30.536 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:00:38.542 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 241781.38287758827 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:00:38.543 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:00:56.729 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:00:56.734 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:01:14.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): last_seen is 257032.03266978264 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:01:14.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:01:26.557 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): last_seen is 244217.6673927307 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:01:26.558 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:01:26.737 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:01:26.741 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:01:50.536 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 241866.590839386 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:01:50.537 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:01:56.743 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:01:56.748 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:02:06.544 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 241869.3840277195 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:02:06.544 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:02:26.751 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:02:26.755 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:02:41.535 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): last_seen is 257119.03325080872 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:02:41.536 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:02:50.557 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): last_seen is 244301.6675403118 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:02:50.558 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:02:56.759 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:02:56.764 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:03:10.537 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 241946.59156918526 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:03:10.537 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:03:26.767 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:03:26.771 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:03:34.544 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 241957.38470983505 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:03:34.545 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:03:56.774 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:03:56.779 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:04:08.537 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): last_seen is 257206.03522634506 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:04:08.538 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x027D](lumi.weather): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:04:14.558 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): last_seen is 244385.66866993904 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:04:14.559 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xDEE5](lumi.sensor_switch): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:04:26.782 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:04:26.786 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:04:30.538 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): last_seen is 242026.59245848656 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:04:30.538 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1A08](Ceiling TW OSRAM): Update device availability -  device available: False - new availability: False - changed: False
2023-01-02 12:04:56.789 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2023-01-02 12:04:56.794 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2023-01-02 12:05:02.546 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): last_seen is 242045.38667321205 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-01-02 12:05:02.547 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4337](lumi.weather): Update device availability -  device available: False - new availability: False - changed: Fals

I plugged the coordinator during HA was running - no issues (red errors):

2023-01-02 12:07:14.826 INFO (MainThread) [zigpy.device] [0x0000] Requesting 'Node Descriptor'
2023-01-02 12:07:14.846 INFO (MainThread) [zigpy.device] [0x0000] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.Coordinator: 0>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice|AlternatePanCoordinator: 143>, manufacturer_code=0, maximum_buffer_size=80, maximum_incoming_transfer_size=160, server_mask=11265, maximum_outgoing_transfer_size=160, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=True, *is_coordinator=True, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=False, *is_security_capable=False)
2023-01-02 12:07:14.846 INFO (MainThread) [zigpy.device] [0x0000] Discovering endpoints
2023-01-02 12:07:14.863 INFO (MainThread) [zigpy.device] [0x0000] Discovered endpoints: [2, 1]
2023-01-02 12:07:14.863 INFO (MainThread) [zigpy.device] [0x0000] Initializing endpoints [<Endpoint id=2 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
2023-01-02 12:07:14.863 INFO (MainThread) [zigpy.endpoint] [0x0000:2] Discovering endpoint information
2023-01-02 12:07:14.880 INFO (MainThread) [zigpy.endpoint] [0x0000:2] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=2, profile=49246, device_type=2080, device_version=0, input_clusters=[0], output_clusters=[])
2023-01-02 12:07:14.881 INFO (MainThread) [zigpy.endpoint] [0x0000:1] Discovering endpoint information
2023-01-02 12:07:14.902 INFO (MainThread) [zigpy.endpoint] [0x0000:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=1024, device_version=0, input_clusters=[0, 6, 10, 25, 1281], output_clusters=[1, 32, 1280, 1282])
2023-01-02 12:07:14.903 INFO (MainThread) [zigpy.device] [0x0000] Already have model and manufacturer info
2023-01-02 12:07:14.903 INFO (MainThread) [zigpy.device] [0x0000] Discovered basic device information for <ZNPCoordinator model='CC1352/CC2652, Z-Stack 3.30+ (build 20220219)' manuf='Texas Instruments' nwk=0x0000 ieee= is_initialized=True>
2023-01-02 12:07:14.908 INFO (MainThread) [zigpy_znp.zigbee.application] Permitting joins for 0 seconds

The only notice I wonder about was: 2023-01-02 12:07:14.908 INFO (MainThread) [zigpy_znp.zigbee.application] Permitting joins for 0 seconds - is that right?

What can I test now? Or is there some ZigBee protocol rule which prevents too many connects from an enddevice in case of failure? Do I have to wait a few hours and all will be back?

I dont see any debug packets recieves when I press my Lumi switch direct near the coordinator - should I?

Thanks in advance! :smiley:

The Lumi switch came back to live today. Weird. All others did not. So I started over and deleted the device and the zigbee.db file.

I added the Coordinator als New and started the pairing mode. The Osram Ceiling TW is no pairing and the Lumi switch is also not showing up. I dont know what happened but something seems not to work. I make further tests tomorrow.

Ended up just reset everything and set it back up with another router.