Thanks for the wealth of information.
I’m still waiting for my smart plugs to come in, but after this morning, I’m not convinced they will solve the issue. Things stopped working again today for some of my lights, even though most all of them have a green connection right now.
And the log is scrolling with information like this:
> 2024-04-13 08:42:56.568 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'26a6a1a9602a675278f87e'
> 2024-04-13 08:42:56.569 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
> 2024-04-13 08:42:56.570 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 224]
> 2024-04-13 08:42:56.570 DEBUG (MainThread) [bellows.zigbee.application] Request (0xF6C0, 159) failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
> 2024-04-13 08:42:56.773 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xBEE7](E11-G13): Device seen - marking the device available and resetting counter
> 2024-04-13 08:42:56.774 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xBEE7](E11-G13): Update device availability - device available: True - new availability: True - changed: False
> 2024-04-13 08:42:57.072 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf6c0, EmberApsFrame(profileId=0, clusterId=32768, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=245), 159, b'\xf5\x00%\xb0\xfd\xfe\xffA-\x14\x00\x00\x00\x00')
> 2024-04-13 08:42:57.076 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'63a721a9602a1572af944a252a5592099d4e275e72c0928bd8769e77033f12b3ebcdde6f5a947e'
> 2024-04-13 08:42:57.087 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'37a7a1a9602a6753aadf7e'
> 2024-04-13 08:42:57.087 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
> 2024-04-13 08:42:57.089 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 225]
> 2024-04-13 08:42:57.091 DEBUG (MainThread) [bellows.zigbee.application] Request (0xF6C0, 159) failed to enqueue, retrying in 1.0s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
> 2024-04-13 08:42:57.107 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAD17](E11-G13): Device seen - marking the device available and resetting counter
> 2024-04-13 08:42:57.107 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAD17](E11-G13): Update device availability - device available: True - new availability: True - changed: False
> 2024-04-13 08:42:58.092 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf6c0, EmberApsFrame(profileId=0, clusterId=32768, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=245), 159, b'\xf5\x00%\xb0\xfd\xfe\xffA-\x14\x00\x00\x00\x00')
> 2024-04-13 08:42:58.096 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'74a421a9602a1572af944a252a5592099d4e275e72c0928bd8769e77033f12b3ebcdde6f995e7e'
> 2024-04-13 08:42:58.107 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'40a4a1a9602a6750352a7e'
> 2024-04-13 08:42:58.107 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
> 2024-04-13 08:42:58.109 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 226]
> 2024-04-13 08:42:58.109 DEBUG (MainThread) [bellows.zigbee.application] Request (0xF6C0, 159) failed to enqueue, retrying in 1.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
> 2024-04-13 08:42:59.248 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50a4b5a9362aba43ad9e4a4aa755d0047e'
> 2024-04-13 08:42:59.248 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
> 2024-04-13 08:42:59.250 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingSenderEui64Handler: [00:0d:6f:00:0a:f4:f1:af]
> 2024-04-13 08:42:59.250 DEBUG (MainThread) [bellows.zigbee.application] Received incomingSenderEui64Handler frame with [00:0d:6f:00:0a:f4:f1:af]
> 2024-04-13 08:42:59.255 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'60a4b1a9112a11b259944a25aa5596499caf97632d389874f63046390180c0e6c6d9de6f59187e'
> 2024-04-13 08:42:59.255 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
> 2024-04-13 08:42:59.260 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_SOURCE_EUI64: 1024>, groupId=0, sequence=225), 176, -56, 0xf6c0, 255, 255, b'\xf6%\xb0\xfd\xfe\xffA-\x14\x00\x00']
> 2024-04-13 08:42:59.261 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_SOURCE_EUI64: 1024>, groupId=0, sequence=225), 176, -56, 0xf6c0, 255, 255, b'\xf6%\xb0\xfd\xfe\xffA-\x14\x00\x00']
> 2024-04-13 08:42:59.262 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2024, 4, 13, 13, 42, 59, 262272, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF6C0), 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=225, profile_id=0, cluster_id=0, data=Serialized[b'\xf6%\xb0\xfd\xfe\xffA-\x14\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=176, rssi=-56)
> 2024-04-13 08:42:59.263 DEBUG (MainThread) [zigpy.zdo] [0xf6c0:zdo] ZDO request ZDOCmd.NWK_addr_req: [14:2d:41:ff:fe:fd:b0:25, <AddrRequestType.Single: 0>, 0]
> 2024-04-13 08:42:59.264 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2024, 4, 13, 13, 42, 59, 264650, tzinfo=datetime.timezone.utc), src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF6C0), dst_ep=0, source_route=None, extended_timeout=False, tsn=246, profile_id=0, cluster_id=<ZDOCmd.NWK_addr_rsp: 0x8000>, data=Serialized[b'\xf6\x00%\xb0\xfd\xfe\xffA-\x14\x00\x00\x00\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
> 2024-04-13 08:42:59.265 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf6c0, EmberApsFrame(profileId=0, clusterId=32768, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=246), 160, b'\xf6\x00%\xb0\xfd\xfe\xffA-\x14\x00\x00\x00\x00')
> 2024-04-13 08:42:59.266 DEBUG (Thread-32) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f3d616b60>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1713015779.262272, 'ieee': 00:0d:6f:00:0a:f4:f1:af, 'min_update_delta': 30.0})
> 2024-04-13 08:42:59.268 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'07a521a9602a1572af944a252a5592099d4e275d4dc0918bd8769e77033f12b3ebcdde6f7d38f47e'
> 2024-04-13 08:42:59.268 DEBUG (Thread-32) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f3d616b60>, 'UPDATE devices_v12\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1713015779.262272, 'ieee': 00:0d:6f:00:0a:f4:f1:af, 'min_update_delta': 30.0}) completed
> 2024-04-13 08:42:59.270 DEBUG (Thread-32) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f3d616b60>)
> 2024-04-13 08:42:59.270 DEBUG (Thread-32) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f3d616b60>) completed
> 2024-04-13 08:42:59.278 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'71a5a1a9602a6756ec827e'
> 2024-04-13 08:42:59.278 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
> 2024-04-13 08:42:59.280 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.MAX_MESSAGE_LIMIT_REACHED: 114>, 228]
> 2024-04-13 08:42:59.281 DEBUG (MainThread) [bellows.zigbee.application] Request (0xF6C0, 160) failed to enqueue, retrying in 0.5s: EmberStatus.MAX_MESSAGE_LIMIT_REACHED
> 2024-04-13 08:42:59.537 DEBUG (MainThread) [zigpy.application] Feeding watchdog
> 2024-04-13 08:42:59.538 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
> 2024-04-13 08:42:59.540 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'10aa21a9a52a7d5d0a7e'
> 2024-04-13 08:42:59.558 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'02aaa1a9a52a34b35994a525aa5592499c4ed9abedce408bfdc66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ff648a45984d9e4f9ff7c3d9d46a35a251904824a57c7e'
> 2024-04-13 08:42:59.558 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
> 2024-04-13 08:42:59.561 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[289, 0, 239, 0, 0, 0, 254, 0, 39, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 309, 0, 2, 0, 0, 0, 0, 0, 0, 0]]
> 2024-04-13 08:42:59.563 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
> 2024-04-13 08:42:59.565 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'21ab21a9fe2a16ddbe7e'
> 2024-04-13 08:42:59.571 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'13aba1a9fe2a15b39dfce87e'
> 2024-04-13 08:42:59.572 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
> 2024-04-13 08:42:59.573 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xc4']
> 2024-04-13 08:42:59.574 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 196
> 2024-04-13 08:42:59.574 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 19607, MAC_TX_BROADCAST = 7536, MAC_RX_UNICAST = 137263, MAC_TX_UNICAST_SUCCESS = 107830, MAC_TX_UNICAST_RETRY = 8069, MAC_TX_UNICAST_FAILED = 1660, APS_DATA_RX_BROADCAST = 10943, APS_DATA_TX_BROADCAST = 48, APS_DATA_RX_UNICAST = 58450, APS_DATA_TX_UNICAST_SUCCESS = 50588, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 6522, ROUTE_DISCOVERY_INITIATED = 137, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 22, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, 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 = 66415, PHY_CCA_FAIL_COUNT = 117044, BROADCAST_TABLE_FULL = 128, 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 = 196]