2023-02-23 11:24:58.977 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0500] Sending request: enroll_response(enroll_response_code=<EnrollResponse.Success: 0>, zone_id=0)
2023-02-23 11:24:58.979 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x19 request
2023-02-23 11:24:58.980 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=1, source_route=None, extended_timeout=True, tsn=25, profile_id=260, cluster_id=1280, data=Serialized[b'\x01\x19\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-23 11:24:58.987 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'47a7a1a92a2a323b7e'
2023-02-23 11:24:58.987 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-23 11:24:59.020 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (54:ef:44:10:00:71:1d:00, 1, 0, 5, 'lumi.sensor_smoke.acn03') params: FOREIGN KEY constraint failed
2023-02-23 11:24:59.021 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-23 11:24:59.041 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=23), 24, b'\x17\x00\x1dq\x00\x10D\xefT\x01\xc0\xfc\x03qs\xe3\xfe\xff\xfb\\8\x01')
2023-02-23 11:24:59.046 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'75a421a9602a15f3cd944a04aa5592499d4e27bcf5d8708be0b76399b8916ba62b31dd1efc1c39242e8e518d4f1d7e'
2023-02-23 11:24:59.059 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50a4a1a9602a15e2e13c7e'
2023-02-23 11:24:59.060 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-23 11:24:59.082 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 80]
2023-02-23 11:24:59.121 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-23 11:24:59.124 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'06a521a92a2a15af28945a61450193218a7e'
2023-02-23 11:24:59.132 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'61a5a1a92a2ace517e'
2023-02-23 11:24:59.132 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-23 11:24:59.133 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-23 11:24:59.138 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=260, clusterId=1280, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=25), 26, b'\x01\x19\x00\x00\x00')
2023-02-23 11:24:59.142 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'17aa21a9602a15f3cd904b25af5493499d4e27b2f7cb6692fdc66346887e'
2023-02-23 11:24:59.153 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'72aaa1a9602a15e3375b7e'
2023-02-23 11:24:59.153 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-23 11:24:59.158 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 81]
2023-02-23 11:25:00.089 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'02aab1a96b2a15f3cd944a04aa5592499d4e27fbf5ce67fbc47e'
2023-02-23 11:25:00.090 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-23 11:25:00.093 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=80), 24, <EmberStatus.SUCCESS: 0>, b'']
2023-02-23 11:25:00.094 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=80), 24, <EmberStatus.SUCCESS: 0>, b'']
2023-02-23 11:25:00.107 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12aab1a9112a15b259b5ca25aa1593499c5ed877ac5a9874ffd1638bb0ea7e'
2023-02-23 11:25:00.107 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-23 11:25:00.111 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=16), 255, -36, 0x9441, 255, 255, b'\x17\x00']
2023-02-23 11:25:00.112 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=16), 255, -36, 0x9441, 255, 255, b'\x17\x00']
2023-02-23 11:25:00.112 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=16, profile_id=0, cluster_id=32801, data=Serialized[b'\x17\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-36)
2023-02-23 11:25:00.118 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: bound 'manufacturer_specific' cluster: Status.SUCCESS
2023-02-23 11:25:00.118 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: Configuring cluster attribute reporting
2023-02-23 11:25:00.120 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: finished channel configuration
2023-02-23 11:25:00.127 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: 'async_configure' stage succeeded
2023-02-23 11:25:00.128 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: 'async_configure' stage succeeded
2023-02-23 11:25:00.128 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: 'async_configure' stage succeeded
2023-02-23 11:25:00.129 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: 'async_configure' stage succeeded
2023-02-23 11:25:00.129 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: 'async_configure' stage succeeded
2023-02-23 11:25:00.129 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0019]: 'async_configure' stage succeeded
2023-02-23 11:25:00.135 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): completed configuration
2023-02-23 11:25:00.137 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] 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, *is_reply=False), tsn=27, command_id=64, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2023-02-23 11:25:00.140 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] Sending request: trigger_effect(effect_id=<EffectIdentifier.Okay: 2>, effect_variant=<EffectVariant.Default: 0>)
2023-02-23 11:25:00.141 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x1b request
2023-02-23 11:25:00.142 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=1, source_route=None, extended_timeout=True, tsn=27, profile_id=260, cluster_id=3, data=Serialized[b'\x01\x1b@\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-23 11:25:00.142 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-23 11:25:00.144 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'22ab21a92a2a15af28945a61450193c7207e'
2023-02-23 11:25:00.150 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'23aba1a92a2ae0a97e'
2023-02-23 11:25:00.151 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-23 11:25:00.156 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-23 11:25:00.165 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=27), 28, b'\x01\x1b@\x02\x00')
2023-02-23 11:25:00.169 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'33a821a9602a15f3cd904b26aa5493499d4e27b0f1cb6690bdc463b2567e'
2023-02-23 11:25:00.185 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'34a8a1a9602a15e011c07e'
2023-02-23 11:25:00.185 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-23 11:25:00.187 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 82]
2023-02-23 11:25:00.309 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'44a8b1a96b2a15f3cd904b25af5493499d4e27faf7ce676f187e'
2023-02-23 11:25:00.309 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-23 11:25:00.314 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=1280, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=81), 26, <EmberStatus.SUCCESS: 0>, b'']
2023-02-23 11:25:00.315 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=1280, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=81), 26, <EmberStatus.SUCCESS: 0>, b'']
2023-02-23 11:25:01.518 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'54a8b1a96b2a15f3cd904b26aa5493499d4e27f9f1ce67fa837e'
2023-02-23 11:25:01.519 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-23 11:25:01.523 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=82), 28, <EmberStatus.SUCCESS: 0>, b'']
2023-02-23 11:25:01.524 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=82), 28, <EmberStatus.SUCCESS: 0>, b'']
2023-02-23 11:25:01.527 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'64a8b1a9112a15b658974a24ab1593499c5fc77fac5a9874f8de7882bc7e3ddf127e'
2023-02-23 11:25:01.527 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-23 11:25:01.528 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=17), 224, -44, 0x9441, 255, 255, b'\x18\x1b\x0b@\x00']
2023-02-23 11:25:01.529 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=17), 224, -44, 0x9441, 255, 255, b'\x18\x1b\x0b@\x00']
2023-02-23 11:25:01.530 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=17, profile_id=260, cluster_id=3, data=Serialized[b'\x18\x1b\x0b@\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44)
2023-02-23 11:25:01.531 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] Received ZCL frame: b'\x18\x1b\x0b@\x00'
2023-02-23 11:25:01.532 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] 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, *is_reply=True), tsn=27, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2023-02-23 11:25:01.534 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] Decoded ZCL frame: Identify:Default_Response(command_id=64, status=<Status.SUCCESS: 0>)
2023-02-23 11:25:01.540 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: executed 'trigger_effect' command with args: '()' kwargs: '{'effect_id': <EffectIdentifier.Okay: 2>, 'effect_variant': <EffectVariant.Default: 0>}' result: Default_Response(command_id=64, status=<Status.SUCCESS: 0>)
2023-02-23 11:25:01.541 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): started initialization
2023-02-23 11:25:01.542 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:ZDO](lumi.sensor_smoke.acn03): 'async_initialize' stage succeeded
2023-02-23 11:25:01.547 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: initializing channel: from_cache: False
2023-02-23 11:25:01.548 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: initializing uncached channel attributes: ['current_temperature'] - from cache[False]
2023-02-23 11:25:01.548 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: Reading attributes in chunks: ['current_temperature']
2023-02-23 11:25:01.549 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: finished channel initialization
2023-02-23 11:25:01.550 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: initializing channel: from_cache: False
2023-02-23 11:25:01.550 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: finished channel initialization
2023-02-23 11:25:01.551 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: initializing channel: from_cache: False
2023-02-23 11:25:01.551 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: finished channel initialization
2023-02-23 11:25:01.551 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: initializing channel: from_cache: False
2023-02-23 11:25:01.552 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: initializing cached channel attributes: ['zone_status', 'zone_type']
2023-02-23 11:25:01.552 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: Reading attributes in chunks: ['zone_status', 'zone_type']
2023-02-23 11:25:01.554 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: initializing uncached channel attributes: ['zone_state'] - from cache[False]
2023-02-23 11:25:01.555 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: Reading attributes in chunks: ['zone_state']
2023-02-23 11:25:01.556 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: finished channel initialization
2023-02-23 11:25:01.556 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: initializing channel: from_cache: False
2023-02-23 11:25:01.556 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: Performing channel specific initialization: []
2023-02-23 11:25:01.557 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: finished channel initialization
2023-02-23 11:25:01.557 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0019]: initializing channel: from_cache: False
2023-02-23 11:25:01.558 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0019]: finished channel initialization
2023-02-23 11:25:01.564 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: 'async_initialize' stage succeeded
2023-02-23 11:25:01.565 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: 'async_initialize' stage succeeded
2023-02-23 11:25:01.566 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: 'async_initialize' stage succeeded
2023-02-23 11:25:01.566 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: 'async_initialize' stage succeeded
2023-02-23 11:25:01.567 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: 'async_initialize' stage succeeded
2023-02-23 11:25:01.567 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0019]: 'async_initialize' stage succeeded
2023-02-23 11:25:01.574 DEBUG (MainThread) [zigpy.appdb] Error handling '_unsupported_attribute_added' event with (54:ef:44:10:00:71:1d:00, 1, 2, 0) params: FOREIGN KEY constraint failed
2023-02-23 11:25:01.577 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): power source: Mains
2023-02-23 11:25:01.578 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): completed initialization
2023-02-23 11:25:01.604 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new binary_sensor.zha entity: binary_sensor.lumi_lumi_sensor_smoke_acn03_iaszone
2023-02-23 11:25:01.606 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new button.zha entity: button.lumi_lumi_sensor_smoke_acn03_identify_2
2023-02-23 11:25:01.613 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.lumi_lumi_sensor_smoke_acn03_rssi_2
2023-02-23 11:25:01.615 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.lumi_lumi_sensor_smoke_acn03_lqi_2
2023-02-23 11:25:08.577 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-02-23 11:25:08.583 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'47a921a9a52a2b0d7e'
2023-02-23 11:25:08.600 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'75a9a1a9a52a14b26094d925bd5590499c4e22abe8ce698bf7c66289fd7e3ea7eacddf6f8bffc6dbd4d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a2519048244f7b7e'
2023-02-23 11:25:08.600 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-23 11:25:08.602 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[1, 57, 147, 23, 2, 0, 5, 5, 14, 10, 1, 1, 1, 1, 1, 4, 1, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-02-23 11:25:08.603 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-02-23 11:25:08.607 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'50ae21a9fe2a16a2bf7e'
2023-02-23 11:25:08.622 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'06aea1a9fe2a15b3c10b1d7e'
2023-02-23 11:25:08.622 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-23 11:25:08.626 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\x98']
2023-02-23 11:25:08.629 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 152
2023-02-23 11:25:08.629 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 1, MAC_TX_BROADCAST = 57, MAC_RX_UNICAST = 147, MAC_TX_UNICAST_SUCCESS = 23, MAC_TX_UNICAST_RETRY = 2, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 5, APS_DATA_TX_BROADCAST = 5, APS_DATA_RX_UNICAST = 14, APS_DATA_TX_UNICAST_SUCCESS = 10, APS_DATA_TX_UNICAST_RETRY = 1, APS_DATA_TX_UNICAST_FAILED = 1, ROUTE_DISCOVERY_INITIATED = 1, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 1, NEIGHBOR_STALE = 4, JOIN_INDICATION = 1, CHILD_REMOVED = 1, 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 = 0, PHY_CCA_FAIL_COUNT = 0, 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 = 152]
2023-02-23 11:25:34.830 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3197, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 25, 'X-attrib-6': 9, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 11:25:37.834 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3197, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 25, 'X-attrib-6': 1, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]
```

Then when I make some smoke nothing happens. Until I press the button to mute the alarm. Once I press the button I get an attribute change that reflects smoke. But it seems no attribute change happens automatically when the smoke alarm goes off.

Logs from pressing the button to mute then rebooting the device
```
2023-02-23 11:26:45.171 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3200, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 25, 'X-attrib-6': 3, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 1, '0xff01-161': 10, '0xff01-162': 0, '0xff01-163': 1, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 11:27:02.086 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3200, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 25, 'X-attrib-6': 5, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 1, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 1, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 11:27:22.579 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 0, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 27, 'X-attrib-6': 0, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 11:27:25.579 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 0, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 27, 'X-attrib-6': 1, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 11:27:27.430 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3183, 'temperature': 25, 'X-attrib-4': 424, 'X-attrib-5': 27, 'X-attrib-6': 1, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]
```

Is there something the quirk would need to do to tell the smoke alarm to update its attributes when triggered?

can it be that you need to update the 0x0002 attribute (ZONE_STATUS) and not the ZONE_STATE?

I think that we need to send a "Zone Status Change Notification" command for the IasZone stuff.
Here's how the motion sensor quirks do it:
https://github.com/zigpy/zha-device-handlers/blob/f6ab87e1c48ccb6db262e8732ca6b63d67fb6252/zhaquirks/__init__.py#L287-L288
@johnboiles I've added another commit: https://github.com/zigpy/zha-device-handlers/pull/2219/commits/4d5116a9679c623298ce9b21c901055d4768e59a (modified __init__.py file)

Heyyy that's some progress!

But unfortunately this doesn't actually happen until someone hits the mute button. Then it seems to push the state out to the device. In these logs, I triggered the smoke alarm at ~16:38:00 then hit the mute button at 16:38:47. The state did not update until I hit the mute button.
2023-02-23 16:21:40.600 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1c:25 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3229, 'temperature': 25, 'X-attrib-4': 5032, 'X-attrib-5': 11, 'X-attrib-6': 1, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 16:38:47.880 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3195, 'temperature': 25, 'X-attrib-4': 5032, 'X-attrib-5': 28, 'X-attrib-6': 3, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 1, '0xff01-161': 10, '0xff01-162': 0, '0xff01-163': 1, '0xff01-164': 0, '0xff01-165': 0}]
2023-02-23 16:40:20.634 WARNING (MainThread) [xiaomi] 54:ef:44:10:00:71:1d:00 - Attribute report. attribute_id: [247] value: [{'battery_voltage_mV': 3195, 'temperature': 25, 'X-attrib-4': 5032, 'X-attrib-5': 28, 'X-attrib-6': 8, '0xff01-8': 273, 'path': 0, '0xff01-12': 1, '0xff01-102': 3, '0xff01-103': 1, '0xff01-104': 168, 'smoke': 0, '0xff01-161': 0, '0xff01-162': 0, '0xff01-163': 0, '0xff01-164': 0, '0xff01-165': 0}]

So the remaining question is, how do we get the device to update attributes when the smoke alarm is triggered.

Hmm, so the log message containing 'smoke': 0 did only arrive when you hit the mute button (and not when the alarm was actually triggered)?
And there was nothing in the logs when the alarm triggered, right?
Z2M does some attribute reads when pairing the device -- maybe that's related(?)

Correct on both counts: nothing logs at all when the alarm is triggered. It's not until I hit the mute button that the attribute gets logged (and the binary sensor switches to 'detected'). Generally I can get the device to send attributes anytime I want by hitting the button. Here's a video demonstration: https://youtu.be/Lmj14oUMV3o
I'm new to Zigbee. Is it typical to need to subscribe or bind to certain events in Zigbee?

Interesting, thanks for the video.

Is it typical to need to subscribe or bind to certain events in Zigbee?

Somewhat. Generally, you need to set up reporting for an attribute in order to get "attribute reports". However, Xiaomi/Aqara doesn't follow Zigbee spec at all and they set up their own little messaging system. Most of the time, their devices send these messages automatically (when anything changes). I'll look at what Z2M does that could solve this.

Also, what coordinator (and what coordinator firmware) are you using?

I have a Home Assistant Yellow on HA 2023.2.5

I think on the Yellow the firmware comes with HA but I'm not sure. Let me know if there's a place to see the firmware version.
I'm out of the office now (where the smoke alarms are) but I'll add logging and try tomorrow! Thanks for all your help on this!

Pushed some more changes: https://github.com/zigpy/zha-device-handlers/pull/2219/files
Just to explain it a bit, the added attribute_names.update(...) adds a proper name for the "special Xiaomi attribute reports" (the ones you see when you press the button). What's weird is that there are many duplicated ones (in Z2M's code).
-> Try to grab a log message when triggering the alarm (maybe you need to press the button for now) ('smoke': 1)
-> Try to grab one where it switches back to 'smoke': 0

I've also added the "normal special" Zigbee attributes on the OppleCluster. I'm not sure the types I've given them are correct, because Z2M doesn't really type them.
I'm also unsure why Z2M reads all those attributes when "configuring"/pairing(?)
This time, please delete and re-pair the sensor again before trying anything (possibly so ZHA binds + sets up attribute reporting for those reports?)
I'm not (yet) doing anything with possible "normal" attribute reports for the attributes on the OppleCluster. They should produce a log message (with the updated logger settings) if there is an attribute report though.
I've tried to add some code to also parse normal attribute reports (if they exist) for the smoke attribute on the OppleCluster, so the entity gets updated. Still, please post the log messages when you trigger the alarm (and when you press the button).
You can also try to read/set those attributes on the OppleCluster using the clusters UI in HA.
(Device page -> three dots -> Manage Zigbee device -> clusters, then select the OppleCluster and in the second drop down, you can select the attributes and read/set them. Do note that you always want to leave the second text field empty.
If you want to write an attribute, put the text in the first text field (where it's also put if you read an attribute).)

I've applied the new changes, and the logging changes remotely and restarted HA.
I can read the OppleCluster things. I'm getting the following values:

buzzer (id: 0x013e): None
buzzer_manual_alarm (id: 0x013d): 0
buzzer_manual_mute (id: 0x0126): 0
heartbeat_indicator (id: 0x013c): 0
linkage_alarm (id: 0x014b): 0
selftest (id: 0x0127): None
smoke (id: 0x013a): 0
smoke_density (id: 0x013b): 0

Sometimes the attributes would fail to read with some logs like below. So I had to try several times on a few of the attributes. But I expect that's a normal thing when a zigbee device doesn't wake up at the right time right?

Logs when reading OppleCluster attributes fail
2023-02-23 19:50:27.493 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=64704, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=147), 28, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-02-23 19:50:27.494 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=64704, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=147), 28, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-02-23 19:50:27.496 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [547977646416] Error handling message: Unknown error (unknown_error) from <IP_ADDRESS> (Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Safari/605.1.15)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 27, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/zha/api.py", line 820, in websocket_read_zigbee_cluster_attributes
    success, failure = await cluster.read_attributes(
  File "/usr/local/lib/python3.10/site-packages/zigpy/zcl/__init__.py", line 471, in read_attributes
    result = await self.read_attributes_raw(to_read, manufacturer=manufacturer)
  File "/usr/local/lib/python3.10/site-packages/zigpy/quirks/__init__.py", line 219, in read_attributes_raw
    return await super().read_attributes_raw(
  File "/usr/local/lib/python3.10/site-packages/zigpy/quirks/__init__.py", line 285, in _read_attributes
    return await super()._read_attributes(
  File "/usr/local/lib/python3.10/site-packages/zigpy/zcl/__init__.py", line 324, in request
    return await self._endpoint.request(
  File "/usr/local/lib/python3.10/site-packages/zigpy/endpoint.py", line 237, in request
    return await self.device.request(
  File "/usr/local/lib/python3.10/site-packages/zigpy/device.py", line 297, in request
    await self._application.request(
  File "/usr/local/lib/python3.10/site-packages/zigpy/application.py", line 690, in request
    await self.send_packet(
  File "/usr/local/lib/python3.10/site-packages/bellows/zigbee/application.py", line 807, in send_packet
    raise zigpy.exceptions.DeliveryError(
zigpy.exceptions.DeliveryError: Failed to deliver message:
2023-02-23 19:50:27.511 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteErrorHandler: [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9441]
2023-02-23 19:50:27.512 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteErrorHandler frame with [<EmberStatus.MAC_INDIRECT_TIMEOUT: 66>, 0x9441]
2023-02-23 19:50:27.512 DEBUG (MainThread) [bellows.zigbee.application] Processing route error: status=EmberStatus.MAC_INDIRECT_TIMEOUT, nwk=0x9441

Ooooh if I write a 1 to buzzer_manual_alarm (id: 0x013d) I can turn on the alarm (as verified via audio from my security cameras).
Tomorrow I will

Start collecting logs in a separate window
Remove and re-pair the smoke alarm (I've been doing that every time anyways just in case -- it's pretty quick)
Trigger the alarm
Read the smoke, smoke_density, buzzer attritbutes
Try to write a 1 to buzzer_manual_mute
(If I don't get a 'smoke':1 message) hit the mute button to send an attribute update
Cancel the alarm (long press the mute button)
(if I don't get a 'smoke':0 message) hit the mute button to send an attribute update

Let me know if there's anything else you want me to try!

Sounds good!
Actually, I saw that a later Z2M commit removed the device temperature sensor due to being fixed at 25°C like you mentioned, so I'll probably remove it in the next commit too.

It worked! The binary sensor showed Detected as soon as the alarm fired! Did you expect that it would work this time? I'm also wondering if setting linkage_alarm to 1 in the OppleCluster could have had an effect on the real-time alarm reporting. This post says linkage_alarm is needed to be able to manually trigger the alarm, perhaps it's also needed to automatically report?

Logs from discovery and re-pairing
2023-02-24 16:21:21.220 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [1, <Bool.false: 0>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:21.221 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [1, <Bool.false: 0>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:24.260 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), 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=143, profile_id=0, cluster_id=<ZDOCmd.Mgmt_Permit_Joining_req: 0x0036>, data=Serialized[b'\x8f\xfe\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:24.261 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (0xfffc, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=143), 0, 144, b'\x8f\xfe\x00')
2023-02-24 16:21:24.263 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'75e421a9622ae94d59947c25aa5592489c4ea8ab7d5dcde875fd5b7d387e'
2023-02-24 16:21:24.266 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57])
2023-02-24 16:21:24.276 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50e4a1a9622a156f919d7e'
2023-02-24 16:21:24.276 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:24.278 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'06e521a9fb2aea4da66bb5da55aac820fb0c42ceaca20be29ca800eccc4769ec7e'
2023-02-24 16:21:24.282 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'60e4b5a94d2a896d557e'
2023-02-24 16:21:24.282 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:24.286 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'70e4b1a9112a10b259a24a25aa5593499c93d8abedce9874fe499d89f4fe7e'
2023-02-24 16:21:24.286 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:24.289 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendBroadcast: [<EmberStatus.SUCCESS: 0>, 221]
2023-02-24 16:21:24.290 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received stackStatusHandler: [<EmberStatus.NETWORK_OPENED: 156>]
2023-02-24 16:21:24.290 DEBUG (MainThread) [bellows.zigbee.application] Received stackStatusHandler frame with [<EmberStatus.NETWORK_OPENED: 156>]
2023-02-24 16:21:24.290 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=221), 255, 0, 0x0000, 255, 255, b'\x8f\xfe\x00']
2023-02-24 16:21:24.291 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=221), 255, 0, 0x0000, 255, 255, b'\x8f\xfe\x00']
2023-02-24 16:21:24.291 DEBUG (MainThread) [bellows.zigbee.application] Ignoring message type: <EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>
2023-02-24 16:21:24.294 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01e5a1a9fb2a15a0b87e'
2023-02-24 16:21:24.294 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:24.306 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received addTransientLinkKey: [<EmberStatus.SUCCESS: 0>]
2023-02-24 16:21:24.313 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.ALLOW_UNSECURED_REJOINS|ALLOW_JOINS: 3>)
2023-02-24 16:21:24.315 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d31ea21a9012a15b15985707e'
2023-02-24 16:21:24.323 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12eaa1a9012a15aed17e'
2023-02-24 16:21:24.324 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:24.326 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setPolicy: [<EzspStatus.SUCCESS: 0>]
2023-02-24 16:21:25.211 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22eab1a96b2a134ea6944a13aa5592499d4e27767dce6742017e'
2023-02-24 16:21:25.211 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:25.220 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=221), 144, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:25.220 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=221), 144, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:25.241 DEBUG (MainThread) [bellows.ezsp.protocol] Send command permitJoining: (254,)
2023-02-24 16:21:25.241 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'23eb21a9762aeb7b047e'
2023-02-24 16:21:25.252 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33eba1a9762a15607e7e'
2023-02-24 16:21:25.252 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:25.256 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received permitJoining: [<EmberStatus.SUCCESS: 0>]
2023-02-24 16:21:26.020 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-02-24 16:21:26.023 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'34e821a9a52a1b487e'
2023-02-24 16:21:26.040 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'44e8a1a9a52a14b248946525a85592499d4e26abecce658bfcc66389fc7e3fa7eacdde6f8fffc7dbd4d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482451937e'
2023-02-24 16:21:26.041 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:26.043 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[1, 17, 47, 2, 0, 1, 1, 1, 2, 1, 0, 0, 0, 1, 0, 0, 0, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-02-24 16:21:26.044 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-02-24 16:21:26.048 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'45e921a9fe2a164e327e'
2023-02-24 16:21:26.056 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'55e9a1a9fe2a15b3c11bb67e'
2023-02-24 16:21:26.056 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:26.066 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\x98']
2023-02-24 16:21:26.070 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 152
2023-02-24 16:21:26.070 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 9, MAC_TX_BROADCAST = 4680, MAC_RX_UNICAST = 15639, MAC_TX_UNICAST_SUCCESS = 81, MAC_TX_UNICAST_RETRY = 12, MAC_TX_UNICAST_FAILED = 16, APS_DATA_RX_BROADCAST = 3, APS_DATA_TX_BROADCAST = 3, APS_DATA_RX_UNICAST = 147, APS_DATA_TX_UNICAST_SUCCESS = 54, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 15, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 1, 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 = 0, PHY_CCA_FAIL_COUNT = 0, 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 = 152]
2023-02-24 16:21:26.688 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'65e9b5a9772a14b318004a38db55820d731a23feae7e'
2023-02-24 16:21:26.688 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:26.692 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [1, <Bool.true: 1>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:26.693 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [1, <Bool.true: 1>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:26.694 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'75e9b1a9702a542659893b25ba117d1d9c4d27abce357e'
2023-02-24 16:21:26.694 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:26.696 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0x9441, 54:ef:44:10:00:71:1d:00, <EmberDeviceUpdate.STANDARD_SECURITY_SECURED_REJOIN: 0>, <EmberJoinDecision.NO_ACTION: 3>, 0x0000]
2023-02-24 16:21:26.697 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x9441, 54:ef:44:10:00:71:1d:00, <EmberDeviceUpdate.STANDARD_SECURITY_SECURED_REJOIN: 0>, <EmberJoinDecision.NO_ACTION: 3>, 0x0000]
2023-02-24 16:21:26.698 INFO (MainThread) [zigpy.application] Device 0x9441 (54:ef:44:10:00:71:1d:00) joined the network
2023-02-24 16:21:26.700 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setManufacturerCode: (4447,)
2023-02-24 16:21:26.703 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'50ee21a9412a4aa31c207e'
2023-02-24 16:21:26.711 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'06eea1a9412a734f7e'
2023-02-24 16:21:26.712 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:26.713 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setManufacturerCode: []
2023-02-24 16:21:26.775 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'16eeb1a9112a11b259874a25aa5593499cc1b76bac5a9874f110221dfc634ea7fb89313b0bfd4d837e'
2023-02-24 16:21:26.776 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:26.778 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_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=143), 144, -64, 0x9441, 255, 255, b'\xd6A\x94\x00\x1dq\x00\x10D\xefT\x84']
2023-02-24 16:21:26.778 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_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=143), 144, -64, 0x9441, 255, 255, b'\xd6A\x94\x00\x1dq\x00\x10D\xefT\x84']
2023-02-24 16:21:26.779 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), 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=143, profile_id=0, cluster_id=19, data=Serialized[b'\xd6A\x94\x00\x1dq\x00\x10D\xefT\x84'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=144, rssi=-64)
2023-02-24 16:21:26.780 INFO (MainThread) [zigpy.application] Device 0x9441 (54:ef:44:10:00:71:1d:00) joined the network
2023-02-24 16:21:26.782 DEBUG (MainThread) [zigpy.zdo] [0x9441:zdo] ZDO request ZDOCmd.Device_annce: [0x9441, 54:ef:44:10:00:71:1d:00, 132]
2023-02-24 16:21:27.256 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'26eeb1a96b2a134fa6944a36aa5592499d4e272412ce6786617e'
2023-02-24 16:21:27.257 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:27.264 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=143), 255, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:27.265 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=143), 255, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:27.266 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 255
2023-02-24 16:21:27.346 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'36eeb1a9112a15b65854b624ab5593499cdea717ac5a9874f4da3c981e74c3a7fbcddc6ffd7e'
2023-02-24 16:21:27.347 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:27.349 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=64704, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=144), 128, -68, 0x9441, 255, 255, b'\x1c_\x11\xe2\n\xfc\x00\x10\x00']
2023-02-24 16:21:27.349 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=64704, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=144), 128, -68, 0x9441, 255, 255, b'\x1c_\x11\xe2\n\xfc\x00\x10\x00']
2023-02-24 16:21:27.350 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=144, profile_id=260, cluster_id=64704, data=Serialized[b'\x1c_\x11\xe2\n\xfc\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=128, rssi=-68)
2023-02-24 16:21:27.354 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0xfcc0] Received ZCL frame: b'\x1c_\x11\xe2\n\xfc\x00\x10\x00'
2023-02-24 16:21:27.356 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), manufacturer=4447, tsn=226, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2023-02-24 16:21:27.359 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0xfcc0] Decoded ZCL frame: OppleCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00FC, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2023-02-24 16:21:27.360 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0xfcc0] Received command 0x0A (TSN 226): Report_Attributes(attribute_reports=[Attribute(attrid=0x00FC, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2023-02-24 16:21:27.361 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0xfcc0] Attribute report received: 0x00FC=<Bool.false: 0>
2023-02-24 16:21:27.392 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (54:ef:44:10:00:71:1d:00, 1, 64704, 252, <Bool.false: 0>) params: FOREIGN KEY constraint failed
2023-02-24 16:21:32.461 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'46eeb5a9772a14b218004a38db55820d731a231fb47e'
2023-02-24 16:21:32.461 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:32.466 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'56eeb1a9702a542659893b25ba117d1d9e4dd8548c3e7e'
2023-02-24 16:21:32.466 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:32.468 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [1, <Bool.false: 0>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:32.468 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [1, <Bool.false: 0>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:32.469 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0x9441, 54:ef:44:10:00:71:1d:00, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0xffff]
2023-02-24 16:21:32.469 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x9441, 54:ef:44:10:00:71:1d:00, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0xffff]
2023-02-24 16:21:32.469 INFO (MainThread) [zigpy.application] Device 0x9441 (54:ef:44:10:00:71:1d:00) left the network
2023-02-24 16:21:36.072 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-02-24 16:21:36.075 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'66ef21a9a52a87487e'
2023-02-24 16:21:36.096 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'67efa1a9a52a17b24a947125af5590499d4e25abefce648bfcc66389fc7e3fa7eacddf6f8fffc6dbd7d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a251904824f9387e'
2023-02-24 16:21:36.096 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:36.099 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[2, 19, 59, 5, 2, 1, 2, 2, 3, 1, 0, 0, 0, 1, 1, 0, 1, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-02-24 16:21:36.105 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-02-24 16:21:36.108 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'77ec21a9fe2a16345d7e'
2023-02-24 16:21:36.113 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'70eca1a9fe2a15b3c093197e'
2023-02-24 16:21:36.114 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:36.120 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\x99']
2023-02-24 16:21:36.125 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 153
2023-02-24 16:21:36.125 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 10, MAC_TX_BROADCAST = 4682, MAC_RX_UNICAST = 15651, MAC_TX_UNICAST_SUCCESS = 84, MAC_TX_UNICAST_RETRY = 14, MAC_TX_UNICAST_FAILED = 16, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 148, APS_DATA_TX_UNICAST_SUCCESS = 54, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 15, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 1, NEIGHBOR_STALE = 0, JOIN_INDICATION = 1, CHILD_REMOVED = 2, 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 = 0, PHY_CCA_FAIL_COUNT = 0, 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 = 153]
2023-02-24 16:21:37.580 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'00ecb1a9702a542659893b25ba117d1d9d4e27ab94367e'
2023-02-24 16:21:37.580 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:37.586 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0x9441, 54:ef:44:10:00:71:1d:00, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000]
2023-02-24 16:21:37.586 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x9441, 54:ef:44:10:00:71:1d:00, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000]
2023-02-24 16:21:37.587 INFO (MainThread) [zigpy.application] Device 0x9441 (54:ef:44:10:00:71:1d:00) joined the network
2023-02-24 16:21:37.595 DEBUG (MainThread) [bellows.ezsp.protocol] Send command findKeyTableEntry: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:37.596 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setManufacturerCode: (4447,)
2023-02-24 16:21:37.598 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'01ed21a9212a15af28945a6145019343bd7e'
2023-02-24 16:21:37.610 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'11eda1a9212aea26247e'
2023-02-24 16:21:37.610 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:37.612 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'12f221a9412a4aa300467e'
2023-02-24 16:21:37.614 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received findKeyTableEntry: [255]
2023-02-24 16:21:37.619 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22f2a1a9412acf977e'
2023-02-24 16:21:37.619 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:37.624 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setManufacturerCode: []
2023-02-24 16:21:37.847 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'32f2b1a9112a11b259874a25aa5593499c48bf69ac5a9874f115221dfc634ea7fb89313b0bfd54fa7e'
2023-02-24 16:21:37.848 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:37.853 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_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=6), 152, -62, 0x9441, 255, 255, b'\xd3A\x94\x00\x1dq\x00\x10D\xefT\x84']
2023-02-24 16:21:37.854 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_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=6), 152, -62, 0x9441, 255, 255, b'\xd3A\x94\x00\x1dq\x00\x10D\xefT\x84']
2023-02-24 16:21:37.855 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), 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=6, profile_id=0, cluster_id=19, data=Serialized[b'\xd3A\x94\x00\x1dq\x00\x10D\xefT\x84'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=152, rssi=-62)
2023-02-24 16:21:37.856 INFO (MainThread) [zigpy.application] Device 0x9441 (54:ef:44:10:00:71:1d:00) joined the network
2023-02-24 16:21:37.857 DEBUG (MainThread) [zigpy.zdo] [0x9441:zdo] ZDO request ZDOCmd.Device_annce: [0x9441, 54:ef:44:10:00:71:1d:00, 132]
2023-02-24 16:21:37.869 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'42f2b1a9772a14b318004a38db55820d731a23e4617e'
2023-02-24 16:21:37.870 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:37.871 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [1, <Bool.true: 1>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:37.871 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [1, <Bool.true: 1>, 0x9441, 54:ef:44:10:00:71:1d:00, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-02-24 16:21:38.137 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'52f2b1a9112a15b259964a25aa1593499c49bf69ac5a9874fe126389fecf657e'
2023-02-24 16:21:38.138 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:38.141 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=7), 152, -62, 0x9441, 255, 255, b'\xd4\x00\x00']
2023-02-24 16:21:38.142 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=7), 152, -62, 0x9441, 255, 255, b'\xd4\x00\x00']
2023-02-24 16:21:38.143 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=7, profile_id=0, cluster_id=2, data=Serialized[b'\xd4\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=152, rssi=-62)
2023-02-24 16:21:38.145 DEBUG (MainThread) [zigpy.zdo] [0x9441:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-02-24 16:21:38.147 DEBUG (MainThread) [zigpy.zdo] [0x9441:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-02-24 16:21:38.373 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'62f2b1a9cf2a15af28945a61450194ad937e'
2023-02-24 16:21:38.373 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:38.377 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'72f2b1a96b2a134fa6944a36aa5592499d4e27ad12ce6799297e'
2023-02-24 16:21:38.378 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:38.385 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received zigbeeKeyEstablishmentHandler: [54:ef:44:10:00:71:1d:00, <EmberKeyStatus.TC_RESPONDED_TO_KEY_REQUEST: 6>]
2023-02-24 16:21:38.385 DEBUG (MainThread) [bellows.zigbee.application] Received zigbeeKeyEstablishmentHandler frame with [54:ef:44:10:00:71:1d:00, <EmberKeyStatus.TC_RESPONDED_TO_KEY_REQUEST: 6>]
2023-02-24 16:21:38.386 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=6), 255, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:38.387 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=6), 255, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:38.387 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 255
2023-02-24 16:21:38.561 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'02f2b1a9cf2a15af28945a614501a60c9f7e'
2023-02-24 16:21:38.561 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:38.562 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received zigbeeKeyEstablishmentHandler: [54:ef:44:10:00:71:1d:00, <EmberKeyStatus.TC_REQUESTER_VERIFY_KEY_SUCCESS: 52>]
2023-02-24 16:21:38.563 DEBUG (MainThread) [bellows.zigbee.application] Received zigbeeKeyEstablishmentHandler frame with [54:ef:44:10:00:71:1d:00, <EmberKeyStatus.TC_REQUESTER_VERIFY_KEY_SUCCESS: 52>]
2023-02-24 16:21:38.662 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12f2b1a9112a11b259a24a25aa5593499c44bb68ac5a9874fe13d788fe2e977e'
2023-02-24 16:21:38.662 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:38.666 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=10), 156, -61, 0x9441, 255, 255, b'\xd5\xb4\x01']
2023-02-24 16:21:38.667 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=10), 156, -61, 0x9441, 255, 255, b'\xd5\xb4\x01']
2023-02-24 16:21:38.667 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), 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=10, profile_id=0, cluster_id=54, data=Serialized[b'\xd5\xb4\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=156, rssi=-61)
2023-02-24 16:21:38.670 DEBUG (MainThread) [zigpy.zdo] [0x9441:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [180, <Bool.true: 1>]
2023-02-24 16:21:39.145 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22f2b1a96b2a134ea6944a13aa5592499d4e27a112ce67ef907e'
2023-02-24 16:21:39.145 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:39.150 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=10), 255, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:39.151 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=10), 255, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:39.152 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 255
2023-02-24 16:21:39.994 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'32f2b1a9112a15b658944a24ab5593499c45af15ac5a9874dfde0083f97e7db087b8b306a18ca2b5a6bd1bd3354ec6871315c4891bb272994c0602b357187e'
2023-02-24 16:21:39.994 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:39.998 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=11), 136, -66, 0x9441, 255, 255, b'\x18c\n\x05\x00B\x17lumi.sensor_smoke.acn03\x01\x00 \x11']
2023-02-24 16:21:39.998 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=11), 136, -66, 0x9441, 255, 255, b'\x18c\n\x05\x00B\x17lumi.sensor_smoke.acn03\x01\x00 \x11']
2023-02-24 16:21:39.999 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=11, profile_id=260, cluster_id=0, data=Serialized[b'\x18c\n\x05\x00B\x17lumi.sensor_smoke.acn03\x01\x00 \x11'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=136, rssi=-66)
2023-02-24 16:21:40.001 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Received ZCL frame: b'\x18c\n\x05\x00B\x17lumi.sensor_smoke.acn03\x01\x00 \x11'
2023-02-24 16:21:40.003 DEBUG (MainThread) [zigpy.zcl] [0x9441: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=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=99, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2023-02-24 16:21:40.005 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0005, value=TypeValue(type=CharacterString, value='lumi.sensor_smoke.acn03')), Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=17))])
2023-02-24 16:21:40.007 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Received command 0x0A (TSN 99): Report_Attributes(attribute_reports=[Attribute(attrid=0x0005, value=TypeValue(type=CharacterString, value='lumi.sensor_smoke.acn03')), Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=17))])
2023-02-24 16:21:40.008 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Attribute report received: model='lumi.sensor_smoke.acn03', app_version=17
2023-02-24 16:21:40.022 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (54:ef:44:10:00:71:1d:00, 1, 0, 5, 'lumi.sensor_smoke.acn03') params: FOREIGN KEY constraint failed
2023-02-24 16:21:40.028 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (54:ef:44:10:00:71:1d:00, 1, 0, 1, 17) params: FOREIGN KEY constraint failed
2023-02-24 16:21:43.523 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xBC5C](lumi.sensor_smoke.acn03): Device seen - marking the device available and resetting counter
2023-02-24 16:21:43.523 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xBC5C](lumi.sensor_smoke.acn03): Update device availability -  device available: True - new availability: True - changed: False
2023-02-24 16:21:43.534 DEBUG (MainThread) [zigpy.application] Sending 'zdo_leave_req' failed:
2023-02-24 16:21:46.015 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'42f2b1a9112a15b65854b624ab5593499c42af15ac5a9874bada3c989874c8a7aaf3df4ee6f3c4f3ccd64824472688f1763d81e975824198440702b0ea51381c0227ba83ea66ed659b25bfe79f57e2d9d4cb15a2f3b048873209187e2f0acfcfdda3ed7e'
2023-02-24 16:21:46.016 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:46.018 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=64704, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=12), 136, -66, 0x9441, 255, 255, b'\x1c_\x11d\n\xf7\x00A>\x01!i\x0c\x03(\x19\x04!\xa8\x01\x05!\x1d\x00\x06$\x03\x00\x00\x00\x00\x08!\x11\x01\n!\x00\x00\x0c \x01f \x03g \x01h!\xa8\x00\xa0!\x00\x00\xa1 \x00\xa2 \x00\xa3 \x00\xa4 \x00\xa5 \x00']
2023-02-24 16:21:46.019 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=64704, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=12), 136, -66, 0x9441, 255, 255, b'\x1c_\x11d\n\xf7\x00A>\x01!i\x0c\x03(\x19\x04!\xa8\x01\x05!\x1d\x00\x06$\x03\x00\x00\x00\x00\x08!\x11\x01\n!\x00\x00\x0c \x01f \x03g \x01h!\xa8\x00\xa0!\x00\x00\xa1 \x00\xa2 \x00\xa3 \x00\xa4 \x00\xa5 \x00']
2023-02-24 16:21:46.019 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=12, profile_id=260, cluster_id=64704, data=Serialized[b'\x1c_\x11d\n\xf7\x00A>\x01!i\x0c\x03(\x19\x04!\xa8\x01\x05!\x1d\x00\x06$\x03\x00\x00\x00\x00\x08!\x11\x01\n!\x00\x00\x0c \x01f \x03g \x01h!\xa8\x00\xa0!\x00\x00\xa1 \x00\xa2 \x00\xa3 \x00\xa4 \x00\xa5 \x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=136, rssi=-66)
2023-02-24 16:21:46.020 WARNING (MainThread) [zigpy.application] Unknown device AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441)
2023-02-24 16:21:46.034 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.RX_ON_WHEN_IDLE: 65533>), dst_ep=0, source_route=None, extended_timeout=False, tsn=145, profile_id=0, cluster_id=<ZDOCmd.IEEE_addr_req: 0x0001>, data=Serialized[b'\x91A\x94\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:46.035 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (0xfffd, EmberApsFrame(profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=145), 0, 146, b'\x91A\x94\x00\x00')
2023-02-24 16:21:46.037 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'25f321a9622ae84d59944b25aa5592489c4eb6ab7fcbf6ca69c663fed57e'
2023-02-24 16:21:46.045 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'53f3a1a9622a1550b06b7e'
2023-02-24 16:21:46.046 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:46.050 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendBroadcast: [<EmberStatus.SUCCESS: 0>, 226]
2023-02-24 16:21:46.054 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'63f3b1a9112a10b259954a25aa5593499cacd8abedce9874f857221dfc7e28227e'
2023-02-24 16:21:46.054 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:46.060 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=226), 255, 0, 0x0000, 255, 255, b'\x91A\x94\x00\x00']
2023-02-24 16:21:46.060 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=226), 255, 0, 0x0000, 255, 255, b'\x91A\x94\x00\x00']
2023-02-24 16:21:46.060 DEBUG (MainThread) [bellows.zigbee.application] Ignoring message type: <EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>
2023-02-24 16:21:46.063 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'73f3b1a9112a15b25995ca25aa1593499cadd8abedce9874f1576389e10f3fb7af228a2e1bac2d7e'
2023-02-24 16:21:46.064 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:46.065 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32769, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=227), 255, 0, 0x0000, 255, 255, b'\x91\x00\x00\x1dq\x00\x10D\xefTA\x94']
2023-02-24 16:21:46.066 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32769, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=227), 255, 0, 0x0000, 255, 255, b'\x91\x00\x00\x1dq\x00\x10D\xefTA\x94']
2023-02-24 16:21:46.066 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=227, profile_id=0, cluster_id=32769, data=Serialized[b'\x91\x00\x00\x1dq\x00\x10D\xefTA\x94'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=0)
2023-02-24 16:21:46.067 DEBUG (MainThread) [zigpy.application] Discovered IEEE address for NWK=0x9441: 54:ef:44:10:00:71:1d:00
2023-02-24 16:21:46.068 INFO (MainThread) [zigpy.application] New device 0x9441 (54:ef:44:10:00:71:1d:00) joined the network
2023-02-24 16:21:46.068 DEBUG (MainThread) [zigpy.device] [0x9441] Scheduling initialization
2023-02-24 16:21:46.069 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request ZDOCmd.IEEE_addr_rsp: [<Status.SUCCESS: 0>, 54:ef:44:10:00:71:1d:00, 0x9441, None, None, []]
2023-02-24 16:21:46.070 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] No handler for ZDO request:ZDOCmd.IEEE_addr_rsp([<Status.SUCCESS: 0>, 54:ef:44:10:00:71:1d:00, 0x9441, None, None, []])
2023-02-24 16:21:46.074 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2023-02-24 16:21:46.074 INFO (MainThread) [zigpy.device] [0x9441] Requesting 'Node Descriptor'
2023-02-24 16:21:46.074 DEBUG (MainThread) [zigpy.util] Tries remaining: 2
2023-02-24 16:21:46.075 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x93 request
2023-02-24 16:21:46.075 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=0, source_route=None, extended_timeout=True, tsn=147, profile_id=0, cluster_id=<ZDOCmd.Node_Desc_req: 0x0002>, data=Serialized[b'\x93A\x94'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:46.076 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:46.078 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'30f021a92a2a15af28945a614501934ff37e'
2023-02-24 16:21:46.084 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'04f0a1a92a2ae42d7e'
2023-02-24 16:21:46.085 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:46.092 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:46.103 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=147), 148, b'\x93A\x94')
2023-02-24 16:21:46.108 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'41f121a9602a15f3cd944a27aa5592499d4e273879cdf4ca6922757e'
2023-02-24 16:21:46.119 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'15f1a1a9602a15562b3d7e'
2023-02-24 16:21:46.119 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:46.125 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 228]
2023-02-24 16:21:46.144 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-02-24 16:21:46.145 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'52f621a9a52a02437e'
2023-02-24 16:21:46.164 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'26f6a1a9a52a16b24e941c25a45591499d4e22abe9ce608bfcc66389fc7e3fa7e9cddc6f8fffc5dbd7d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a251904824e29d7e'
2023-02-24 16:21:46.164 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:46.166 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[3, 23, 86, 14, 3, 1, 5, 4, 7, 1, 0, 0, 0, 2, 2, 0, 2, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-02-24 16:21:46.177 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-02-24 16:21:46.178 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'63f721a9fe2a161bc67e'
2023-02-24 16:21:46.184 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'37f7a1a9fe2a15b3cf679f7e'
2023-02-24 16:21:46.184 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:46.189 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\x96']
2023-02-24 16:21:46.193 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 150
2023-02-24 16:21:46.193 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 11, MAC_TX_BROADCAST = 4686, MAC_RX_UNICAST = 15678, MAC_TX_UNICAST_SUCCESS = 93, MAC_TX_UNICAST_RETRY = 15, MAC_TX_UNICAST_FAILED = 16, APS_DATA_RX_BROADCAST = 7, APS_DATA_TX_BROADCAST = 6, APS_DATA_RX_UNICAST = 152, APS_DATA_TX_UNICAST_SUCCESS = 54, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 15, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 2, NEIGHBOR_REMOVED = 2, NEIGHBOR_STALE = 0, JOIN_INDICATION = 2, CHILD_REMOVED = 2, 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 = 0, PHY_CCA_FAIL_COUNT = 0, 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 = 150]
2023-02-24 16:21:47.005 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'47f7b1a96b2a15f3cd944a27aa5592499d4e274f79ce6739187e'
2023-02-24 16:21:47.006 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:47.012 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=228), 148, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:47.013 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=228), 148, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:47.019 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'57f7b1a9112a15b25996ca25aa1593499c43af15ac5a9874ec5563c8687c7f23b4dca10b8fffebbfd5d26b7c7e7e'
2023-02-24 16:21:47.019 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:47.022 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=13), 136, -66, 0x9441, 255, 255, b'\x93\x00A\x94\x02@\x84_\x11\x7fd\x00\x00,d\x00\x00']
2023-02-24 16:21:47.023 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=13), 136, -66, 0x9441, 255, 255, b'\x93\x00A\x94\x02@\x84_\x11\x7fd\x00\x00,d\x00\x00']
2023-02-24 16:21:47.024 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=13, profile_id=0, cluster_id=32770, data=Serialized[b'\x93\x00A\x94\x02@\x84_\x11\x7fd\x00\x00,d\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=136, rssi=-66)
2023-02-24 16:21:47.025 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x9441 ieee=54:ef:44:10:00:71:1d:00 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'\x93\x00A\x94\x02@\x84_\x11\x7fd\x00\x00,d\x00\x00'
2023-02-24 16:21:47.028 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'67f7b1a96b2a134fa6944a24aa5592499d4e27497fce67245b7e'
2023-02-24 16:21:47.028 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:47.032 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=226), 146, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:47.033 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=226), 146, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:47.039 INFO (MainThread) [zigpy.device] [0x9441] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|MainsPowered: 132>, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=11264, maximum_outgoing_transfer_size=100, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=True, *is_full_function_device=False, *is_mains_powered=True, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)
2023-02-24 16:21:47.040 INFO (MainThread) [zigpy.device] [0x9441] Discovering endpoints
2023-02-24 16:21:47.040 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2023-02-24 16:21:47.041 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x95 request
2023-02-24 16:21:47.041 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=0, source_route=None, extended_timeout=True, tsn=149, profile_id=0, cluster_id=<ZDOCmd.Active_EP_req: 0x0005>, data=Serialized[b'\x95A\x94'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:47.043 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:47.045 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'77f421a92a2a15af28945a61450193e0c87e'
2023-02-24 16:21:47.054 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'70f4a1a92a2a2e167e'
2023-02-24 16:21:47.054 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:47.066 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:47.080 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=149), 150, b'\x95A\x94')
2023-02-24 16:21:47.084 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'00f521a9602a15f3cd944a20aa5592499d4e273e7bcdf2ca69df197e'
2023-02-24 16:21:47.092 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01f5a1a9602a1557c8037e'
2023-02-24 16:21:47.092 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:47.101 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 229]
2023-02-24 16:21:48.216 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'11f5b1a96b2a15f3cd944a20aa5592499d4e274e7bce674d827e'
2023-02-24 16:21:48.216 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:48.220 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=229), 150, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:48.221 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=229), 150, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:48.225 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'21f5b1a9112a15b25991ca25aa1593499c40a316ac5a9874fb5363c8687f3ea5d9cd7e'
2023-02-24 16:21:48.225 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:48.227 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=14), 132, -67, 0x9441, 255, 255, b'\x95\x00A\x94\x01\x01']
2023-02-24 16:21:48.227 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=14), 132, -67, 0x9441, 255, 255, b'\x95\x00A\x94\x01\x01']
2023-02-24 16:21:48.228 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=14, profile_id=0, cluster_id=32773, data=Serialized[b'\x95\x00A\x94\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=132, rssi=-67)
2023-02-24 16:21:48.229 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x9441 ieee=54:ef:44:10:00:71:1d:00 is_initialized=False> from ep 0 to ep 0, cluster 32773: b'\x95\x00A\x94\x01\x01'
2023-02-24 16:21:48.234 INFO (MainThread) [zigpy.device] [0x9441] Discovered endpoints: [1]
2023-02-24 16:21:48.234 INFO (MainThread) [zigpy.device] [0x9441] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
2023-02-24 16:21:48.235 INFO (MainThread) [zigpy.endpoint] [0x9441:1] Discovering endpoint information
2023-02-24 16:21:48.235 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2023-02-24 16:21:48.236 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x97 request
2023-02-24 16:21:48.236 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=0, source_route=None, extended_timeout=True, tsn=151, profile_id=0, cluster_id=<ZDOCmd.Simple_Desc_req: 0x0004>, data=Serialized[b'\x97A\x94\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:48.237 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:48.238 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d33fa21a92a2a15af28945a614501932c6b7e'
2023-02-24 16:21:48.251 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'32faa1a92a2a00ee7e'
2023-02-24 16:21:48.251 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:48.258 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:48.265 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=151), 152, b'\x97A\x94\x01')
2023-02-24 16:21:48.267 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'24fb21a9602a15f3cd944a21aa5592499d4e273c75caf0ca69c791307e'
2023-02-24 16:21:48.275 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'43fba1a9602a1554b39e7e'
2023-02-24 16:21:48.275 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:48.283 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 230]
2023-02-24 16:21:49.427 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'53fbb1a96b2a15f3cd944a21aa5592499d4e274d75ce67e7b67e'
2023-02-24 16:21:49.428 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:49.432 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=230), 152, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:49.433 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=230), 152, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:49.440 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'63fbb1a9112a15b25990ca25aa1593499c415312ac5a9874ea5163c8686c3ea3eacfda6e8bffc7dbd0d1698d4622b0ec7442cf7e'
2023-02-24 16:21:49.440 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:49.443 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=15), 116, -71, 0x9441, 255, 255, b'\x97\x00A\x94\x12\x01\x04\x01\x02\x04\x01\x04\x00\x00\x00\x05\x03\x00\x01\x00\x01\x19\x00']
2023-02-24 16:21:49.444 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=15), 116, -71, 0x9441, 255, 255, b'\x97\x00A\x94\x12\x01\x04\x01\x02\x04\x01\x04\x00\x00\x00\x05\x03\x00\x01\x00\x01\x19\x00']
2023-02-24 16:21:49.444 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=15, profile_id=0, cluster_id=32772, data=Serialized[b'\x97\x00A\x94\x12\x01\x04\x01\x02\x04\x01\x04\x00\x00\x00\x05\x03\x00\x01\x00\x01\x19\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=116, rssi=-71)
2023-02-24 16:21:49.446 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x9441 ieee=54:ef:44:10:00:71:1d:00 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'\x97\x00A\x94\x12\x01\x04\x01\x02\x04\x01\x04\x00\x00\x00\x05\x03\x00\x01\x00\x01\x19\x00'
2023-02-24 16:21:49.457 INFO (MainThread) [zigpy.endpoint] [0x9441:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=1026, device_version=1, input_clusters=[0, 1280, 3, 1], output_clusters=[25])
2023-02-24 16:21:49.459 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=153, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2023-02-24 16:21:49.461 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
2023-02-24 16:21:49.462 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x99 request
2023-02-24 16:21:49.462 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=1, source_route=None, extended_timeout=True, tsn=153, profile_id=260, cluster_id=0, data=Serialized[b'\x00\x99\x00\x04\x00\x05\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:49.463 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:49.465 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'37f821a92a2a15af28945a61450193dc0f7e'
2023-02-24 16:21:49.477 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'74f8a1a92a2aa39c7e'
2023-02-24 16:21:49.477 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:49.482 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:49.493 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=153), 154, b'\x00\x99\x00\x04\x00\x05\x00')
2023-02-24 16:21:49.497 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'40f921a9602a15f3cd904b25aa5493499d4e273277c96712fdc2638cfc95c17e'
2023-02-24 16:21:49.507 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'05f9a1a9602a1555b5477e'
2023-02-24 16:21:49.508 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:49.509 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 231]
2023-02-24 16:21:50.642 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'15f9b1a96b2a15f3cd904b25aa5493499d4e274c77ce679a657e'
2023-02-24 16:21:50.642 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:50.645 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=231), 154, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:50.646 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=231), 154, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:50.657 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'25f9b1a9112a15b658944a24ab1593499c5ea717ac5a9874d5defa88f87e3fe5ef818b22c6fac7db97c505f92b4a879f1355d68507dd32f5234d769f8113562c3d052ed17e'
2023-02-24 16:21:50.658 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:50.662 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|APS_OPTION_RETRY: 320>, groupId=0, sequence=16), 128, -68, 0x9441, 255, 255, b'\x18\x99\x01\x04\x00\x00B\x04LUMI\x05\x00\x00B\x17lumi.sensor_smoke.acn03']
2023-02-24 16:21:50.663 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|APS_OPTION_RETRY: 320>, groupId=0, sequence=16), 128, -68, 0x9441, 255, 255, b'\x18\x99\x01\x04\x00\x00B\x04LUMI\x05\x00\x00B\x17lumi.sensor_smoke.acn03']
2023-02-24 16:21:50.664 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=16, profile_id=260, cluster_id=0, data=Serialized[b'\x18\x99\x01\x04\x00\x00B\x04LUMI\x05\x00\x00B\x17lumi.sensor_smoke.acn03'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=128, rssi=-68)
2023-02-24 16:21:50.665 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Received ZCL frame: b'\x18\x99\x01\x04\x00\x00B\x04LUMI\x05\x00\x00B\x17lumi.sensor_smoke.acn03'
2023-02-24 16:21:50.666 DEBUG (MainThread) [zigpy.zcl] [0x9441: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=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=153, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2023-02-24 16:21:50.669 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='LUMI')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='lumi.sensor_smoke.acn03'))])
2023-02-24 16:21:50.678 INFO (MainThread) [zigpy.device] [0x9441] Read model 'lumi.sensor_smoke.acn03' and manufacturer 'LUMI' from <Endpoint id=1 in=[basic:0x0000, ias_zone:0x0500, identify:0x0003, power:0x0001] out=[ota:0x0019] status=<Status.ZDO_INIT: 1>>
2023-02-24 16:21:50.678 INFO (MainThread) [zigpy.device] [0x9441] Discovered basic device information for <Device model='lumi.sensor_smoke.acn03' manuf='LUMI' nwk=0x9441 ieee=54:ef:44:10:00:71:1d:00 is_initialized=True>
2023-02-24 16:21:50.679 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='lumi.sensor_smoke.acn03' manuf='LUMI' nwk=0x9441 ieee=54:ef:44:10:00:71:1d:00 is_initialized=True>
2023-02-24 16:21:50.682 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_smoke.acn03 (54:ef:44:10:00:71:1d:00)
2023-02-24 16:21:50.683 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'xiaomi.smoke.LumiSensorSmokeAcn03'>
2023-02-24 16:21:50.684 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 54:ef:44:10:00:71:1d:00: <class 'xiaomi.smoke.LumiSensorSmokeAcn03'>
2023-02-24 16:21:50.692 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'binary_sensor' component -> 'IASZone' using ['ias_zone']
2023-02-24 16:21:50.693 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'button' component -> 'ZHAIdentifyButton' using ['identify']
2023-02-24 16:21:50.693 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'DeviceTemperature' using ['device_temperature']
2023-02-24 16:21:50.693 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'RSSISensor' using ['basic']
2023-02-24 16:21:50.694 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'LQISensor' using ['basic']
2023-02-24 16:21:50.699 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x9441:54:ef:44:10:00:71:1d:00 entering async_device_initialized - is_new_join: True
2023-02-24 16:21:50.699 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x9441:54:ef:44:10:00:71:1d:00 has joined the ZHA zigbee network
2023-02-24 16:21:50.701 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): started configuration
2023-02-24 16:21:50.701 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:ZDO](lumi.sensor_smoke.acn03): 'async_configure' stage succeeded
2023-02-24 16:21:50.720 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: started IASZoneChannel configuration
2023-02-24 16:21:50.720 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: bound 'ias_zone' cluster: Status.SUCCESS
2023-02-24 16:21:50.721 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: wrote cie_addr: 38:5c:fb:ff:fe:e3:73:71 to 'ias_zone' cluster: [WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)]
2023-02-24 16:21:50.722 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: Sending pro-active IAS enroll response
2023-02-24 16:21:50.722 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: finished IASZoneChannel configuration
2023-02-24 16:21:50.723 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: Configuring cluster attribute reporting
2023-02-24 16:21:50.723 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: finished channel configuration
2023-02-24 16:21:50.724 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: Performing cluster binding
2023-02-24 16:21:50.724 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: bound 'device_temperature' cluster: Status.SUCCESS
2023-02-24 16:21:50.725 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: Configuring cluster attribute reporting
2023-02-24 16:21:50.726 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: Successfully configured reporting for '{'current_temperature': (30, 900, 50)}' on 'device_temperature' cluster: [ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>)]
2023-02-24 16:21:50.727 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: finished channel configuration
2023-02-24 16:21:50.727 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: Configuring cluster attribute reporting
2023-02-24 16:21:50.728 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: finished channel configuration
2023-02-24 16:21:50.728 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: Performing cluster binding
2023-02-24 16:21:50.729 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x9b request
2023-02-24 16:21:50.730 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=0, source_route=None, extended_timeout=True, tsn=155, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x9b\x00\x1dq\x00\x10D\xefT\x01\xc0\xfc\x03qs\xe3\xfe\xff\xfb\\8\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:50.730 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:50.731 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0019]: finished channel configuration
2023-02-24 16:21:50.732 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (54:ef:44:10:00:71:1d:00, 1, 0, 4, 'LUMI') params: FOREIGN KEY constraint failed
2023-02-24 16:21:50.738 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'53fe21a92a2a15af28945a61450193fbc77e'
2023-02-24 16:21:50.743 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0500] 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, *is_reply=False), tsn=157, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2023-02-24 16:21:50.744 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0500] Sending request: enroll_response(enroll_response_code=<EnrollResponse.Success: 0>, zone_id=0)
2023-02-24 16:21:50.745 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x9d request
2023-02-24 16:21:50.746 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=1, source_route=None, extended_timeout=True, tsn=157, profile_id=260, cluster_id=1280, data=Serialized[b'\x01\x9d\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:50.748 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'36fea1a92a2a8f497e'
2023-02-24 16:21:50.748 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:50.755 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:50.755 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (54:ef:44:10:00:71:1d:00, 1, 0, 5, 'lumi.sensor_smoke.acn03') params: FOREIGN KEY constraint failed
2023-02-24 16:21:50.765 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=155), 156, b'\x9b\x00\x1dq\x00\x10D\xefT\x01\xc0\xfc\x03qs\xe3\xfe\xff\xfb\\8\x01')
2023-02-24 16:21:50.767 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'64ff21a9602a15f3cd944a04aa5592499d4e273071d8fc8be0b76399b8916ba62b31dd1efc1c39242e8e518d5c177e'
2023-02-24 16:21:50.781 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'47ffa1a9602a155a9cfb7e'
2023-02-24 16:21:50.781 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:50.783 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 232]
2023-02-24 16:21:50.787 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:50.789 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'75fc21a92a2a15af28945a61450193467a7e'
2023-02-24 16:21:50.798 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50fca1a92a2a19337e'
2023-02-24 16:21:50.798 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:50.803 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:50.805 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=260, clusterId=1280, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=157), 158, b'\x01\x9d\x00\x00\x00')
2023-02-24 16:21:50.809 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'06fd21a9602a15f3cd904b25af5493499d4e273673cb6616fdc66305e27e'
2023-02-24 16:21:50.827 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'61fda1a9602a155b179a7e'
2023-02-24 16:21:50.827 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2023-02-24 16:21:50.830 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 233]
2023-02-24 16:21:51.853 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'71fdb1a96b2a15f3cd944a04aa5592499d4e274371ce672f267e'
2023-02-24 16:21:51.853 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2023-02-24 16:21:51.859 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=232), 156, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:51.860 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=232), 156, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:51.868 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01fdb1a9112a15b259b5ca25aa1593499c5fa316ac5a9874ff5d638b30737e'
2023-02-24 16:21:51.868 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2023-02-24 16:21:51.870 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=17), 132, -67, 0x9441, 255, 255, b'\x9b\x00']
2023-02-24 16:21:51.871 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=17), 132, -67, 0x9441, 255, 255, b'\x9b\x00']
2023-02-24 16:21:51.871 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=17, profile_id=0, cluster_id=32801, data=Serialized[b'\x9b\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=132, rssi=-67)
2023-02-24 16:21:51.881 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: bound 'opple_cluster' cluster: Status.SUCCESS
2023-02-24 16:21:51.882 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: Configuring cluster attribute reporting
2023-02-24 16:21:51.883 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: finished channel configuration
2023-02-24 16:21:51.892 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: 'async_configure' stage succeeded
2023-02-24 16:21:51.892 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: 'async_configure' stage succeeded
2023-02-24 16:21:51.893 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0002]: 'async_configure' stage succeeded
2023-02-24 16:21:51.893 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0000]: 'async_configure' stage succeeded
2023-02-24 16:21:51.894 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0xfcc0]: 'async_configure' stage succeeded
2023-02-24 16:21:51.894 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0019]: 'async_configure' stage succeeded
2023-02-24 16:21:51.905 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): completed configuration
2023-02-24 16:21:51.907 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] 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, *is_reply=False), tsn=159, command_id=64, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2023-02-24 16:21:51.908 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] Sending request: trigger_effect(effect_id=<EffectIdentifier.Okay: 2>, effect_variant=<EffectVariant.Default: 0>)
2023-02-24 16:21:51.909 DEBUG (MainThread) [zigpy.device] [0x9441] Extending timeout for 0x9f request
2023-02-24 16:21:51.910 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), dst_ep=1, source_route=None, extended_timeout=True, tsn=159, profile_id=260, cluster_id=3, data=Serialized[b'\x01\x9f@\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-02-24 16:21:51.911 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (54:ef:44:10:00:71:1d:00, True)
2023-02-24 16:21:51.914 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d318221a92a2a15af28945a61450193fa5e7e'
2023-02-24 16:21:51.923 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1282a1a92a2a2a4d7e'
2023-02-24 16:21:51.924 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2023-02-24 16:21:51.933 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-02-24 16:21:51.940 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9441, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=159), 160, b'\x01\x9f@\x02\x00')
2023-02-24 16:21:51.943 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'228321a9602a15f3cd904b26aa5493499d4e27344dcb6614bdc463bdd07e'
2023-02-24 16:21:51.952 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2383a1a9602a1558e8667e'
2023-02-24 16:21:51.952 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2023-02-24 16:21:51.953 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 234]
2023-02-24 16:21:52.068 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3383b1a96b2a15f3cd904b25af5493499d4e274273ce67c4a47e'
2023-02-24 16:21:52.068 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2023-02-24 16:21:52.071 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=1280, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=233), 158, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:52.072 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=1280, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=233), 158, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:53.276 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4383b1a96b2a15f3cd904b26aa5493499d4e27414dce679c0f7e'
2023-02-24 16:21:53.277 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2023-02-24 16:21:53.280 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=234), 160, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:53.281 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37953, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=234), 160, <EmberStatus.SUCCESS: 0>, b'']
2023-02-24 16:21:53.286 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5383b1a9112a15b658974a24ab1593499c5ca717ac5a9874f8defc82bc7e3d789b7e'
2023-02-24 16:21:53.286 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2023-02-24 16:21:53.288 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=18), 128, -68, 0x9441, 255, 255, b'\x18\x9f\x0b@\x00']
2023-02-24 16:21:53.288 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=18), 128, -68, 0x9441, 255, 255, b'\x18\x9f\x0b@\x00']
2023-02-24 16:21:53.289 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x9441), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=18, profile_id=260, cluster_id=3, data=Serialized[b'\x18\x9f\x0b@\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=128, rssi=-68)
2023-02-24 16:21:53.290 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] Received ZCL frame: b'\x18\x9f\x0b@\x00'
2023-02-24 16:21:53.291 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] 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, *is_reply=True), tsn=159, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2023-02-24 16:21:53.296 DEBUG (MainThread) [zigpy.zcl] [0x9441:1:0x0003] Decoded ZCL frame: Identify:Default_Response(command_id=64, status=<Status.SUCCESS: 0>)
2023-02-24 16:21:53.307 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: executed 'trigger_effect' command with args: '()' kwargs: '{'effect_id': <EffectIdentifier.Okay: 2>, 'effect_variant': <EffectVariant.Default: 0>}' result: Default_Response(command_id=64, status=<Status.SUCCESS: 0>)
2023-02-24 16:21:53.309 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9441](lumi.sensor_smoke.acn03): started initialization
2023-02-24 16:21:53.309 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:ZDO](lumi.sensor_smoke.acn03): 'async_initialize' stage succeeded
2023-02-24 16:21:53.324 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: initializing channel: from_cache: False
2023-02-24 16:21:53.324 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: initializing cached channel attributes: ['zone_status', 'zone_type']
2023-02-24 16:21:53.325 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: Reading attributes in chunks: ['zone_status', 'zone_type']
2023-02-24 16:21:53.325 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: initializing uncached channel attributes: ['zone_state'] - from cache[False]
2023-02-24 16:21:53.326 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: Reading attributes in chunks: ['zone_state']
2023-02-24 16:21:53.326 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0500]: finished channel initialization
2023-02-24 16:21:53.327 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: initializing channel: from_cache: False
2023-02-24 16:21:53.327 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9441:1:0x0003]: finished channel initialization