Hello,
I was using the setup in topic until recently (with some other zigbee devices). I wanted to try out the ZBT-1 in zigbee mode, so I migrated everything, which worked fine.
I decided to migrate back to my CC2351 and use my ZBT-1 as matter device (to gradually move away from zigbee(but it is not installed yet => so no conflicts)).
So I migrated back, which “worked without errors” (but nothing really did work, so had to reinstall my zigbee network manually).
Everything is working fine, except my STYRBAR. It gets recognized (slowly), but after that the buttons are not working (even being very close to the coordinator).
I am dazzled. Tried removing the STYRBAR, reloading ZHA, nothing works…
I have a debug log, but is rather large. I don’t know what I should be looking for…tried a copy paste of the laste lines of debug (moment when I normally added the STYRBAR)
2024-12-23 12:04:17.381 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 1794, 'attr_id': 512, 'value': <bitmap8: 0>, 'timestamp': 1734951857.356575, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:17.383 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:17.383 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:17.867 DEBUG (MainThread) [zigpy.application] Feeding watchdog
2024-12-23 12:04:17.868 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2024-12-23 12:04:17.872 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.SYS|AF|ZDO|SAPI|UTIL|APP: 377>)
2024-12-23 12:04:22.215 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=2820, SrcAddr=0x0EA1, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=84, SecurityUse=<Bool.false: 0>, TimeStamp=950220, TSN=0, Data=b'\x08\x6E\x0A\x05\x05\x21\xEC\x00\x08\x05\x21\x96\x00\x0B\x05\x29\x16\x00', MacSrcAddr=0x0EA1, MsgResultRadius=29)
2024-12-23 12:04:22.216 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2024, 12, 23, 11, 4, 22, 216654, tzinfo=datetime.timezone.utc), priority=0, src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0EA1), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=2820, data=Serialized[b'\x08n\n\x05\x05!\xec\x00\x08\x05!\x96\x00\x0b\x05)\x16\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=29, non_member_radius=0, lqi=84, rssi=None)
2024-12-23 12:04:22.218 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Received ZCL frame: b'\x08n\n\x05\x05!\xec\x00\x08\x05!\x96\x00\x0b\x05)\x16\x00'
2024-12-23 12:04:22.218 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl<0x08>(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=110, command_id=10, *direction=<Direction.Server_to_Client: 1>)
2024-12-23 12:04:22.220 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Decoded ZCL frame: TuyaZBElectricalMeasurement:Report_Attributes(attribute_reports=[Attribute(attrid=0x0505, value=TypeValue(type=uint16_t, value=236)), Attribute(attrid=0x0508, value=TypeValue(type=uint16_t, value=150)), Attribute(attrid=0x050B, value=TypeValue(type=int16s, value=22))])
2024-12-23 12:04:22.222 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Received command 0x0A (TSN 110): Report_Attributes(attribute_reports=[Attribute(attrid=0x0505, value=TypeValue(type=uint16_t, value=236)), Attribute(attrid=0x0508, value=TypeValue(type=uint16_t, value=150)), Attribute(attrid=0x050B, value=TypeValue(type=int16s, value=22))])
2024-12-23 12:04:22.223 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Attribute report received: rms_voltage=236, rms_current=150, active_power=22
2024-12-23 12:04:22.224 DEBUG (MainThread) [zha.zigbee.cluster_handlers] [0x0EA1:1:0x0b04]: cluster_handler[electrical_measurement] attribute_updated - cluster[Electrical Measurement] attr[rms_voltage] value[236]
2024-12-23 12:04:22.224 DEBUG (MainThread) [zha] Emitting event cluster_handler_attribute_updated with data ClusterAttributeUpdatedEvent(attribute_id=1285, attribute_name='rms_voltage', attribute_value=236, cluster_handler_unique_id='a4:c1:38:45:09:0d:07:df:1:0x0b04', cluster_id=2820, event_type='cluster_handler_event', event='cluster_handler_attribute_updated') (3 listeners)
2024-12-23 12:04:22.225 DEBUG (MainThread) [zha.zigbee.cluster_handlers] [0x0EA1:1:0x0b04]: cluster_handler[electrical_measurement] attribute_updated - cluster[Electrical Measurement] attr[rms_current] value[150]
2024-12-23 12:04:22.225 DEBUG (MainThread) [zha] Emitting event cluster_handler_attribute_updated with data ClusterAttributeUpdatedEvent(attribute_id=1288, attribute_name='rms_current', attribute_value=150, cluster_handler_unique_id='a4:c1:38:45:09:0d:07:df:1:0x0b04', cluster_id=2820, event_type='cluster_handler_event', event='cluster_handler_attribute_updated') (3 listeners)
2024-12-23 12:04:22.225 DEBUG (MainThread) [zha] Emitting event state_changed with data EntityStateChangedEvent(event_type='entity', event='state_changed', platform=<Platform.SENSOR: 'sensor'>, unique_id='a4:c1:38:45:09:0d:07:df-1-2820-rms_current', device_ieee=a4:c1:38:45:09:0d:07:df, endpoint_id=1, group_id=None) (1 listeners)
2024-12-23 12:04:22.226 DEBUG (MainThread) [homeassistant.components.zha.entity] sensor.tz3000_gjnozsaz_ts011f_current: Handling event from entity: EntityStateChangedEvent(event_type='entity', event='state_changed', platform=<Platform.SENSOR: 'sensor'>, unique_id='a4:c1:38:45:09:0d:07:df-1-2820-rms_current', device_ieee=a4:c1:38:45:09:0d:07:df, endpoint_id=1, group_id=None)
2024-12-23 12:04:22.227 DEBUG (MainThread) [zha.zigbee.cluster_handlers] [0x0EA1:1:0x0b04]: cluster_handler[electrical_measurement] attribute_updated - cluster[Electrical Measurement] attr[active_power] value[22]
2024-12-23 12:04:22.229 DEBUG (MainThread) [zha] Emitting event cluster_handler_attribute_updated with data ClusterAttributeUpdatedEvent(attribute_id=1291, attribute_name='active_power', attribute_value=22, cluster_handler_unique_id='a4:c1:38:45:09:0d:07:df:1:0x0b04', cluster_id=2820, event_type='cluster_handler_event', event='cluster_handler_attribute_updated') (3 listeners)
2024-12-23 12:04:22.230 DEBUG (MainThread) [zha] Emitting event state_changed with data EntityStateChangedEvent(event_type='entity', event='state_changed', platform=<Platform.SENSOR: 'sensor'>, unique_id='a4:c1:38:45:09:0d:07:df-1-2820', device_ieee=a4:c1:38:45:09:0d:07:df, endpoint_id=1, group_id=None) (1 listeners)
2024-12-23 12:04:22.230 DEBUG (MainThread) [homeassistant.components.zha.entity] sensor.tz3000_gjnozsaz_ts011f_power: Handling event from entity: EntityStateChangedEvent(event_type='entity', event='state_changed', platform=<Platform.SENSOR: 'sensor'>, unique_id='a4:c1:38:45:09:0d:07:df-1-2820', device_ieee=a4:c1:38:45:09:0d:07:df, endpoint_id=1, group_id=None)
2024-12-23 12:04:22.233 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Sending reply header: ZCLHeader(frame_control=FrameControl<0x10>(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 0>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=110, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 0>)
2024-12-23 12:04:22.234 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0b04] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2024-12-23 12:04:22.235 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2024, 12, 23, 11, 4, 22, 234969, tzinfo=datetime.timezone.utc), priority=<PacketPriority.LOW: -1>, src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0EA1), dst_ep=1, source_route=None, extended_timeout=False, tsn=110, profile_id=260, cluster_id=2820, data=Serialized[b'\x10n\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2024-12-23 12:04:22.237 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, 'UPDATE devices_v13\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1734951862.216654, 'ieee': a4:c1:38:45:09:0d:07:df, 'min_update_delta': 30.0})
2024-12-23 12:04:22.236 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x0EA1), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=2820, TSN=110, Options=<TransmitOptions.ACK_REQUEST|SUPPRESS_ROUTE_DISC_NETWORK: 48>, Radius=0, Data=b'\x10\x6E\x0B\x0A\x00')
2024-12-23 12:04:22.242 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, 'UPDATE devices_v13\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1734951862.216654, 'ieee': a4:c1:38:45:09:0d:07:df, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:22.247 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:22.248 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:22.249 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2024-12-23 12:04:22.251 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 2820, 'attr_id': 1285, 'value': 236, 'timestamp': 1734951862.224141, 'min_update_delta': 30.0})
2024-12-23 12:04:22.253 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 2820, 'attr_id': 1285, 'value': 236, 'timestamp': 1734951862.224141, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:22.256 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:22.257 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:22.259 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 2820, 'attr_id': 1288, 'value': 150, 'timestamp': 1734951862.225063, 'min_update_delta': 30.0})
2024-12-23 12:04:22.263 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 2820, 'attr_id': 1288, 'value': 150, 'timestamp': 1734951862.225063, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:22.266 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:22.268 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:22.269 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 2820, 'attr_id': 1291, 'value': 22, 'timestamp': 1734951862.227235, 'min_update_delta': 30.0})
2024-12-23 12:04:22.270 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 2820, 'attr_id': 1291, 'value': 22, 'timestamp': 1734951862.227235, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:22.274 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=110)
2024-12-23 12:04:22.276 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:22.277 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:22.284 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=1794, SrcAddr=0x0EA1, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=84, SecurityUse=<Bool.false: 0>, TimeStamp=950436, TSN=0, Data=b'\x08\x6F\x0A\x00\x00\x25\x84\xF7\x00\x00\x00\x00', MacSrcAddr=0x0EA1, MsgResultRadius=29)
2024-12-23 12:04:22.285 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(timestamp=datetime.datetime(2024, 12, 23, 11, 4, 22, 285278, tzinfo=datetime.timezone.utc), priority=0, src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0EA1), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=1794, data=Serialized[b'\x08o\n\x00\x00%\x84\xf7\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=29, non_member_radius=0, lqi=84, rssi=None)
2024-12-23 12:04:22.286 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Received ZCL frame: b'\x08o\n\x00\x00%\x84\xf7\x00\x00\x00\x00'
2024-12-23 12:04:22.287 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl<0x08>(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=111, command_id=10, *direction=<Direction.Server_to_Client: 1>)
2024-12-23 12:04:22.289 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Decoded ZCL frame: TuyaZBMeteringCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint48_t, value=63364))])
2024-12-23 12:04:22.289 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Received command 0x0A (TSN 111): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint48_t, value=63364))])
2024-12-23 12:04:22.290 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Attribute report received: current_summ_delivered=63364
2024-12-23 12:04:22.290 DEBUG (MainThread) [zha.zigbee.cluster_handlers] [0x0EA1:1:0x0702]: cluster_handler[smartenergy_metering] attribute_updated - cluster[TuyaZBMeteringCluster] attr[current_summ_delivered] value[63364]
2024-12-23 12:04:22.290 DEBUG (MainThread) [zha] Emitting event cluster_handler_attribute_updated with data ClusterAttributeUpdatedEvent(attribute_id=0, attribute_name='current_summ_delivered', attribute_value=63364, cluster_handler_unique_id='a4:c1:38:45:09:0d:07:df:1:0x0702', cluster_id=1794, event_type='cluster_handler_event', event='cluster_handler_attribute_updated') (1 listeners)
2024-12-23 12:04:22.292 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Sending reply header: ZCLHeader(frame_control=FrameControl<0x10>(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 0>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=111, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 0>)
2024-12-23 12:04:22.293 DEBUG (MainThread) [zigpy.zcl] [0x0EA1:1:0x0702] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2024-12-23 12:04:22.294 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(timestamp=datetime.datetime(2024, 12, 23, 11, 4, 22, 294756, tzinfo=datetime.timezone.utc), priority=<PacketPriority.LOW: -1>, src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0EA1), dst_ep=1, source_route=None, extended_timeout=False, tsn=111, profile_id=260, cluster_id=1794, data=Serialized[b'\x10o\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2024-12-23 12:04:22.296 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x0EA1), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=1794, TSN=111, Options=<TransmitOptions.ACK_REQUEST|SUPPRESS_ROUTE_DISC_NETWORK: 48>, Radius=0, Data=b'\x10\x6F\x0B\x0A\x00')
2024-12-23 12:04:22.297 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, 'UPDATE devices_v13\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1734951862.285278, 'ieee': a4:c1:38:45:09:0d:07:df, 'min_update_delta': 30.0})
2024-12-23 12:04:22.297 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, 'UPDATE devices_v13\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_update_delta', {'ts': 1734951862.285278, 'ieee': a4:c1:38:45:09:0d:07:df, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:22.298 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:22.299 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:22.300 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 1794, 'attr_id': 0, 'value': 63364, 'timestamp': 1734951862.290681, 'min_update_delta': 30.0})
2024-12-23 12:04:22.301 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fba687a5c60>, '\n INSERT INTO attributes_cache_v13\n VALUES (:ieee, :endpoint_id, :cluster_type, :cluster_id, :attr_id, :value, :timestamp)\n ON CONFLICT (ieee, endpoint_id, cluster_type, cluster_id, attr_id) DO UPDATE\n SET value=excluded.value, last_updated=excluded.last_updated\n WHERE\n value != excluded.value\n OR :timestamp - last_updated > :min_update_delta\n ', {'ieee': a4:c1:38:45:09:0d:07:df, 'endpoint_id': 1, 'cluster_type': <ClusterType.Server: 0>, 'cluster_id': 1794, 'attr_id': 0, 'value': 63364, 'timestamp': 1734951862.290681, 'min_update_delta': 30.0}) completed
2024-12-23 12:04:22.303 DEBUG (Thread-30) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>)
2024-12-23 12:04:22.307 DEBUG (Thread-30) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fba687a5c60>) completed
2024-12-23 12:04:22.308 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2024-12-23 12:04:22.322 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=111)