Network coming to a halt

liamstears · December 7, 2023, 10:19am

Having issues with some Moes ZigBee light switches. Had them on Z2M initially but every now and again 1 would stop responding etc and this was very annoying. To try and find out the problem I decided to repair them all to ZHA instead using a different coordinator but now the whole of ZHA just freezes up and all lights stop responding. Anyone had this sort of issue before? I will attach some logs below, can someone have a look see if they can see why my ZHA network goes into an unresponsive state?

2023-12-07 08:45:05.840 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=112), 176, -56, 0x9e19, 255, 255, b'\x18\x19\n\x00\x00\x10\x00']
2023-12-07 08:45:05.840 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 45, 5, 840534, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9E19), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=112, profile_id=260, cluster_id=6, data=Serialized[b'\x18\x19\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=176, rssi=-56)
2023-12-07 08:45:05.840 DEBUG (MainThread) [zigpy.zcl] [0x9E19:2:0x0006] Received ZCL frame: b'\x18\x19\n\x00\x00\x10\x00'
2023-12-07 08:45:05.840 DEBUG (MainThread) [zigpy.zcl] [0x9E19:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=25, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:45:05.841 DEBUG (MainThread) [zigpy.zcl] [0x9E19:2:0x0006] Decoded ZCL frame: TuyaZBOnOffAttributeCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2023-12-07 08:45:05.841 DEBUG (MainThread) [zigpy.zcl] [0x9E19:2:0x0006] Received command 0x0A (TSN 25): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2023-12-07 08:45:05.841 DEBUG (MainThread) [zigpy.zcl] [0x9E19:2:0x0006] Attribute report received: on_off=<Bool.false: 0>
2023-12-07 08:45:05.993 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteErrorHandler: [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9e19]
2023-12-07 08:45:05.993 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteErrorHandler frame with [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9e19]
2023-12-07 08:45:05.993 DEBUG (MainThread) [bellows.zigbee.application] Processing route error: status=EmberStatus.MAC_INDIRECT_TIMEOUT, nwk=0x9e19
2023-12-07 08:45:08.144 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=44), 255, -36, 0x446a, 255, 255, b'\x18M\n\x00\x00\x10\x00']
2023-12-07 08:45:08.144 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=44), 255, -36, 0x446a, 255, 255, b'\x18M\n\x00\x00\x10\x00']
2023-12-07 08:45:08.144 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 45, 8, 144673, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x446A), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=44, profile_id=260, cluster_id=6, data=Serialized[b'\x18M\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-36)
2023-12-07 08:45:08.144 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Received ZCL frame: b'\x18M\n\x00\x00\x10\x00'
2023-12-07 08:45:08.145 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=77, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:45:08.145 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Decoded ZCL frame: TuyaZBOnOffAttributeCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2023-12-07 08:45:08.145 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Received command 0x0A (TSN 77): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2023-12-07 08:45:08.145 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Attribute report received: on_off=<Bool.false: 0>
2023-12-07 08:57:00.288 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=228), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:00.288 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=228), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:00.288 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 57, 0, 288698, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2ABC), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=228, profile_id=260, cluster_id=0, data=Serialized[b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=180, rssi=-55)
2023-12-07 08:57:00.289 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received ZCL frame: b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'
2023-12-07 08:57:00.289 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:00.289 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:00.289 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received command 0x0A (TSN 244): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:00.289 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Attribute report received: app_version=80, 0xFFE2=55, 0xFFE4=0
2023-12-07 08:57:00.290 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:00.290 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2023-12-07 08:57:00.290 DEBUG (MainThread) [zigpy.util] Duplicate 244 TSN
2023-12-07 08:57:00.456 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x2abc, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=244), 21, b'\x18\xf4\x0b\n\x00')
2023-12-07 08:57:00.465 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 107]
2023-12-07 08:57:00.465 DEBUG (MainThread) [bellows.zigbee.application] Request 21 failed to enqueue, retrying in 1.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 08:57:00.745 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=229), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:00.745 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=229), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:00.746 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 57, 0, 746062, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2ABC), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=229, profile_id=260, cluster_id=0, data=Serialized[b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=180, rssi=-55)
2023-12-07 08:57:00.746 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received ZCL frame: b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'
2023-12-07 08:57:00.746 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:00.746 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:00.746 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received command 0x0A (TSN 244): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:00.746 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Attribute report received: app_version=80, 0xFFE2=55, 0xFFE4=0
2023-12-07 08:57:00.747 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:00.747 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2023-12-07 08:57:00.747 DEBUG (MainThread) [zigpy.util] Duplicate 244 TSN
2023-12-07 08:57:00.875 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-12-07 08:57:00.893 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[70, 83, 20638, 176, 853, 150, 30, 30, 135, 63, 18, 6, 3, 55, 55, 0, 16, 28, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-12-07 08:57:00.894 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-12-07 08:57:00.899 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xc2']
2023-12-07 08:57:00.899 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 194
2023-12-07 08:57:00.899 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 105, MAC_TX_BROADCAST = 245, MAC_RX_UNICAST = 86173, MAC_TX_UNICAST_SUCCESS = 518, MAC_TX_UNICAST_RETRY = 1129, MAC_TX_UNICAST_FAILED = 165, APS_DATA_RX_BROADCAST = 53, APS_DATA_TX_BROADCAST = 53, APS_DATA_RX_UNICAST = 579, APS_DATA_TX_UNICAST_SUCCESS = 288, APS_DATA_TX_UNICAST_RETRY = 45, APS_DATA_TX_UNICAST_FAILED = 13, ROUTE_DISCOVERY_INITIATED = 8, NEIGHBOR_ADDED = 57, NEIGHBOR_REMOVED = 57, NEIGHBOR_STALE = 0, JOIN_INDICATION = 41, CHILD_REMOVED = 30, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 3, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 6, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 194]
2023-12-07 08:57:01.192 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=230), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:01.192 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=230), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:01.192 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 57, 1, 192713, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2ABC), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=230, profile_id=260, cluster_id=0, data=Serialized[b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=180, rssi=-55)
2023-12-07 08:57:01.192 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received ZCL frame: b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'
2023-12-07 08:57:01.193 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:01.193 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:01.193 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received command 0x0A (TSN 244): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:01.193 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Attribute report received: app_version=80, 0xFFE2=55, 0xFFE4=0
2023-12-07 08:57:01.193 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:01.193 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2023-12-07 08:57:01.194 DEBUG (MainThread) [zigpy.util] Duplicate 244 TSN
2023-12-07 08:57:01.356 DEBUG (MainThread) [zigpy.endpoint] [0x9b9a:1] Failed to sync-up group membership
2023-12-07 08:57:01.356 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:2:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=22, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 08:57:01.357 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:2:0x0004] Sending request: get_membership(groups=[])
2023-12-07 08:57:01.357 DEBUG (MainThread) [zigpy.device] [0x9b9a] Extending timeout for 0x16 request
2023-12-07 08:57:01.357 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 57, 1, 357218, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9B9A), dst_ep=2, source_route=None, extended_timeout=True, tsn=22, profile_id=260, cluster_id=4, data=Serialized[b'\x01\x16\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 08:57:01.357 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 08:57:01.364 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 08:57:01.364 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=22), 23, b'\x01\x16\x02\x00')
2023-12-07 08:57:01.373 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 108]
2023-12-07 08:57:01.374 DEBUG (MainThread) [bellows.zigbee.application] Request 23 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 08:57:01.643 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=231), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:01.643 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=231), 180, -55, 0x2abc, 255, 255, b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00']
2023-12-07 08:57:01.644 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 57, 1, 644079, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2ABC), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=231, profile_id=260, cluster_id=0, data=Serialized[b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=180, rssi=-55)
2023-12-07 08:57:01.644 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received ZCL frame: b'\x08\xf4\n\x01\x00 P\xe2\xff 7\xe4\xff \x00'
2023-12-07 08:57:01.644 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=10, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:01.644 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:01.644 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Received command 0x0A (TSN 244): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=55)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2023-12-07 08:57:01.644 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Attribute report received: app_version=80, 0xFFE2=55, 0xFFE4=0
2023-12-07 08:57:01.645 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=244, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
2023-12-07 08:57:01.645 DEBUG (MainThread) [zigpy.zcl] [0x2ABC:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2023-12-07 08:57:01.645 DEBUG (MainThread) [zigpy.util] Duplicate 244 TSN
2023-12-07 08:57:01.875 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 08:57:01.882 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 08:57:01.883 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=22), 23, b'\x01\x16\x02\x00')
2023-12-07 08:57:01.891 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 109]
2023-12-07 08:57:01.891 DEBUG (MainThread) [bellows.zigbee.application] Request 23 failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 08:57:02.161 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteErrorHandler: [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9b9a]
2023-12-07 08:57:02.161 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteErrorHandler frame with [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9b9a]
2023-12-07 08:57:02.161 DEBUG (MainThread) [bellows.zigbee.application] Processing route error: status=EmberStatus.MAC_INDIRECT_TIMEOUT, nwk=0x9b9a
2023-12-07 08:57:02.892 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 08:57:02.899 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 08:57:02.899 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=22), 23, b'\x01\x16\x02\x00')
2023-12-07 08:57:02.908 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 110]
2023-12-07 08:57:02.908 DEBUG (MainThread) [bellows.zigbee.application] Request 23 failed to enqueue, retrying in 1.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 08:57:03.958 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.memory_usage_percent, old_state=<state sensor.memory_usage_percent=44.1; unit_of_measurement=%, friendly_name=Memory Usage Percent, hide_attributes=['templates', 'icon_color'] @ 2023-12-07T08:56:58.995878+00:00>, new_state=<state sensor.memory_usage_percent=44.0; unit_of_measurement=%, friendly_name=Memory Usage Percent, hide_attributes=['templates', 'icon_color'] @ 2023-12-07T08:57:03.958045+00:00>>
2023-12-07 08:57:03.959 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu_usage, old_state=<state sensor.cpu_usage=6.4; unit_of_measurement=%, friendly_name=CPU Usage, hide_attributes=['templates', 'icon_color'] @ 2023-12-07T08:56:58.996644+00:00>, new_state=<state sensor.cpu_usage=6.6; unit_of_measurement=%, friendly_name=CPU Usage, hide_attributes=['templates', 'icon_color'] @ 2023-12-07T08:57:03.959146+00:00>>
2023-12-07 08:57:04.297 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [3, <Bool.true: 1>, 0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-12-07 08:57:04.298 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [3, <Bool.true: 1>, 0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-12-07 08:57:04.303 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberDeviceUpdate.STANDARD_SECURITY_SECURED_REJOIN: 0>, <EmberJoinDecision.NO_ACTION: 3>, 0x0000]
2023-12-07 08:57:04.303 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberDeviceUpdate.STANDARD_SECURITY_SECURED_REJOIN: 0>, <EmberJoinDecision.NO_ACTION: 3>, 0x0000]
2023-12-07 08:57:04.303 INFO (MainThread) [zigpy.application] Device 0x9b9a (a4:c1:38:70:d7:fb:03:e1) joined the network
2023-12-07 08:57:04.303 DEBUG (MainThread) [zigpy.device] [0x9b9a] Cancelling old group rescan
2023-12-07 08:57:04.303 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=24, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 08:57:04.304 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:1:0x0004] Sending request: get_membership(groups=[])
2023-12-07 08:57:04.304 DEBUG (MainThread) [zigpy.device] [0x9b9a] Extending timeout for 0x18 request
2023-12-07 08:57:04.304 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 8, 57, 4, 304159, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9B9A), dst_ep=1, source_route=None, extended_timeout=True, tsn=24, profile_id=260, cluster_id=4, data=Serialized[b'\x01\x18\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 08:57:04.304 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 08:57:04.310 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 08:57:04.310 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=24), 25, b'\x01\x18\x02\x00')
2023-12-07 08:57:04.320 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 111]
2023-12-07 08:57:04.320 DEBUG (MainThread) [bellows.zigbee.application] Request 25 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 08:57:04.822 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 08:57:04.830 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 08:57:04.830 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=24), 25, b'\x01\x18\x02\x00')
2023-12-07 08:57:04.838 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 112]
2023-12-07 08:57:04.839 DEBUG (MainThread) [bellows.zigbee.application] Request 25 failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED

liamstears · December 7, 2023, 10:20am

2023-12-07 10:09:24.181 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2023-12-07 10:09:24.182 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=229, command_id=1, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 10:09:24.182 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Sending request: on()
2023-12-07 10:09:24.182 DEBUG (MainThread) [zigpy.device] [0x446a] Extending timeout for 0xe5 request
2023-12-07 10:09:24.182 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 10, 9, 24, 182389, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x446A), dst_ep=2, source_route=None, extended_timeout=True, tsn=229, profile_id=260, cluster_id=6, data=Serialized[b'\x01\xe5\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 10:09:24.186 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 128]
2023-12-07 10:09:24.186 DEBUG (MainThread) [bellows.zigbee.application] Request 228 failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.186 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:24.193 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.193 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=229), 230, b'\x01\xe5\x01')
2023-12-07 10:09:24.201 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 129]
2023-12-07 10:09:24.202 DEBUG (MainThread) [bellows.zigbee.application] Request 230 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.342 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.dining_room_light>
2023-12-07 10:09:24.343 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2023-12-07 10:09:24.343 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=231, command_id=1, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 10:09:24.343 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Sending request: on()
2023-12-07 10:09:24.343 DEBUG (MainThread) [zigpy.device] [0x446a] Extending timeout for 0xe7 request
2023-12-07 10:09:24.343 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 10, 9, 24, 343483, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x446A), dst_ep=2, source_route=None, extended_timeout=True, tsn=231, profile_id=260, cluster_id=6, data=Serialized[b'\x01\xe7\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 10:09:24.343 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:24.350 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.350 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=231), 232, b'\x01\xe7\x01')
2023-12-07 10:09:24.358 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 130]
2023-12-07 10:09:24.358 DEBUG (MainThread) [bellows.zigbee.application] Request 232 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.463 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [3, <Bool.true: 1>, 0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-12-07 10:09:24.463 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [3, <Bool.true: 1>, 0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-12-07 10:09:24.468 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberDeviceUpdate.STANDARD_SECURITY_SECURED_REJOIN: 0>, <EmberJoinDecision.NO_ACTION: 3>, 0x0000]
2023-12-07 10:09:24.468 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x9b9a, a4:c1:38:70:d7:fb:03:e1, <EmberDeviceUpdate.STANDARD_SECURITY_SECURED_REJOIN: 0>, <EmberJoinDecision.NO_ACTION: 3>, 0x0000]
2023-12-07 10:09:24.468 INFO (MainThread) [zigpy.application] Device 0x9b9a (a4:c1:38:70:d7:fb:03:e1) joined the network
2023-12-07 10:09:24.468 DEBUG (MainThread) [zigpy.device] [0x9b9a] Cancelling old group rescan
2023-12-07 10:09:24.469 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=233, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 10:09:24.469 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:1:0x0004] Sending request: get_membership(groups=[])
2023-12-07 10:09:24.469 DEBUG (MainThread) [zigpy.device] [0x9b9a] Extending timeout for 0xe9 request
2023-12-07 10:09:24.469 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 10, 9, 24, 469787, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9B9A), dst_ep=1, source_route=None, extended_timeout=True, tsn=233, profile_id=260, cluster_id=4, data=Serialized[b'\x01\xe9\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 10:09:24.469 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 10:09:24.476 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.476 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=233), 234, b'\x01\xe9\x02\x00')
2023-12-07 10:09:24.486 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 131]
2023-12-07 10:09:24.486 DEBUG (MainThread) [bellows.zigbee.application] Request 234 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.571 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.dining_room_light>
2023-12-07 10:09:24.572 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2023-12-07 10:09:24.572 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=235, command_id=1, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 10:09:24.572 DEBUG (MainThread) [zigpy.zcl] [0x446A:2:0x0006] Sending request: on()
2023-12-07 10:09:24.572 DEBUG (MainThread) [zigpy.device] [0x446a] Extending timeout for 0xeb request
2023-12-07 10:09:24.572 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 10, 9, 24, 572626, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x446A), dst_ep=2, source_route=None, extended_timeout=True, tsn=235, profile_id=260, cluster_id=6, data=Serialized[b'\x01\xeb\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 10:09:24.572 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:24.579 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.580 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=235), 236, b'\x01\xeb\x01')
2023-12-07 10:09:24.588 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 132]
2023-12-07 10:09:24.588 DEBUG (MainThread) [bellows.zigbee.application] Request 236 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.647 DEBUG (MainThread) [homeassistant.components.zha.entity] light.dining_room_light: transition complete - future attribute reports will write HA state
2023-12-07 10:09:24.703 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:24.709 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.709 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=229), 230, b'\x01\xe5\x01')
2023-12-07 10:09:24.717 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 133]
2023-12-07 10:09:24.718 DEBUG (MainThread) [bellows.zigbee.application] Request 230 failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.769 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteErrorHandler: [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9b9a]
2023-12-07 10:09:24.769 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteErrorHandler frame with [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9b9a]
2023-12-07 10:09:24.769 DEBUG (MainThread) [bellows.zigbee.application] Processing route error: status=EmberStatus.MAC_INDIRECT_TIMEOUT, nwk=0x9b9a
2023-12-07 10:09:24.777 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteErrorHandler: [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9b9a]
2023-12-07 10:09:24.777 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteErrorHandler frame with [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9b9a]
2023-12-07 10:09:24.777 DEBUG (MainThread) [bellows.zigbee.application] Processing route error: status=EmberStatus.MAC_INDIRECT_TIMEOUT, nwk=0x9b9a
2023-12-07 10:09:24.816 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=114), 84, -79, 0x9b9a, 255, 255, b'\x9b\x9a\x9b\xe1\x03\xfb\xd7p8\xc1\xa4\x80']
2023-12-07 10:09:24.816 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=114), 84, -79, 0x9b9a, 255, 255, b'\x9b\x9a\x9b\xe1\x03\xfb\xd7p8\xc1\xa4\x80']
2023-12-07 10:09:24.816 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 10, 9, 24, 816541, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9B9A), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=114, profile_id=0, cluster_id=19, data=Serialized[b'\x9b\x9a\x9b\xe1\x03\xfb\xd7p8\xc1\xa4\x80'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=84, rssi=-79)
2023-12-07 10:09:24.816 INFO (MainThread) [zigpy.application] Device 0x9b9a (a4:c1:38:70:d7:fb:03:e1) joined the network
2023-12-07 10:09:24.816 DEBUG (MainThread) [zigpy.device] [0x9b9a] Cancelling old group rescan
2023-12-07 10:09:24.816 DEBUG (MainThread) [zigpy.zdo] [0x9b9a:zdo] ZDO request ZDOCmd.Device_annce: [0x9B9A, a4:c1:38:70:d7:fb:03:e1, 128]
2023-12-07 10:09:24.817 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=237, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-12-07 10:09:24.817 DEBUG (MainThread) [zigpy.zcl] [0x9B9A:1:0x0004] Sending request: get_membership(groups=[])
2023-12-07 10:09:24.817 DEBUG (MainThread) [zigpy.device] [0x9b9a] Extending timeout for 0xed request
2023-12-07 10:09:24.817 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2023, 12, 7, 10, 9, 24, 817440, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9B9A), dst_ep=1, source_route=None, extended_timeout=True, tsn=237, profile_id=260, cluster_id=4, data=Serialized[b'\x01\xed\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-12-07 10:09:24.817 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:70:d7:fb:03:e1, True)
2023-12-07 10:09:24.824 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.824 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9b9a, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=237), 238, b'\x01\xed\x02\x00')
2023-12-07 10:09:24.835 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 134]
2023-12-07 10:09:24.835 DEBUG (MainThread) [bellows.zigbee.application] Request 238 failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.859 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:24.866 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.866 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=231), 232, b'\x01\xe7\x01')
2023-12-07 10:09:24.876 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 135]
2023-12-07 10:09:24.876 DEBUG (MainThread) [bellows.zigbee.application] Request 232 failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:24.938 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:24.944 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:24.944 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=225), 226, b'\x01\xe1\x01')
2023-12-07 10:09:24.953 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 136]
2023-12-07 10:09:24.954 DEBUG (MainThread) [bellows.zigbee.application] Request 226 failed to enqueue, retrying in 1.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:25.090 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:a4:94:44:5b:19, True)
2023-12-07 10:09:25.097 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-12-07 10:09:25.097 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x446a, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=235), 236, b'\x01\xeb\x01')
2023-12-07 10:09:25.106 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 137]
2023-12-07 10:09:25.107 DEBUG (MainThread) [bellows.zigbee.application] Request 236 failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
2023-12-07 10:09:25.185 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139804343336128] Failed to send request: Failed to enqueue message after 3 attempts: <EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/__init__.py", line 64, in wrap_zigpy_exceptions
    yield
  File "/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/__init__.py", line 84, in wrapper
    return await RETRYABLE_REQUEST_DECORATOR(func)(*args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/util.py", line 138, in retry
    return await func()
           ^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/quirks/__init__.py", line 199, in command
    return await self.request(
           ^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/zcl/__init__.py", line 377, in request
    return await self._endpoint.request(
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py", line 253, in request
    return await self.device.request(
           ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/device.py", line 299, in request
    await self._application.request(
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 824, in request
    await self.send_packet(
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 811, in send_packet
    raise zigpy.exceptions.DeliveryError(
zigpy.exceptions.DeliveryError: Failed to enqueue message after 3 attempts: <EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 238, in handle_call_service
    response = await hass.services.async_call(
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/core.py", line 2067, in async_call
    response_data = await coro
                    ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/core.py", line 2104, in _execute_service
    return await target(service_call)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/entity_component.py", line 272, in handle_service
    return await service.entity_service_call(
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 878, in entity_service_call
    single_response = await _handle_entity_call(
                      ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 948, in _handle_entity_call
    result = await task
             ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/light/__init__.py", line 591, in async_handle_light_off_service
    await light.async_turn_off(**filter_turn_off_params(light, params))
  File "/usr/src/homeassistant/homeassistant/components/zha/light.py", line 471, in async_turn_off
    result = await self._on_off_cluster_handler.off()
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/__init__.py", line 83, in wrapper
    with wrap_zigpy_exceptions():
  File "/usr/local/lib/python3.11/contextlib.py", line 155, in __exit__
    self.gen.throw(typ, value, traceback)
  File "/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/__init__.py", line 75, in wrap_zigpy_exceptions
    raise HomeAssistantError(message) from exc
homeassistant.exceptions.HomeAssistantError: Failed to send request: Failed to enqueue message after 3 attempts: <EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>
2023-12-07 10:09:25.209 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=system_log, service=write, service_data=logger=frontend.js.latest.202312060, message=Unhandled promise rejection from Brave 120.0.0.0 on Windows 10
{
  "code": "home_assistant_error",
  "message": "Failed to send request: Failed to enqueue message after 3 attempts: <EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>"
}, level=debug>

Stiltjack · December 7, 2023, 10:40am

Are you using zigbee groups?

Group commands are network-wide broadcasts that are bounced back and forth between all devices to make sure every possible group member has a chance to “hear” the message. It is not a good idea to issue several in quick succession - for example in an automation - as this may swamp the network.

Having said that, I’ve had a similar problem with Moes switches not responding (only the 2-gang ones - 1-gang seem to be fine). Haven’t resolved it yet, but they seem to recover spontaneously sometimes. Otherwise they come back to life if you turn the power off and on - which is, I agree, a pain.

liamstears · December 7, 2023, 10:57am

Wouldn’t even know how to setup a Zigbee group lol

But yes that’s exactly what was happening to me on z2m! Very frustrating as I’ve spent a lot of money converting my whole house to these switches to end up having problens

Hedda · December 7, 2023, 12:11pm

Also read and try following and tips here before troubleshooting any deeper as very common that EMF interference and/or too few Zigbee Router devices can cause weird and intermittent/random issues → Zigbee networks: how to avoid interference and get the best range and coverage

sol3uk · May 15, 2024, 9:06am

@liamstears did you ever get to the bottom of this? I’m getting the same issue and am having trouble with just understanding the logs, what’s normal and what isn’t, as well as being able to track down if it’s just one device misbehaving. Any help would be really appreciated.

isaackehle · May 24, 2024, 12:45pm

+1 for this question. I have been troubleshooting the same behavior with the Zigbee devices in my house. This was happening with the zigbee network on the habitat, then I moved over to the Silicon Labs direct ZHA solution with similar results.

EDIT: For what it’s worth, I have created the Zigbee groups under the Configuration settings of the SL orchestrator. That sometimes seems to have fixed it, othertimes not.

liamstears · May 24, 2024, 4:47pm

Sorry for the delay guys

My solution in the end was to put all the moes ZigBee lights on a hacked Lidl/silvercrest gateway using local tuya. Haven’t had an issue since.

My guess is that there’s something in these switches which isn’t quite “normal” when it comes to ZigBee functionality, maybe something proprietary that upsets normal ZigBee connections.

Either way I needed a quick fix and this worked, would prefer to have them on zigbee2mqttt but I needed stability…