zha-device-handlers
zha-device-handlers copied to clipboard
[Device Support Request] Nedis Motion Sensor with illuminance sensor _TZE200_s6hzw8g2
Problem description
Hi, I would like to have support for this PIR motion sensor/ Light sensor. Added it via ZHA and it does not show up much, as you can see from attached screenshot. There are no entities created to this. System logs had something there but I cannot make anything useful of it.
Tried most of the TS0601 motion pir quirks I've found that would fit the bill, removed pycache folders, restarted HA - but, with or without the quirks the device looks the same. There are no entities created etc.
Been doing this -"rinse & repeat" - for 2 solid days now. Maybe someone can help me with this ? Thx
-T
Solution description
Support for this particular zigbee motion sensor / light sensor
Screenshots/Video
Screenshots/Video
Device signature
Device signature
"signature": {
"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: 128>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, 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=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": "0x0104",
"device_type": "0x0051",
"input_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0xef00"
],
"output_clusters": [
"0x000a",
"0x0019"
]
}
},
"manufacturer": "_TZE200_s6hzw8g2",
"model": "TS0601"
},
Diagnostic information
Diagnostic information
{
"home_assistant": {
"installation_type": "Home Assistant Container",
"version": "2023.9.3",
"dev": false,
"hassio": false,
"virtualenv": false,
"python_version": "3.11.5",
"docker": true,
"arch": "x86_64",
"timezone": "Europe/Helsinki",
"os_name": "Linux",
"os_version": "6.2.0-26-generic",
"run_as_root": true
},
"custom_components": {
"zha_toolkit": {
"version": "v1.0.0",
"requirements": [
"pytz"
]
},
"ruuvi": {
"version": "1.1.0",
"requirements": [
"simple-ruuvitag==0.0.8"
]
},
"hacs": {
"version": "1.33.0",
"requirements": [
"aiogithubapi>=22.10.1"
]
}
},
"integration_manifest": {
"domain": "zha",
"name": "Zigbee Home Automation",
"after_dependencies": [
"onboarding",
"usb"
],
"codeowners": [
"@dmulcahey",
"@adminiuga",
"@puddly"
],
"config_flow": true,
"dependencies": [
"file_upload"
],
"documentation": "https://www.home-assistant.io/integrations/zha",
"iot_class": "local_polling",
"loggers": [
"aiosqlite",
"bellows",
"crccheck",
"pure_pcapy3",
"zhaquirks",
"zigpy",
"zigpy_deconz",
"zigpy_xbee",
"zigpy_zigate",
"zigpy_znp",
"universal_silabs_flasher"
],
"requirements": [
"bellows==0.36.4",
"pyserial==3.5",
"pyserial-asyncio==0.6",
"zha-quirks==0.0.103",
"zigpy-deconz==0.21.1",
"zigpy==0.57.1",
"zigpy-xbee==0.18.2",
"zigpy-zigate==0.11.0",
"zigpy-znp==0.11.4",
"universal-silabs-flasher==0.0.14"
],
"usb": [
{
"vid": "10C4",
"pid": "EA60",
"description": "*2652*",
"known_devices": [
"slae.sh cc2652rb stick"
]
},
{
"vid": "1A86",
"pid": "55D4",
"description": "*sonoff*plus*",
"known_devices": [
"sonoff zigbee dongle plus v2"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*sonoff*plus*",
"known_devices": [
"sonoff zigbee dongle plus"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*zigstar*",
"known_devices": [
"ZigStar Coordinators"
]
},
{
"vid": "1CF1",
"pid": "0030",
"description": "*conbee*",
"known_devices": [
"Conbee II"
]
},
{
"vid": "10C4",
"pid": "8A2A",
"description": "*zigbee*",
"known_devices": [
"Nortek HUSBZB-1"
]
},
{
"vid": "0403",
"pid": "6015",
"description": "*zigate*",
"known_devices": [
"ZiGate+"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*zigate*",
"known_devices": [
"ZiGate"
]
},
{
"vid": "10C4",
"pid": "8B34",
"description": "*bv 2010/10*",
"known_devices": [
"Bitron Video AV2010/10"
]
}
],
"zeroconf": [
{
"type": "_esphomelib._tcp.local.",
"name": "tube*"
},
{
"type": "_zigate-zigbee-gateway._tcp.local.",
"name": "*zigate*"
},
{
"type": "_zigstar_gw._tcp.local.",
"name": "*zigstar*"
},
{
"type": "_uzg-01._tcp.local.",
"name": "uzg-01*"
},
{
"type": "_slzb-06._tcp.local.",
"name": "slzb-06*"
}
],
"is_built_in": true
},
"data": {
"ieee": "**REDACTED**",
"nwk": 56232,
"manufacturer": "_TZE200_s6hzw8g2",
"model": "TS0601",
"name": "_TZE200_s6hzw8g2 TS0601",
"quirk_applied": false,
"quirk_class": "zigpy.device.Device",
"manufacturer_code": 4417,
"power_source": "Battery or Unknown",
"lqi": 200,
"rssi": -50,
"last_seen": "2023-10-02T21:08:25",
"available": true,
"device_type": "EndDevice",
"signature": {
"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: 128>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, 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=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": "0x0104",
"device_type": "0x0051",
"input_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0xef00"
],
"output_clusters": [
"0x000a",
"0x0019"
]
}
},
"manufacturer": "_TZE200_s6hzw8g2",
"model": "TS0601"
},
"active_coordinator": false,
"entities": [],
"neighbors": [],
"routes": [],
"endpoint_names": [
{
"name": "SMART_PLUG"
}
],
"user_given_name": "NEDIS PIR LUMEN",
"device_reg_id": "760a5c0a858881a6ca574e3a6c03fe99",
"area_id": "stairway",
"cluster_details": {
"1": {
"device_type": {
"name": "SMART_PLUG",
"id": 81
},
"profile_id": 260,
"in_clusters": {
"0x0004": {
"endpoint_attribute": "groups",
"attributes": {},
"unsupported_attributes": {}
},
"0x0005": {
"endpoint_attribute": "scenes",
"attributes": {},
"unsupported_attributes": {}
},
"0xef00": {
"endpoint_attribute": null,
"attributes": {},
"unsupported_attributes": {}
},
"0x0000": {
"endpoint_attribute": "basic",
"attributes": {
"0x0004": {
"attribute_name": "manufacturer",
"value": "_TZE200_s6hzw8g2"
},
"0x0005": {
"attribute_name": "model",
"value": "TS0601"
}
},
"unsupported_attributes": {}
}
},
"out_clusters": {
"0x0019": {
"endpoint_attribute": "ota",
"attributes": {},
"unsupported_attributes": {}
},
"0x000a": {
"endpoint_attribute": "time",
"attributes": {},
"unsupported_attributes": {}
}
}
}
}
}
}
Logs
Logs
023-10-02 21:35:30.763 INFO (MainThread) [zigpy.device] [0xfce5] Read model 'TS0601' and manufacturer '_TZE200_s6hzw8g2' from <Endpoint id=1 in=[groups:0x0004, scenes:0x0005, None:0xEF00, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>>
2023-10-02 21:35:30.763 INFO (MainThread) [zigpy.device] [0xfce5] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xFCE5 ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-02 21:35:30.763 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xFCE5 ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-02 21:35:30.763 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for _TZE200_s6hzw8g2 TS0601 (a4:c1:38:e5:9e:72:d3:28)
2023-10-02 21:35:30.763 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'ts0601_pirmotion.PirMotion'>
2023-10-02 21:35:30.763 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-02 21:35:30.769 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:e5:9e:72:d3:28, 1, 0, 4, '_TZE200_s6hzw8g2', datetime.datetime(2023, 10, 2, 18, 35, 30, 763141, tzinfo=datetime.timezone.utc)) params: FOREIGN KEY constraint failed
2023-10-02 21:35:30.770 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:e5:9e:72:d3:28, 1, 0, 5, 'TS0601', datetime.datetime(2023, 10, 2, 18, 35, 30, 763169, tzinfo=datetime.timezone.utc)) params: FOREIGN KEY constraint failed
2023-10-02 21:34:23.530 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=161), 184, -54, 0xfce5, 255, 255, b'\x18S\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601']
2023-10-02 21:34:23.530 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=161), 184, -54, 0xfce5, 255, 255, b'\x18S\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601']
2023-10-02 21:34:23.530 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xFCE5), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=161, profile_id=260, cluster_id=0, data=Serialized[b'\x18S\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=184, rssi=-54)
2023-10-02 21:34:23.531 DEBUG (MainThread) [zigpy.zcl] [0xFCE5:1:0x0000] Received ZCL frame: b'\x18S\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601'
2023-10-02 21:34:23.531 DEBUG (MainThread) [zigpy.zcl] [0xFCE5: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), tsn=83, command_id=1, *direction=<Direction.Client_to_Server: 1>)
2023-10-02 21:34:23.532 DEBUG (MainThread) [zigpy.zcl] [0xFCE5:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='_TZE200_s6hzw8g2')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='TS0601'))])
2023-10-02 21:34:23.532 INFO (MainThread) [zigpy.device] [0xfce5] Read model 'TS0601' and manufacturer '_TZE200_s6hzw8g2' from <Endpoint id=1 in=[groups:0x0004, scenes:0x0005, None:0xEF00, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>>
2023-10-02 21:34:23.533 INFO (MainThread) [zigpy.device] [0xfce5] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xFCE5 ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-02 21:34:23.533 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xFCE5 ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-02 21:34:23.533 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for _TZE200_s6hzw8g2 TS0601 (a4:c1:38:e5:9e:72:d3:28)
2023-10-02 21:34:23.533 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'ts0601_pirmotion.PirMotion'>
2023-10-02 21:34:23.533 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
Custom quirk
Custom quirk
[Paste your custom quirk here]
Additional information
I would suggest to add your MODELS_INFO to the MmwRadarMotion
quirk:
https://github.com/zigpy/zha-device-handlers/blob/74b9f043a996da6baca722d1e191d842a31ae198/zhaquirks/tuya/ts0601_motion.py#L342C7-L342C7
Once donde, repair your device and enable the debug logs. Interact with the device and attach the relevant logs.
Thanks for replying ! Removed device from HA. Added the models_info - like instructed, restarted HA. Added the device back - all still looks exactly like earlier... Tried the repair function, didn't do anything that would indicate "success" Included the diagnostic info and relevant bits from system log. I don't know where else I should look for debug logs Thx
-T
Diagnostic information
{
"home_assistant": {
"installation_type": "Home Assistant Container",
"version": "2023.9.3",
"dev": false,
"hassio": false,
"virtualenv": false,
"python_version": "3.11.5",
"docker": true,
"arch": "x86_64",
"timezone": "Europe/Helsinki",
"os_name": "Linux",
"os_version": "6.2.0-26-generic",
"run_as_root": true
},
"custom_components": {
"zha_toolkit": {
"version": "v1.0.0",
"requirements": [
"pytz"
]
},
"ruuvi": {
"version": "1.1.0",
"requirements": [
"simple-ruuvitag==0.0.8"
]
},
"hacs": {
"version": "1.33.0",
"requirements": [
"aiogithubapi>=22.10.1"
]
}
},
"integration_manifest": {
"domain": "zha",
"name": "Zigbee Home Automation",
"after_dependencies": [
"onboarding",
"usb"
],
"codeowners": [
"@dmulcahey",
"@adminiuga",
"@puddly"
],
"config_flow": true,
"dependencies": [
"file_upload"
],
"documentation": "https://www.home-assistant.io/integrations/zha",
"iot_class": "local_polling",
"loggers": [
"aiosqlite",
"bellows",
"crccheck",
"pure_pcapy3",
"zhaquirks",
"zigpy",
"zigpy_deconz",
"zigpy_xbee",
"zigpy_zigate",
"zigpy_znp",
"universal_silabs_flasher"
],
"requirements": [
"bellows==0.36.4",
"pyserial==3.5",
"pyserial-asyncio==0.6",
"zha-quirks==0.0.103",
"zigpy-deconz==0.21.1",
"zigpy==0.57.1",
"zigpy-xbee==0.18.2",
"zigpy-zigate==0.11.0",
"zigpy-znp==0.11.4",
"universal-silabs-flasher==0.0.14"
],
"usb": [
{
"vid": "10C4",
"pid": "EA60",
"description": "*2652*",
"known_devices": [
"slae.sh cc2652rb stick"
]
},
{
"vid": "1A86",
"pid": "55D4",
"description": "*sonoff*plus*",
"known_devices": [
"sonoff zigbee dongle plus v2"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*sonoff*plus*",
"known_devices": [
"sonoff zigbee dongle plus"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*zigstar*",
"known_devices": [
"ZigStar Coordinators"
]
},
{
"vid": "1CF1",
"pid": "0030",
"description": "*conbee*",
"known_devices": [
"Conbee II"
]
},
{
"vid": "10C4",
"pid": "8A2A",
"description": "*zigbee*",
"known_devices": [
"Nortek HUSBZB-1"
]
},
{
"vid": "0403",
"pid": "6015",
"description": "*zigate*",
"known_devices": [
"ZiGate+"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*zigate*",
"known_devices": [
"ZiGate"
]
},
{
"vid": "10C4",
"pid": "8B34",
"description": "*bv 2010/10*",
"known_devices": [
"Bitron Video AV2010/10"
]
}
],
"zeroconf": [
{
"type": "_esphomelib._tcp.local.",
"name": "tube*"
},
{
"type": "_zigate-zigbee-gateway._tcp.local.",
"name": "*zigate*"
},
{
"type": "_zigstar_gw._tcp.local.",
"name": "*zigstar*"
},
{
"type": "_uzg-01._tcp.local.",
"name": "uzg-01*"
},
{
"type": "_slzb-06._tcp.local.",
"name": "slzb-06*"
}
],
"is_built_in": true
},
"data": {
"ieee": "**REDACTED**",
"nwk": 44974,
"manufacturer": "_TZE200_s6hzw8g2",
"model": "TS0601",
"name": "_TZE200_s6hzw8g2 TS0601",
"quirk_applied": false,
"quirk_class": "zigpy.device.Device",
"manufacturer_code": 4417,
"power_source": "Battery or Unknown",
"lqi": 160,
"rssi": -60,
"last_seen": "2023-10-03T21:15:13",
"available": true,
"device_type": "EndDevice",
"signature": {
"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: 128>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, 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=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": "0x0104",
"device_type": "0x0051",
"input_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0xef00"
],
"output_clusters": [
"0x000a",
"0x0019"
]
}
},
"manufacturer": "_TZE200_s6hzw8g2",
"model": "TS0601"
},
"active_coordinator": false,
"entities": [],
"neighbors": [],
"routes": [],
"endpoint_names": [
{
"name": "SMART_PLUG"
}
],
"user_given_name": null,
"device_reg_id": "760a5c0a858881a6ca574e3a6c03fe99",
"area_id": "stairway",
"cluster_details": {
"1": {
"device_type": {
"name": "SMART_PLUG",
"id": 81
},
"profile_id": 260,
"in_clusters": {
"0x0004": {
"endpoint_attribute": "groups",
"attributes": {},
"unsupported_attributes": {}
},
"0x0005": {
"endpoint_attribute": "scenes",
"attributes": {},
"unsupported_attributes": {}
},
"0xef00": {
"endpoint_attribute": null,
"attributes": {},
"unsupported_attributes": {}
},
"0x0000": {
"endpoint_attribute": "basic",
"attributes": {
"0x0004": {
"attribute_name": "manufacturer",
"value": "_TZE200_s6hzw8g2"
},
"0x0005": {
"attribute_name": "model",
"value": "TS0601"
}
},
"unsupported_attributes": {}
}
},
"out_clusters": {
"0x0019": {
"endpoint_attribute": "ota",
"attributes": {},
"unsupported_attributes": {}
},
"0x000a": {
"endpoint_attribute": "time",
"attributes": {},
"unsupported_attributes": {}
}
}
}
}
}
}
Logs
2023-10-03 21:13:40.660 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=249), 204, -49, 0xe322, 255, 255, b'\x18G\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601']
2023-10-03 21:13:40.661 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE322), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=249, profile_id=260, cluster_id=0, data=Serialized[b'\x18G\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:13:40.661 DEBUG (MainThread) [zigpy.zcl] [0xE322:1:0x0000] Received ZCL frame: b'\x18G\x01\x04\x00\x00B\x10_TZE200_s6hzw8g2\x05\x00\x00B\x06TS0601'
2023-10-03 21:13:40.661 DEBUG (MainThread) [zigpy.zcl] [0xE322: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), tsn=71, command_id=1, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:13:40.662 DEBUG (MainThread) [zigpy.zcl] [0xE322:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='_TZE200_s6hzw8g2')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='TS0601'))])
2023-10-03 21:13:40.662 INFO (MainThread) [zigpy.device] [0xe322] Read model 'TS0601' and manufacturer '_TZE200_s6hzw8g2' from <Endpoint id=1 in=[groups:0x0004, scenes:0x0005, None:0xEF00, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>>
2023-10-03 21:13:40.662 INFO (MainThread) [zigpy.device] [0xe322] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xE322 ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-03 21:13:40.662 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xE322 ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for _TZE200_s6hzw8g2 TS0601 (a4:c1:38:e5:9e:72:d3:28)
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'TZE200_s6hzw8g2.MmwRadarMotionGPP'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 242} {1}
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 242} {1}
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 41, 42, 242, 51, 21, 31} {1}
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1}
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1}
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2023-10-03 21:13:40.664 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1}
2023-10-03 21:13:40.666 INFO (MainThread) [homeassistant.components.zha.core.endpoint] Creating cluster handler for cluster id: 4 class: <class 'homeassistant.components.zha.core.cluster_handlers.general.Groups'>
2023-10-03 21:13:40.667 INFO (MainThread) [homeassistant.components.zha.core.endpoint] Creating cluster handler for cluster id: 5 class: <class 'homeassistant.components.zha.core.cluster_handlers.general.Scenes'>
2023-10-03 21:13:40.667 INFO (MainThread) [homeassistant.components.zha.core.endpoint] Creating cluster handler for cluster id: 61184 class: <class 'homeassistant.components.zha.core.cluster_handlers.manufacturerspecific.TuyaClusterHandler'>
2023-10-03 21:13:40.667 INFO (MainThread) [homeassistant.components.zha.core.endpoint] Creating cluster handler for cluster id: 0 class: <class 'homeassistant.components.zha.core.cluster_handlers.general.BasicClusterHandler'>
2023-10-03 21:13:40.668 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] Discovering entities for endpoint: a4:c1:38:e5:9e:72:d3:28-1
2023-10-03 21:13:40.672 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'RSSISensor' using ['basic']
2023-10-03 21:13:40.672 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'LQISensor' using ['basic']
2023-10-03 21:13:40.674 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event device_registry_updated[L]: action=create, device_id=760a5c0a858881a6ca574e3a6c03fe99>
2023-10-03 21:13:40.674 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0xE322:a4:c1:38:e5:9e:72:d3:28 entering async_device_initialized - is_new_join: True
2023-10-03 21:13:40.674 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0xE322:a4:c1:38:e5:9e:72:d3:28 has joined the ZHA zigbee network
2023-10-03 21:13:40.674 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): started configuration
2023-10-03 21:13:40.675 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:ZDO](TS0601): 'async_configure' stage succeeded
2023-10-03 21:13:40.678 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0000]: Configuring cluster attribute reporting
2023-10-03 21:13:40.678 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0000]: finished cluster handler configuration
2023-10-03 21:13:40.678 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: Performing cluster binding
2023-10-03 21:13:40.679 DEBUG (MainThread) [zigpy.device] [0xe322] Extending timeout for 0x49 request
2023-10-03 21:13:40.679 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=0xE322), dst_ep=0, source_route=None, extended_timeout=True, tsn=73, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'I(\xd3r\x9e\xe58\xc1\xa4\x01\x00\xef\x03\x0b4\x03\xfe\xffW\xb4\x14\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:13:40.679 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:13:40.679 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0019]: finished cluster handler configuration
2023-10-03 21:13:40.680 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:e5:9e:72:d3:28, 1, 0, 4, '_TZE200_s6hzw8g2', datetime.datetime(2023, 10, 3, 18, 13, 40, 662621, tzinfo=datetime.timezone.utc)) params: FOREIGN KEY constraint failed
2023-10-03 21:13:40.680 DEBUG (MainThread) [bellows.uart] Sending: b'63d721a92a2a3d612b0aaf1d6bf193377d3a7e'
2023-10-03 21:13:40.682 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:e5:9e:72:d3:28, 1, 0, 5, 'TS0601', datetime.datetime(2023, 10, 3, 18, 13, 40, 662644, tzinfo=datetime.timezone.utc)) params: FOREIGN KEY constraint failed
2023-10-03 21:13:40.733 DEBUG (MainThread) [bellows.uart] Data frame: b'37d7a1a92a2a6a217e'
2023-10-03 21:13:40.733 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-10-03 21:13:40.733 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:13:40.733 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xe322, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=73), 74, b'I(\xd3r\x9e\xe58\xc1\xa4\x01\x00\xef\x03\x0b4\x03\xfe\xffW\xb4\x14\x01')
2023-10-03 21:13:40.734 DEBUG (MainThread) [bellows.uart] Sending: b'74d421a9602a1590ba944a04aa5592499d4e27e2a7d82ea32eb4fd6cc4bf9ba6eb22dd64bbfc392482667d5d8d21ea7e'
2023-10-03 21:13:40.789 DEBUG (MainThread) [bellows.uart] Data frame: b'40d4a1a9602a1551cf517e'
2023-10-03 21:13:40.789 DEBUG (MainThread) [bellows.uart] Sending: b'8520dd7e'
2023-10-03 21:13:40.789 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 227]
2023-10-03 21:13:40.789 DEBUG (MainThread) [bellows.uart] Data frame: b'50d4b1a96b2a1590ba944a04aa5592499d4e2748a7ce67194d7e'
2023-10-03 21:13:40.789 DEBUG (MainThread) [bellows.uart] Sending: b'8610be7e'
2023-10-03 21:13:40.789 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 58146, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=227), 74, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:13:40.790 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 58146, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=227), 74, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:13:40.864 DEBUG (MainThread) [bellows.uart] Data frame: b'60d4b1a9112a15b259964a25aa1593499cb4eb64cf2d9874fedb63895ac37e'
2023-10-03 21:13:40.864 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-10-03 21:13:40.864 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=250), 204, -49, 0xe322, 255, 255, b'\x1d\x00\x00']
2023-10-03 21:13:40.864 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=250), 204, -49, 0xe322, 255, 255, b'\x1d\x00\x00']
2023-10-03 21:13:40.865 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE322), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=250, profile_id=0, cluster_id=2, data=Serialized[b'\x1d\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:13:40.865 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:13:40.865 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:13:40.865 DEBUG (MainThread) [bellows.uart] Data frame: b'70d4b1a9112a15b259b5ca25aa1593499cb5eb64cf2d9874ff8f63f9947e'
2023-10-03 21:13:40.865 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-10-03 21:13:40.865 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=251), 204, -49, 0xe322, 255, 255, b'I\x00']
2023-10-03 21:13:40.865 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=251), 204, -49, 0xe322, 255, 255, b'I\x00']
2023-10-03 21:13:40.866 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE322), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=251, profile_id=0, cluster_id=32801, data=Serialized[b'I\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:13:40.866 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: bound 'None' cluster: Status.SUCCESS
2023-10-03 21:13:40.866 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: Configuring cluster attribute reporting
2023-10-03 21:13:40.866 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: finished cluster handler configuration
2023-10-03 21:13:40.868 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0000]: 'async_configure' stage succeeded
2023-10-03 21:13:40.869 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: 'async_configure' stage succeeded
2023-10-03 21:13:40.869 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0019]: 'async_configure' stage succeeded
2023-10-03 21:13:40.870 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): completed configuration
2023-10-03 21:13:40.871 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): started initialization
2023-10-03 21:13:40.871 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:ZDO](TS0601): 'async_initialize' stage succeeded
2023-10-03 21:13:40.873 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0000]: initializing cluster handler: from_cache: False
2023-10-03 21:13:40.874 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0000]: finished cluster handler initialization
2023-10-03 21:13:40.874 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: initializing cluster handler: from_cache: False
2023-10-03 21:13:40.874 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: finished cluster handler initialization
2023-10-03 21:13:40.875 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0019]: initializing cluster handler: from_cache: False
2023-10-03 21:13:40.875 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0019]: finished cluster handler initialization
2023-10-03 21:13:40.876 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0000]: 'async_initialize' stage succeeded
2023-10-03 21:13:40.876 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0xef00]: 'async_initialize' stage succeeded
2023-10-03 21:13:40.876 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE322:1:0x0019]: 'async_initialize' stage succeeded
2023-10-03 21:13:40.877 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): power source: Battery or Unknown
2023-10-03 21:13:40.877 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): completed initialization
2023-10-03 21:13:40.879 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event device_registry_updated[L]: action=update, device_id=760a5c0a858881a6ca574e3a6c03fe99, changes=via_device_id=None>
2023-10-03 21:13:40.879 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_s6hzw8g2_ts0601_rssi
2023-10-03 21:13:40.879 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event entity_registry_updated[L]: action=create, entity_id=sensor.tze200_s6hzw8g2_ts0601_rssi>
2023-10-03 21:13:40.880 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_s6hzw8g2_ts0601_lqi
2023-10-03 21:13:40.880 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event entity_registry_updated[L]: action=create, entity_id=sensor.tze200_s6hzw8g2_ts0601_lqi>
2023-10-03 21:13:42.233 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-10-03 21:13:42.233 DEBUG (MainThread) [bellows.uart] Sending: b'00d521a9a52a1ff17e'
2023-10-03 21:13:42.289 DEBUG (MainThread) [bellows.uart] Data frame: b'01d5a1a9a52a34b267945225be5592499c4e23abe9ce688bf6c67989e47e33a7eacdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2611b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482412e27e'
2023-10-03 21:13:42.289 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-10-03 21:13:42.289 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[33, 62, 24, 20, 0, 0, 4, 4, 15, 11, 26, 24, 12, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-10-03 21:13:42.289 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-10-03 21:13:42.290 DEBUG (MainThread) [bellows.uart] Sending: b'7d31da21a9fe2a16840f7e'
2023-10-03 21:13:42.340 DEBUG (MainThread) [bellows.uart] Data frame: b'12daa1a9fe2a15b3a886e27e'
2023-10-03 21:13:42.340 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-10-03 21:13:42.340 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf1']
2023-10-03 21:13:42.340 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 241
2023-10-03 21:13:42.341 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 33, MAC_TX_BROADCAST = 62, MAC_RX_UNICAST = 24, MAC_TX_UNICAST_SUCCESS = 20, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 15, APS_DATA_TX_UNICAST_SUCCESS = 11, APS_DATA_TX_UNICAST_RETRY = 26, APS_DATA_TX_UNICAST_FAILED = 24, ROUTE_DISCOVERY_INITIATED = 12, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 2, 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 = 241]
2023-10-03 21:13:45.340 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event device_registry_updated[L]: action=update, device_id=760a5c0a858881a6ca574e3a6c03fe99, changes=area_id=None>
2023-10-03 21:13:45.863 DEBUG (MainThread) [bellows.uart] Data frame: b'22dab1a9112a15b259964a25aa1593499cb3eb64cf2d9874fed86389034b7e'
2023-10-03 21:13:45.864 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-10-03 21:13:45.864 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=253), 204, -49, 0xe322, 255, 255, b'\x1e\x00\x00']
2023-10-03 21:13:45.864 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=253), 204, -49, 0xe322, 255, 255, b'\x1e\x00\x00']
2023-10-03 21:13:45.864 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE322), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=253, profile_id=0, cluster_id=2, data=Serialized[b'\x1e\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:13:45.865 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:13:45.865 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:13:50.868 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=255), 204, -49, 0xe322, 255, 255, b'\x1f\x00\x00']
2023-10-03 21:13:50.868 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=255), 204, -49, 0xe322, 255, 255, b'\x1f\x00\x00']
2023-10-03 21:13:50.868 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE322), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=255, profile_id=0, cluster_id=2, data=Serialized[b'\x1f\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:13:50.869 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:13:50.869 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:13:52.342 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-10-03 21:13:52.396 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[35, 65, 28, 26, 0, 0, 4, 4, 17, 11, 26, 24, 12, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 6, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-10-03 21:13:52.396 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-10-03 21:13:52.448 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf1']
2023-10-03 21:13:52.448 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 241
2023-10-03 21:13:52.448 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 35, MAC_TX_BROADCAST = 65, MAC_RX_UNICAST = 28, MAC_TX_UNICAST_SUCCESS = 26, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 17, APS_DATA_TX_UNICAST_SUCCESS = 11, APS_DATA_TX_UNICAST_RETRY = 26, APS_DATA_TX_UNICAST_FAILED = 24, ROUTE_DISCOVERY_INITIATED = 12, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 6, 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 = 241]
2023-10-03 21:13:55.772 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received changeSourceRouteHandler: [0xe502, 0x00fc, <Bool.false: 0>]
2023-10-03 21:13:55.772 DEBUG (MainThread) [bellows.zigbee.application] Received changeSourceRouteHandler frame with [0xe502, 0x00fc, <Bool.false: 0>]
2023-10-03 21:13:55.872 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=1), 204, -49, 0xe322, 255, 255, b' \x00\x00']
2023-10-03 21:13:55.872 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=1), 204, -49, 0xe322, 255, 255, b' \x00\x00']
2023-10-03 21:13:55.872 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE322), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=1, profile_id=0, cluster_id=2, data=Serialized[b' \x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:13:55.873 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:13:55.873 DEBUG (MainThread) [zigpy.zdo] [0xe322:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:14:00.872 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xe322, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:00.872 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xe322, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:00.872 INFO (MainThread) [zigpy.application] Device 0xe322 (a4:c1:38:e5:9e:72:d3:28) left the network
2023-10-03 21:14:00.872 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): Update device availability - device available: True - new availability: False - changed: True
2023-10-03 21:14:00.872 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): Device availability changed and device became unavailable
2023-10-03 21:14:00.872 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=a4:c1:38:e5:9e:72:d3:28, unique_id=a4:c1:38:e5:9e:72:d3:28, device_id=760a5c0a858881a6ca574e3a6c03fe99, device_event_type=device_offline>
2023-10-03 21:14:00.874 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xe322, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:00.874 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xe322, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:00.874 INFO (MainThread) [zigpy.application] Device 0xe322 (a4:c1:38:e5:9e:72:d3:28) left the network
2023-10-03 21:14:00.874 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE322](TS0601): Update device availability - device available: False - new availability: False - changed: False
2023-10-03 21:14:02.450 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-10-03 21:14:02.504 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[37, 66, 33, 29, 0, 0, 4, 4, 18, 11, 26, 24, 12, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-10-03 21:14:02.504 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-10-03 21:14:02.552 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf1']
2023-10-03 21:14:02.552 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 241
2023-10-03 21:14:02.552 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 37, MAC_TX_BROADCAST = 66, MAC_RX_UNICAST = 33, MAC_TX_UNICAST_SUCCESS = 29, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 18, APS_DATA_TX_UNICAST_SUCCESS = 11, APS_DATA_TX_UNICAST_RETRY = 26, APS_DATA_TX_UNICAST_FAILED = 24, ROUTE_DISCOVERY_INITIATED = 12, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 8, 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 = 241]
2023-10-03 21:14:04.848 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x6409]
2023-10-03 21:14:04.848 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x6409]
2023-10-03 21:14:04.848 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) joined the network
2023-10-03 21:14:04.848 DEBUG (MainThread) [zigpy.application] Device a4:c1:38:e5:9e:72:d3:28 changed id (0xe322 => 0xafae)
2023-10-03 21:14:04.848 DEBUG (MainThread) [zigpy.device] [0xafae] Skipping initialization, device is fully initialized
2023-10-03 21:14:04.848 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='TS0601' manuf='_TZE200_s6hzw8g2' nwk=0xAFAE ieee=a4:c1:38:e5:9e:72:d3:28 is_initialized=True>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for _TZE200_s6hzw8g2 TS0601 (a4:c1:38:e5:9e:72:d3:28)
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'TZE200_s6hzw8g2.MmwRadarMotionGPP'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 242} {1}
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 242} {1}
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 41, 42, 242, 51, 21, 31} {1}
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1}
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1}
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2023-10-03 21:14:04.849 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1}
2023-10-03 21:14:04.850 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x6409]
2023-10-03 21:14:04.850 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x6409]
2023-10-03 21:14:04.850 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) joined the network
2023-10-03 21:14:04.850 DEBUG (MainThread) [bellows.ezsp.protocol] Send command findKeyTableEntry: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:04.850 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0xafae:a4:c1:38:e5:9e:72:d3:28 entering async_device_initialized - is_new_join: False
2023-10-03 21:14:04.850 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0xafae:a4:c1:38:e5:9e:72:d3:28 has been reset and re-added or its nwk address changed
2023-10-03 21:14:04.850 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] skipping discovery for previously discovered device - 0xafae:a4:c1:38:e5:9e:72:d3:28
2023-10-03 21:14:04.850 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): started configuration
2023-10-03 21:14:04.850 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:ZDO](TS0601): 'async_configure' stage succeeded
2023-10-03 21:14:04.850 DEBUG (MainThread) [bellows.ezsp.protocol] Send command findKeyTableEntry: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:04.851 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=75, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:04.851 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request: get_membership(groups=[])
2023-10-03 21:14:04.851 DEBUG (MainThread) [zigpy.device] [0xafae] Extending timeout for 0x4b request
2023-10-03 21:14:04.852 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=0xAFAE), dst_ep=1, source_route=None, extended_timeout=True, tsn=75, profile_id=260, cluster_id=4, data=Serialized[b'\x01K\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:04.853 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:04.853 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: Configuring cluster attribute reporting
2023-10-03 21:14:04.853 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: finished cluster handler configuration
2023-10-03 21:14:04.853 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: Performing cluster binding
2023-10-03 21:14:04.853 DEBUG (MainThread) [zigpy.device] [0xafae] Extending timeout for 0x4d request
2023-10-03 21:14:04.853 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=0xAFAE), dst_ep=0, source_route=None, extended_timeout=True, tsn=77, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'M(\xd3r\x9e\xe58\xc1\xa4\x01\x00\xef\x03\x0b4\x03\xfe\xffW\xb4\x14\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:04.854 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: finished cluster handler configuration
2023-10-03 21:14:04.904 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received findKeyTableEntry: [255]
2023-10-03 21:14:04.956 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received findKeyTableEntry: [255]
2023-10-03 21:14:05.012 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:05.012 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xafae, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=75), 76, b'\x01K\x02\x00')
2023-10-03 21:14:05.068 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 236]
2023-10-03 21:14:05.068 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:05.120 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:05.120 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xafae, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=77), 78, b'M(\xd3r\x9e\xe58\xc1\xa4\x01\x00\xef\x03\x0b4\x03\xfe\xffW\xb4\x14\x01')
2023-10-03 21:14:05.176 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 237]
2023-10-03 21:14:05.176 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=237), 78, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.176 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=237), 78, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.272 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=236), 76, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.273 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=236), 76, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.384 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=208), 204, -49, 0xafae, 255, 255, b'!\xae\xaf(\xd3r\x9e\xe58\xc1\xa4\x80']
2023-10-03 21:14:05.384 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=208), 204, -49, 0xafae, 255, 255, b'!\xae\xaf(\xd3r\x9e\xe58\xc1\xa4\x80']
2023-10-03 21:14:05.385 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), 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=208, profile_id=0, cluster_id=19, data=Serialized[b'!\xae\xaf(\xd3r\x9e\xe58\xc1\xa4\x80'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:05.385 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) joined the network
2023-10-03 21:14:05.385 DEBUG (MainThread) [zigpy.device] [0xafae] Cancelling old group rescan
2023-10-03 21:14:05.386 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Update device availability - device available: False - new availability: True - changed: True
2023-10-03 21:14:05.386 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Device availability changed and device became available, reinitializing cluster handlers
2023-10-03 21:14:05.386 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] ZDO request ZDOCmd.Device_annce: [0xAFAE, a4:c1:38:e5:9e:72:d3:28, 128]
2023-10-03 21:14:05.387 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=209), 204, -49, 0xafae, 255, 255, b'M\x00']
2023-10-03 21:14:05.387 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=209), 204, -49, 0xafae, 255, 255, b'M\x00']
2023-10-03 21:14:05.387 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=209, profile_id=0, cluster_id=32801, data=Serialized[b'M\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:05.387 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=210), 204, -49, 0xafae, 255, 255, b'\x19K\x02\x10\x00']
2023-10-03 21:14:05.387 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=210), 204, -49, 0xafae, 255, 255, b'\x19K\x02\x10\x00']
2023-10-03 21:14:05.387 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=210, profile_id=260, cluster_id=4, data=Serialized[b'\x19K\x02\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:05.388 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Received ZCL frame: b'\x19K\x02\x10\x00'
2023-10-03 21:14:05.388 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=75, command_id=2, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:14:05.388 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame: Groups:get_membership_response(capacity=16, groups=[])
2023-10-03 21:14:05.388 DEBUG (MainThread) [zigpy.device] [0xafae] Invalid state on future for 0x4b seq -- probably duplicate response
2023-10-03 21:14:05.388 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=211), 204, -49, 0xafae, 255, 255, b'\x18K\x0b\x02\x00']
2023-10-03 21:14:05.389 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=211), 204, -49, 0xafae, 255, 255, b'\x18K\x0b\x02\x00']
2023-10-03 21:14:05.389 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=211, profile_id=260, cluster_id=4, data=Serialized[b'\x18K\x0b\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:05.389 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Received ZCL frame: b'\x18K\x0b\x02\x00'
2023-10-03 21:14:05.389 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=75, command_id=11, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:14:05.389 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame: Groups:Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2023-10-03 21:14:05.389 DEBUG (MainThread) [zigpy.device] [0xafae] Invalid state on future for 0x4b seq -- probably duplicate response
2023-10-03 21:14:05.390 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=79, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:05.390 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request: get_membership(groups=[])
2023-10-03 21:14:05.390 DEBUG (MainThread) [zigpy.device] [0xafae] Extending timeout for 0x4f request
2023-10-03 21:14:05.390 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=0xAFAE), dst_ep=1, source_route=None, extended_timeout=True, tsn=79, profile_id=260, cluster_id=4, data=Serialized[b'\x01O\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:05.390 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): started initialization
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:ZDO](TS0601): 'async_initialize' stage succeeded
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: bound 'None' cluster: Status.SUCCESS
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: Configuring cluster attribute reporting
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: finished cluster handler configuration
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: initializing cluster handler: from_cache: False
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: finished cluster handler initialization
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: initializing cluster handler: from_cache: False
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: finished cluster handler initialization
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: initializing cluster handler: from_cache: False
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: finished cluster handler initialization
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: 'async_configure' stage succeeded
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: 'async_configure' stage succeeded
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: 'async_configure' stage succeeded
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: 'async_initialize' stage succeeded
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: 'async_initialize' stage succeeded
2023-10-03 21:14:05.391 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: 'async_initialize' stage succeeded
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): completed configuration
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Update device availability - device available: False - new availability: True - changed: True
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Device availability changed and device became available, reinitializing cluster handlers
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): started initialization
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:ZDO](TS0601): 'async_initialize' stage succeeded
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): power source: Battery or Unknown
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): completed initialization
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: initializing cluster handler: from_cache: False
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: finished cluster handler initialization
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: initializing cluster handler: from_cache: False
2023-10-03 21:14:05.392 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: finished cluster handler initialization
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: initializing cluster handler: from_cache: False
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: finished cluster handler initialization
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: 'async_initialize' stage succeeded
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: 'async_initialize' stage succeeded
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: 'async_initialize' stage succeeded
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): power source: Battery or Unknown
2023-10-03 21:14:05.393 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): completed initialization
2023-10-03 21:14:05.440 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:05.440 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xafae, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=79), 80, b'\x01O\x02\x00')
2023-10-03 21:14:05.492 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 238]
2023-10-03 21:14:05.493 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=238), 80, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.493 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=238), 80, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.494 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=212), 204, -49, 0xafae, 255, 255, b'\x19O\x02\x10\x00']
2023-10-03 21:14:05.494 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=212), 204, -49, 0xafae, 255, 255, b'\x19O\x02\x10\x00']
2023-10-03 21:14:05.494 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=212, profile_id=260, cluster_id=4, data=Serialized[b'\x19O\x02\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:05.495 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Received ZCL frame: b'\x19O\x02\x10\x00'
2023-10-03 21:14:05.495 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=79, command_id=2, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:14:05.495 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame: Groups:get_membership_response(capacity=16, groups=[])
2023-10-03 21:14:05.496 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=213), 204, -49, 0xafae, 255, 255, b'\x18O\x0b\x02\x00']
2023-10-03 21:14:05.496 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=213), 204, -49, 0xafae, 255, 255, b'\x18O\x0b\x02\x00']
2023-10-03 21:14:05.496 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=213, profile_id=260, cluster_id=4, data=Serialized[b'\x18O\x0b\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:05.497 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Received ZCL frame: b'\x18O\x0b\x02\x00'
2023-10-03 21:14:05.497 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=79, command_id=11, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:14:05.497 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame: Groups:Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2023-10-03 21:14:05.497 DEBUG (MainThread) [zigpy.device] [0xafae] Invalid state on future for 0x4f seq -- probably duplicate response
2023-10-03 21:14:05.896 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_NONE: 0>, groupId=0, sequence=208), 0, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.896 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_NONE: 0>, groupId=0, sequence=208), 0, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:05.896 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 0
2023-10-03 21:14:06.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=214), 204, -49, 0xafae, 255, 255, b'"\x00\x00']
2023-10-03 21:14:06.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=214), 204, -49, 0xafae, 255, 255, b'"\x00\x00']
2023-10-03 21:14:06.568 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=214, profile_id=0, cluster_id=2, data=Serialized[b'"\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:06.568 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:14:06.569 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:14:09.241 DEBUG (MainThread) [homeassistant.components.zha.websocket_api] Reconfiguring node with ieee_address: a4:c1:38:e5:9e:72:d3:28
2023-10-03 21:14:09.241 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): started configuration
2023-10-03 21:14:09.242 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:ZDO](TS0601): 'async_configure' stage succeeded
2023-10-03 21:14:09.242 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: Configuring cluster attribute reporting
2023-10-03 21:14:09.243 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: finished cluster handler configuration
2023-10-03 21:14:09.243 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: Performing cluster binding
2023-10-03 21:14:09.244 DEBUG (MainThread) [zigpy.device] [0xafae] Extending timeout for 0x51 request
2023-10-03 21:14:09.245 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=0xAFAE), dst_ep=0, source_route=None, extended_timeout=True, tsn=81, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'Q(\xd3r\x9e\xe58\xc1\xa4\x01\x00\xef\x03\x0b4\x03\xfe\xffW\xb4\x14\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:09.246 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:09.246 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: finished cluster handler configuration
2023-10-03 21:14:09.296 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:09.296 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xafae, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=81), 82, b'Q(\xd3r\x9e\xe58\xc1\xa4\x01\x00\xef\x03\x0b4\x03\xfe\xffW\xb4\x14\x01')
2023-10-03 21:14:09.352 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 240]
2023-10-03 21:14:09.353 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=240), 82, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:09.353 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=240), 82, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:09.353 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=216), 204, -49, 0xafae, 255, 255, b'Q\x00']
2023-10-03 21:14:09.353 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=216), 204, -49, 0xafae, 255, 255, b'Q\x00']
2023-10-03 21:14:09.353 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=216, profile_id=0, cluster_id=32801, data=Serialized[b'Q\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:09.354 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: bound 'None' cluster: Status.SUCCESS
2023-10-03 21:14:09.354 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: Configuring cluster attribute reporting
2023-10-03 21:14:09.354 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: finished cluster handler configuration
2023-10-03 21:14:09.355 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: 'async_configure' stage succeeded
2023-10-03 21:14:09.355 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: 'async_configure' stage succeeded
2023-10-03 21:14:09.355 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: 'async_configure' stage succeeded
2023-10-03 21:14:09.355 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): completed configuration
2023-10-03 21:14:11.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=217), 204, -49, 0xafae, 255, 255, b'#\x00\x00']
2023-10-03 21:14:11.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=217), 204, -49, 0xafae, 255, 255, b'#\x00\x00']
2023-10-03 21:14:11.569 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=217, profile_id=0, cluster_id=2, data=Serialized[b'#\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:11.569 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:14:11.569 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:14:12.553 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-10-03 21:14:12.608 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[42, 79, 52, 43, 0, 0, 5, 5, 26, 15, 27, 24, 14, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 12, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-10-03 21:14:12.608 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-10-03 21:14:12.656 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf1']
2023-10-03 21:14:12.656 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 241
2023-10-03 21:14:12.656 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 42, MAC_TX_BROADCAST = 79, MAC_RX_UNICAST = 52, MAC_TX_UNICAST_SUCCESS = 43, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 5, APS_DATA_TX_BROADCAST = 5, APS_DATA_RX_UNICAST = 26, APS_DATA_TX_UNICAST_SUCCESS = 15, APS_DATA_TX_UNICAST_RETRY = 27, APS_DATA_TX_UNICAST_FAILED = 24, ROUTE_DISCOVERY_INITIATED = 14, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 12, 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 = 241]
2023-10-03 21:14:16.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=219), 204, -49, 0xafae, 255, 255, b'$\x00\x00']
2023-10-03 21:14:16.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=219), 204, -49, 0xafae, 255, 255, b'$\x00\x00']
2023-10-03 21:14:16.568 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=219, profile_id=0, cluster_id=2, data=Serialized[b'$\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=204, rssi=-49)
2023-10-03 21:14:16.569 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:14:16.569 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:14:17.402 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x66C0](TS0203): Attempting to checkin with device - missed checkins: 1
2023-10-03 21:14:17.403 DEBUG (MainThread) [zigpy.zcl] [0x66C0: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), tsn=83, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:17.403 DEBUG (MainThread) [zigpy.zcl] [0x66C0:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4])
2023-10-03 21:14:17.403 DEBUG (MainThread) [zigpy.device] [0x66c0] Extending timeout for 0x53 request
2023-10-03 21:14:17.403 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=0x66C0), dst_ep=1, source_route=None, extended_timeout=True, tsn=83, profile_id=260, cluster_id=0, data=Serialized[b'\x00S\x00\x04\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:17.404 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:32:48:1e:cd:44, True)
2023-10-03 21:14:17.416 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01CC](TRADFRI bulb E27 CWS 806lm): Attempting to checkin with device - missed checkins: 1
2023-10-03 21:14:17.416 DEBUG (MainThread) [zigpy.zcl] [0x01CC: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), tsn=85, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:17.417 DEBUG (MainThread) [zigpy.zcl] [0x01CC:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4])
2023-10-03 21:14:17.417 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=0x01CC), dst_ep=1, source_route=None, extended_timeout=False, tsn=85, profile_id=260, cluster_id=0, data=Serialized[b'\x00U\x00\x04\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:17.456 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:17.456 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x66c0, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=83), 84, b'\x00S\x00\x04\x00')
2023-10-03 21:14:17.512 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 243]
2023-10-03 21:14:17.512 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x01cc, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=85), 86, b'\x00U\x00\x04\x00')
2023-10-03 21:14:17.564 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 244]
2023-10-03 21:14:19.120 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 26304, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=243), 84, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-10-03 21:14:19.120 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 26304, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=243), 84, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-10-03 21:14:19.176 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 460, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=244), 86, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-10-03 21:14:19.176 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 460, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=244), 86, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-10-03 21:14:21.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=221), 200, -50, 0xafae, 255, 255, b'%\x00\x00']
2023-10-03 21:14:21.568 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_RETRY|APS_OPTION_ENABLE_ROUTE_DISCOVERY: 320>, groupId=0, sequence=221), 200, -50, 0xafae, 255, 255, b'%\x00\x00']
2023-10-03 21:14:21.568 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=221, profile_id=0, cluster_id=2, data=Serialized[b'%\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=200, rssi=-50)
2023-10-03 21:14:21.568 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
2023-10-03 21:14:21.569 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
2023-10-03 21:14:22.658 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-10-03 21:14:22.712 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[50, 90, 56, 49, 0, 0, 5, 5, 28, 15, 29, 26, 16, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 16, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-10-03 21:14:22.713 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-10-03 21:14:22.768 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf1']
2023-10-03 21:14:22.768 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 241
2023-10-03 21:14:22.768 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 50, MAC_TX_BROADCAST = 90, MAC_RX_UNICAST = 56, MAC_TX_UNICAST_SUCCESS = 49, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 5, APS_DATA_TX_BROADCAST = 5, APS_DATA_RX_UNICAST = 28, APS_DATA_TX_UNICAST_SUCCESS = 15, APS_DATA_TX_UNICAST_RETRY = 29, APS_DATA_TX_UNICAST_FAILED = 26, ROUTE_DISCOVERY_INITIATED = 16, NEIGHBOR_ADDED = 1, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 16, 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 = 241]
2023-10-03 21:14:23.423 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xFC86](TRADFRI Driver 30W): Attempting to checkin with device - missed checkins: 1
2023-10-03 21:14:23.424 DEBUG (MainThread) [zigpy.zcl] [0xFC86: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), tsn=87, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:23.425 DEBUG (MainThread) [zigpy.zcl] [0xFC86:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4])
2023-10-03 21:14:23.425 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=0xFC86), dst_ep=1, source_route=None, extended_timeout=False, tsn=87, profile_id=260, cluster_id=0, data=Serialized[b'\x00W\x00\x04\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:23.426 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xfc86, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=87), 88, b'\x00W\x00\x04\x00')
2023-10-03 21:14:23.480 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 246]
2023-10-03 21:14:25.088 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 64646, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=246), 88, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-10-03 21:14:25.088 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 64646, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=246), 88, <EmberStatus.DELIVERY_FAILED: 102>, b'']
2023-10-03 21:14:26.569 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:26.569 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:26.569 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) left the network
2023-10-03 21:14:26.569 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Update device availability - device available: True - new availability: False - changed: True
2023-10-03 21:14:26.569 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Device availability changed and device became unavailable
2023-10-03 21:14:26.569 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=a4:c1:38:e5:9e:72:d3:28, unique_id=a4:c1:38:e5:9e:72:d3:28, device_id=760a5c0a858881a6ca574e3a6c03fe99, device_event_type=device_offline>
2023-10-03 21:14:26.570 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:26.570 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.DEVICE_LEFT: 2>, <EmberJoinDecision.NO_ACTION: 3>, 0x6409]
2023-10-03 21:14:26.570 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) left the network
2023-10-03 21:14:26.570 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Update device availability - device available: False - new availability: False - changed: False
2023-10-03 21:14:30.328 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [1, <Bool.true: 1>, 0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-10-03 21:14:30.328 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [1, <Bool.true: 1>, 0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberNodeType.SLEEPY_END_DEVICE: 4>]
2023-10-03 21:14:30.383 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xC3A7](TY0202): Device seen - marking the device available and resetting counter
2023-10-03 21:14:30.383 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xC3A7](TY0202): Update device availability - device available: True - new availability: True - changed: False
2023-10-03 21:14:30.532 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000]
2023-10-03 21:14:30.532 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0xafae, a4:c1:38:e5:9e:72:d3:28, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000]
2023-10-03 21:14:30.532 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) joined the network
2023-10-03 21:14:30.532 DEBUG (MainThread) [bellows.ezsp.protocol] Send command findKeyTableEntry: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:30.532 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=89, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:30.533 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request: get_membership(groups=[])
2023-10-03 21:14:30.533 DEBUG (MainThread) [zigpy.device] [0xafae] Extending timeout for 0x59 request
2023-10-03 21:14:30.533 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=0xAFAE), dst_ep=1, source_route=None, extended_timeout=True, tsn=89, profile_id=260, cluster_id=4, data=Serialized[b'\x01Y\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:30.533 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:30.584 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received findKeyTableEntry: [255]
2023-10-03 21:14:30.636 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:30.636 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xafae, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=89), 90, b'\x01Y\x02\x00')
2023-10-03 21:14:30.688 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 248]
2023-10-03 21:14:31.044 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=248), 90, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:31.044 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 44974, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=248), 90, <EmberStatus.SUCCESS: 0>, b'']
2023-10-03 21:14:31.044 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=251), 168, -58, 0xafae, 255, 255, b'&\xae\xaf(\xd3r\x9e\xe58\xc1\xa4\x80']
2023-10-03 21:14:31.045 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=251), 168, -58, 0xafae, 255, 255, b'&\xae\xaf(\xd3r\x9e\xe58\xc1\xa4\x80']
2023-10-03 21:14:31.045 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), 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=251, profile_id=0, cluster_id=19, data=Serialized[b'&\xae\xaf(\xd3r\x9e\xe58\xc1\xa4\x80'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=168, rssi=-58)
2023-10-03 21:14:31.045 INFO (MainThread) [zigpy.application] Device 0xafae (a4:c1:38:e5:9e:72:d3:28) joined the network
2023-10-03 21:14:31.045 DEBUG (MainThread) [zigpy.device] [0xafae] Cancelling old group rescan
2023-10-03 21:14:31.045 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Update device availability - device available: False - new availability: True - changed: True
2023-10-03 21:14:31.045 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): Device availability changed and device became available, reinitializing cluster handlers
2023-10-03 21:14:31.045 DEBUG (MainThread) [zigpy.zdo] [0xafae:zdo] ZDO request ZDOCmd.Device_annce: [0xAFAE, a4:c1:38:e5:9e:72:d3:28, 128]
2023-10-03 21:14:31.046 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=252), 168, -58, 0xafae, 255, 255, b'\x19Y\x02\x10\x00']
2023-10-03 21:14:31.046 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=252), 168, -58, 0xafae, 255, 255, b'\x19Y\x02\x10\x00']
2023-10-03 21:14:31.046 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=252, profile_id=260, cluster_id=4, data=Serialized[b'\x19Y\x02\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=168, rssi=-58)
2023-10-03 21:14:31.046 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Received ZCL frame: b'\x19Y\x02\x10\x00'
2023-10-03 21:14:31.046 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=89, command_id=2, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:14:31.046 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame: Groups:get_membership_response(capacity=16, groups=[])
2023-10-03 21:14:31.047 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=253), 168, -58, 0xafae, 255, 255, b'\x18Y\x0b\x02\x00']
2023-10-03 21:14:31.047 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=253), 168, -58, 0xafae, 255, 255, b'\x18Y\x0b\x02\x00']
2023-10-03 21:14:31.047 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xAFAE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=253, profile_id=260, cluster_id=4, data=Serialized[b'\x18Y\x0b\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=168, rssi=-58)
2023-10-03 21:14:31.047 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Received ZCL frame: b'\x18Y\x0b\x02\x00'
2023-10-03 21:14:31.048 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=89, command_id=11, *direction=<Direction.Client_to_Server: 1>)
2023-10-03 21:14:31.048 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Decoded ZCL frame: Groups:Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2023-10-03 21:14:31.048 DEBUG (MainThread) [zigpy.device] [0xafae] Invalid state on future for 0x59 seq -- probably duplicate response
2023-10-03 21:14:31.048 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=91, command_id=2, *direction=<Direction.Server_to_Client: 0>)
2023-10-03 21:14:31.049 DEBUG (MainThread) [zigpy.zcl] [0xAFAE:1:0x0004] Sending request: get_membership(groups=[])
2023-10-03 21:14:31.049 DEBUG (MainThread) [zigpy.device] [0xafae] Extending timeout for 0x5b request
2023-10-03 21:14:31.049 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=0xAFAE), dst_ep=1, source_route=None, extended_timeout=True, tsn=91, profile_id=260, cluster_id=4, data=Serialized[b'\x01[\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2023-10-03 21:14:31.049 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:e5:9e:72:d3:28, True)
2023-10-03 21:14:31.049 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): started initialization
2023-10-03 21:14:31.049 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:ZDO](TS0601): 'async_initialize' stage succeeded
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: initializing cluster handler: from_cache: False
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: finished cluster handler initialization
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: initializing cluster handler: from_cache: False
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: finished cluster handler initialization
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: initializing cluster handler: from_cache: False
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: finished cluster handler initialization
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0000]: 'async_initialize' stage succeeded
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0xef00]: 'async_initialize' stage succeeded
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xafae:1:0x0019]: 'async_initialize' stage succeeded
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): power source: Battery or Unknown
2023-10-03 21:14:31.050 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xafae](TS0601): completed initialization
2023-10-03 21:14:31.100 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: []
2023-10-03 21:14:31.100 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xafae, EmberApsFrame(profileId=260, clusterId=4, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=91), 92, b'\x01[\x02\x00')
2023-10-03 21:14:31.152 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 249]
From your logs I see that your HA instance is evaluating a TZE200_s6hzw8g2.MmwRadarMotionGPP
class but the endpoints don't match:
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'TZE200_s6hzw8g2.MmwRadarMotionGPP'>
2023-10-03 21:13:40.663 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 242} {1}
My suggestion was to try with the MmwRadarMotion
(and not the GPP one).
Maybe you have taken the wrong one or maybe your HA instance is not updating the local quirks.
Thx for your patience @javicalle :1st_place_medal: !
I paid more attention to editing this time and now re-adding, re-configuring the device - we can really see progress (see screenshots.)
Theres occupancy and illuminance entities etc.
So I guess this can be called 'done' :+1:
Thx a lot, this was educational !
-T
Here's the quirk file I have in /homeassistant/config/zha_quirks TZE200_s6hzw8g2.zip
Are the Illuminance
and Occupancy
sensors working?
Hi, Sorry I overlooked the other part of the functionality, as the Occupancy works fine.
However, Illuminance default state is always in "unknown" - so its not working correctly.
I haven't had time to concentrate on this - but ofcourse hoping to get it working
Hi
Tried to apply this custom quirk for the exact same model, and i noticed that the occupancy sensor is the wrong way around. It will show clear when there is movement, and movement detected when there is no movement...
Hi @jonasblomq - thanks for your comment ! I haven't had time to advance this any further, however looking forward having it working.
As a side note, something I overlooked was that were not getting battery level information
Thanks for all the work done here. I got some readings out of this sensor now. However, there is still:
- no Illuminance
- Occupancy is still switched
Hi @geleeroyale did you ever get this fully functional ? Could you pls share the quirk
Sadly - no. I tried messing around with MQTT but that then broke my HA installation so I did not pursue it any further. The furthest I got with MQTT was that it recognized the device, but I could not read any useful sensor data.