Open centershock opened 2 years ago
Do you have an link to the device?
Can find it only in my AliExpress order: https://de.aliexpress.com/item/1005003518715880.html?gatewayAdapt=glo2deu&spm=a2g0o.order_detail.0.0.328363687Th9Lw
Normally, this device should work without a quirk since it uses standard clusters, did you try it? Do you have some problems with it?
The power is always 0%, and there is not update whatsoever for ias_zone. I tried with three different devices of the same model, same result.
Try if this quirk works: ts0601_magnet.zip
Unfortunately, no success and the same behavior.
The log when pairing:
[0x340B:1:0x0402] Received ZCL frame: b'\x18\x1b\n\x00\x00)\xe8\x08' [0x340B:1:0x0402] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=27, command_id=10, *is_reply=True) [0x340B:1:0x0402] Decoded ZCL frame: TemperatureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2280))]) [0x340B:1:0x0402] Received command 0x0A (TSN 27): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2280))]) [0x340B:1:0x0402] Attribute report received: measured_value=2280 [0x340B:1:0x0405] Received ZCL frame: b'\x18\x1c\n\x00\x00!T\x0f' [0x340B:1:0x0405] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=10, *is_reply=True) [0x340B:1:0x0405] Decoded ZCL frame: RelativeHumidityCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=3924))]) [0x340B:1:0x0405] Received command 0x0A (TSN 28): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=3924))]) [0x340B:1:0x0405] Attribute report received: measured_value=3924 [0x340B:1:0x0403] Received ZCL frame: b"\x18\x1d\n\x00\x00)\xfb\x03\x14\x00(\xff\x10\x00)\xd1'" [0x340B:1:0x0403] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=29, command_id=10, *is_reply=True) [0x340B:1:0x0403] Decoded ZCL frame: PressureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1019)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10193))]) [0x340B:1:0x0403] Received command 0x0A (TSN 29): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1019)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10193))]) [0x340B:1:0x0403] Attribute report received: measured_value=1019, 0x0014=-1, 0x0010=10193 Device 0x877f (a4:c1:38:4d:82:df:a6:b0) joined the network Device a4:c1:38:4d:82:df:a6:b0 changed id (0x877f => 0x877f) [0x877f] Skipping initialization, device is fully initialized Device is initialized <TuyaContactSensor model='TS0601' manuf='_TZE200_pay2byax' nwk=0x877F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True> [0x877f:zdo] ZDO request ZDOCmd.Device_annce: [0x877F, a4:c1:38:4d:82:df:a6:b0, 128] 'binary_sensor' component -> 'IASZone' using ['ias_zone'] 'sensor' component -> 'Battery' using ['power'] 'sensor' component -> 'RSSISensor' using ['basic'] 'sensor' component -> 'LQISensor' using ['basic'] device - 34687:a4:c1:38:4d:82:df:a6:b0 entering async_device_initialized - is_new_join: True device - 34687:a4:c1:38:4d:82:df:a6:b0 has joined the ZHA zigbee network [34687](TS0601): started configuration [34687:ZDO](TS0601): 'async_configure' stage succeeded [34687:1:0x0000]: finished channel configuration [0x877f] Extending timeout for 0x27 request [0x877F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=41, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False) [0x877F:1:0x0500] Sending request: Read_Attributes(attribute_ids=[1]) [0x877f] Extending timeout for 0x29 request [34687:1:0x0019]: finished channel configuration [34687:1:0x0001]: bound 'power' cluster: Status.SUCCESS [0x877F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=43, command_id=<GeneralCommand.Configure_Reporting: 6>, *is_reply=False) [0x877F:1:0x0001] Sending request: Configure_Reporting(config_records=[AttributeReportingConfig(direction=0, attrid=0x0020, datatype=32, min_interval=3600, max_interval=10800, reportable_change=1), AttributeReportingConfig(direction=0, attrid=0x0021, datatype=32, min_interval=3600, max_interval=10800, reportable_change=1)]) [0x877f] Extending timeout for 0x2b request [0x877F:1:0x0500] Received ZCL frame: b'\x18)\x01\x01\x00\x001(\x00' [0x877F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=41, command_id=1, *is_reply=True) [0x877F:1:0x0500] Decoded ZCL frame: IasZone:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0001, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum16, value=<enum16.undefined_0x0028: 40>))]) [34687:1:0x0500]: started IASZoneChannel configuration [0x877f] Extending timeout for 0x2d request [0x877F:1:0x0001] Received ZCL frame: b'\x18+\x07\x00\x00 \x00\x00\x00!\x00' [0x877F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=43, command_id=7, *is_reply=True) [0x877F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Configure_Reporting_rsp(status_records=[ConfigureReportingResponseRecord(status=0), ConfigureReportingResponseRecord(status=0)]) [34687:1:0x0001]: Successfully configured reporting for '{'battery_percentage_remaining', 'battery_voltage'}' on 'power' cluster [34687:1:0x0001]: Failed to configure reporting for '[]' on 'power' cluster: [ConfigureReportingResponseRecord(status=0), ConfigureReportingResponseRecord(status=0)] [34687:1:0x0001]: finished channel configuration [34687:1:0x0500]: bound 'ias_zone' cluster: Status.SUCCESS [0x877F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=<GeneralCommand.Write_Attributes: 2>, *is_reply=False) [0x877F:1:0x0500] Sending request: Write_Attributes(attributes=[Attribute(attrid=0x0010, value=TypeValue(type=EUI64, value=00:21:2e:ff:ff:07:79:3e))]) [0x877f] Extending timeout for 0x2f request [0x877F:1:0x0500] Received ZCL frame: b'\x18/\x04\x00' [0x877F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=4, *is_reply=True) [0x877F:1:0x0500] Decoded ZCL frame: IasZone:Write_Attributes_rsp(status_records=[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)]) [34687:1:0x0500]: wrote cie_addr: 00:21:2e:ff:ff:07:79:3e to 'ias_zone' cluster: [WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)] [34687:1:0x0500]: Sending pro-active IAS enroll response [0x877F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=49, command_id=0, *is_reply=False) [0x877F:1:0x0500] Sending request: enroll_response(enroll_response_code=<EnrollResponse.Success: 0>, zone_id=0) [34687:1:0x0500]: finished IASZoneChannel configuration [0x877f] Extending timeout for 0x31 request [34687:1:0x0000]: 'async_configure' stage succeeded [34687:1:0x0001]: 'async_configure' stage succeeded [34687:1:0x0500]: 'async_configure' stage succeeded [34687:1:0x0019]: 'async_configure' stage succeeded [34687](TS0601): completed configuration [34687](TS0601): stored in registry: ZhaDeviceEntry(name='_TZE200_pay2byax TS0601', ieee='a4:c1:38:4d:82:df:a6:b0', last_seen=1650195016.5551138) [34687](TS0601): started initialization [34687:ZDO](TS0601): 'async_initialize' stage succeeded [34687:1:0x0000]: initializing channel: from_cache: False [34687:1:0x0000]: finished channel initialization [34687:1:0x0001]: initializing channel: from_cache: False [0x877F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=51, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False) [0x877F:1:0x0001] Sending request: Read_Attributes(attribute_ids=[32, 33]) [0x877f] Extending timeout for 0x33 request [34687:1:0x0500]: initializing channel: from_cache: False [0x877F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False) [0x877F:1:0x0500] Sending request: Read_Attributes(attribute_ids=[0]) [0x877f] Extending timeout for 0x35 request [34687:1:0x0019]: initializing channel: from_cache: False [34687:1:0x0019]: finished channel initialization [0x877F:1:0x0500] Received ZCL frame: b'\x181\x0b\x00\x00' [0x877F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=49, command_id=11, *is_reply=True) [0x877F:1:0x0500] Decoded ZCL frame: IasZone:Default_Response(command_id=0, status=<Status.SUCCESS: 0>) [0x877F:1:0x0001] Received ZCL frame: b'\x183\x01 \x00\x00 \x00!\x00\x00 \x00' [0x877F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=51, command_id=1, *is_reply=True) [0x877F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0020, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0)), ReadAttributeRecord(attrid=0x0021, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0))]) [0x877F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False) [0x877F:1:0x0001] Sending request: Read_Attributes(attribute_ids=[49, 51]) [0x877f] Extending timeout for 0x37 request [0x877F:1:0x0500] Received ZCL frame: b'\x185\x01\x00\x00\x000\x00' [0x877F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=1, *is_reply=True) [0x877F:1:0x0500] Decoded ZCL frame: IasZone:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum8, value=<enum8.undefined_0x00: 0>))]) [34687:1:0x0500]: finished channel initialization Ignoring message (b'0917020024650200040000030a') on cluster 61184: unknown endpoint or cluster id: 'No cluster ID 0xef00 on (a4:c1:38:4d:82:df:a6:b0, 1)' [0x877F:1:0x0001] Received ZCL frame: b'\x187\x011\x00\x863\x00\x86' [0x877F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=1, *is_reply=True) [0x877F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0031, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>), ReadAttributeRecord(attrid=0x0033, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)]) [34687:1:0x0001]: finished channel initialization [34687:1:0x0000]: 'async_initialize' stage succeeded [34687:1:0x0001]: 'async_initialize' stage succeeded [34687:1:0x0500]: 'async_initialize' stage succeeded [34687:1:0x0019]: 'async_initialize' stage succeeded [34687](TS0601): power source: Battery or Unknown [34687](TS0601): completed initialization Ignoring message (b'0917020024650200040000030a') on cluster 61184: unknown endpoint or cluster id: 'No cluster ID 0xef00 on (a4:c1:38:4d:82:df:a6:b0, 1)' Ignoring message (b'0917020024650200040000030a') on cluster 61184: unknown endpoint or cluster id: 'No cluster ID 0xef00 on (a4:c1:38:4d:82:df:a6:b0, 1)' Ignoring message (b'091802002b0202000400000064') on cluster 61184: unknown endpoint or cluster id: 'No cluster ID 0xef00 on (a4:c1:38:4d:82:df:a6:b0, 1)' Ignoring message (b'091802002b0202000400000064') on cluster 61184: unknown endpoint or cluster id: 'No cluster ID 0xef00 on (a4:c1:38:4d:82:df:a6:b0, 1)'
Can I provide more information? Happy Easter and thanks for your help!
Thanks, also Happy Easter 😄
The information you provide is ok, mabye you can look if there is something else interesting in the logs.
It looks like the device sends data on cluster 0xEF00
even though it isn't shown in the device signature, this is also new for me 😄 .
Try this: ts0601_magnet.zip
You can also enable debug logging, maybe it shows more:
logger:
default: info
logs:
zigpy: debug
zigpy_znp: debug
zhaquirks: debug
Again no change in behavior. The log when pairing:
[0x877f]` Delivery error for seq # 0x21, on endpoint id 0 cluster 0x0034: message send failure
Sending 'zdo_leave_req' failed: [0x877f:0:0x0034]: Message send failure
[0x3808:11:0x0008] Received ZCL frame: b'\x18\x03\n\x00\x00 ;'
[0x3808:11:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=3, command_id=10, *is_reply=True)
[0x3808:11:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=59))])
[0x3808:11:0x0008] Received command 0x0A (TSN 3): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=59))])
[0x3808:11:0x0008] Attribute report received: current_level=59
[0x3808:11:0x0008]: received attribute: 0 update with value: 59
New device 0x035f (a4:c1:38:4d:82:df:a6:b0) joined the network
[0x035f] Scheduling initialization
Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 19: b'\x07_\x03\xb0\xa6\xdf\x82M8\xc1\xa4\x80'
[0x035f:zdo] ZDO request ZDOCmd.Device_annce: [0x035F, a4:c1:38:4d:82:df:a6:b0, 128]
Tries remaining: 3
[0x035f] Requesting 'Node Descriptor'
Tries remaining: 2
[0x035f] Extending timeout for 0x27 request
Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32770: b"'\x00_\x03\x02@\x80A\x11BB\x00\x00*B\x00\x00"
[0x035f] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 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)
[0x035f] Discovering endpoints
Tries remaining: 3
[0x035f] Extending timeout for 0x29 request
Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32773: b')\x00_\x03\x01\x01'
[0x035f] Discovered endpoints: [1]
[0x035f] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
[0x035f:1] Discovering endpoint information
Tries remaining: 3
[0x035f] Extending timeout for 0x2b request
Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'+\x00_\x03\x12\x01\x04\x01\x02\x04\x01\x03\x01\x00\x00\x05\x00\x00\x02\x19\x00\n\x00'
[0x035f:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=1026, device_version=1, input_clusters=[1, 1280, 0], output_clusters=[25, 10])
[0x035F:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=45, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[0x035F:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[0x035f] Extending timeout for 0x2d request
[0x035F:1:0x0000] Received ZCL frame: b'\x18-\x01\x04\x00\x00B\x10_TZE200_pay2byax\x05\x00\x00B\x06TS0601'
[0x035F:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=45, command_id=1, *is_reply=True)
[0x035F:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='_TZE200_pay2byax')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='TS0601'))])
[0x035f] Read model 'TS0601' and manufacturer '_TZE200_pay2byax' from <Endpoint id=1 in=[power:0x0001, ias_zone:0x0500, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>>
[0x035f] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_pay2byax' nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True>
Device is initialized <Device model='TS0601' manuf='_TZE200_pay2byax' nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True>
Checking quirks for _TZE200_pay2byax TS0601 (a4:c1:38:4d:82:df:a6:b0)
Considering <class 'ts0601_magnet.TuyaContactSensor'>
Found custom device replacement for a4:c1:38:4d:82:df:a6:b0: <class 'ts0601_magnet.TuyaContactSensor'>
'binary_sensor' component -> 'IASZone' using ['ias_zone']
'sensor' component -> 'Battery' using ['power']
'sensor' component -> 'RSSISensor' using ['basic']
'sensor' component -> 'LQISensor' using ['basic']
device - 0x035F:a4:c1:38:4d:82:df:a6:b0 entering async_device_initialized - is_new_join: True
device - 0x035F:a4:c1:38:4d:82:df:a6:b0 has joined the ZHA zigbee network
[0x035F](TS0601): started configuration
[0x035F:ZDO](TS0601): 'async_configure' stage succeeded
[0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[0x035F:1:0x0500] Sending request: Read_Attributes(attribute_ids=[1])
[0x035f] Extending timeout for 0x2f request
[0x035f] Extending timeout for 0x31 request
[0x035F:1:0x0000]: finished channel configuration
[0x035F:1:0x0019]: finished channel configuration
Error handling '_save_attribute' event with (a4:c1:38:4d:82:df:a6:b0, 1, 0, 4, '_TZE200_pay2byax') params: FOREIGN KEY constraint failed
Error handling '_save_attribute' event with (a4:c1:38:4d:82:df:a6:b0, 1, 0, 5, 'TS0601') params: FOREIGN KEY constraint failed
[0x035F:1:0xef00] Received ZCL frame: b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z'
[0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=55, command_id=2, *is_reply=True)
[0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0xef00] Received command 0x02 (TSN 55): set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0x0500] Received ZCL frame: b'\x18/\x01\x01\x00\x001(\x00'
[0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=1, *is_reply=True)
[0x035F:1:0x0500] Decoded ZCL frame: IasZone:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0001, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum16, value=<enum16.undefined_0x0028: 40>))])
[0x035F:1:0x0500]: started IASZoneChannel configuration
[0x035f] Extending timeout for 0x33 request
[0x035F:1:0xef00] Received ZCL frame: b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z'
[0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=55, command_id=2, *is_reply=True)
[0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0xef00] Received command 0x02 (TSN 55): set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0x0001]: bound 'power' cluster: Status.SUCCESS
[0x035F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=<GeneralCommand.Configure_Reporting: 6>, *is_reply=False)
[0x035F:1:0x0001] Sending request: Configure_Reporting(config_records=[AttributeReportingConfig(direction=0, attrid=0x0020, datatype=32, min_interval=3600, max_interval=10800, reportable_change=1), AttributeReportingConfig(direction=0, attrid=0x0021, datatype=32, min_interval=3600, max_interval=10800, reportable_change=1)])
[0x035f] Extending timeout for 0x35 request
[0x035F:1:0x0500]: bound 'ias_zone' cluster: Status.SUCCESS
[0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=<GeneralCommand.Write_Attributes: 2>, *is_reply=False)
[0x035F:1:0x0500] Sending request: Write_Attributes(attributes=[Attribute(attrid=0x0010, value=TypeValue(type=EUI64, value=00:21:2e:ff:ff:07:79:3e))])
[0x035f] Extending timeout for 0x37 request
[0x035F:1:0x0001] Received ZCL frame: b'\x185\x07\x00\x00 \x00\x00\x00!\x00'
[0x035F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=7, *is_reply=True)
[0x035F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Configure_Reporting_rsp(status_records=[ConfigureReportingResponseRecord(status=0), ConfigureReportingResponseRecord(status=0)])
[0x035F:1:0x0001]: Successfully configured reporting for '{'battery_percentage_remaining', 'battery_voltage'}' on 'power' cluster
[0x035F:1:0x0001]: Failed to configure reporting for '[]' on 'power' cluster: [ConfigureReportingResponseRecord(status=0), ConfigureReportingResponseRecord(status=0)]
[0x035F:1:0x0001]: finished channel configuration
[0x035F:1:0xef00] Received ZCL frame: b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z'
[0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=55, command_id=2, *is_reply=True)
[0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
[0x035F:1:0x0500]: wrote cie_addr: 00:21:2e:ff:ff:07:79:3e to 'ias_zone' cluster: Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122])
[0x035F:1:0x0500]: Sending pro-active IAS enroll response
[0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=0, *is_reply=False)
[0x035F:1:0x0500] Sending request: enroll_response(enroll_response_code=<EnrollResponse.Success: 0>, zone_id=0)
[0x035F:1:0x0500]: finished IASZoneChannel configuration
[0x035f] Extending timeout for 0x39 request
[0x035F:1:0x0500]: 'async_configure' stage succeeded
[0x035F:1:0x0001]: 'async_configure' stage succeeded
[0x035F:1:0x0000]: 'async_configure' stage succeeded
[0x035F:1:0x0019]: 'async_configure' stage succeeded
[0x035F](TS0601): completed configuration
[0x035F](TS0601): stored in registry: ZhaDeviceEntry(name='_TZE200_pay2byax TS0601', ieee='a4:c1:38:4d:82:df:a6:b0', last_seen=1650220280.3852458)
[0x035F](TS0601): started initialization
[0x035F:ZDO](TS0601): 'async_initialize' stage succeeded
[0x035F:1:0x0500]: initializing channel: from_cache: False
[0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=59, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[0x035F:1:0x0500] Sending request: Read_Attributes(attribute_ids=[0])
[0x035f] Extending timeout for 0x3b request
[0x035F:1:0x0001]: initializing channel: from_cache: False
[0x035F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=61, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[0x035F:1:0x0001] Sending request: Read_Attributes(attribute_ids=[32, 33])
[0x035f] Extending timeout for 0x3d request
[0x035F:1:0x0000]: initializing channel: from_cache: False
[0x035F:1:0x0000]: finished channel initialization
[0x035F:1:0x0019]: initializing channel: from_cache: False
[0x035F:1:0x0019]: finished channel initialization
[0x035F:1:0x0500] Received ZCL frame: b'\x187\x04\x00'
[0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=4, *is_reply=True)
[0x035F:1:0x0500] Decoded ZCL frame: IasZone:Write_Attributes_rsp(status_records=[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)])
[0x035F:1:0x0500] Received command 0x04 (TSN 55): Write_Attributes_rsp(status_records=[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)])
[0x035F:1:0x0500] Received ZCL frame: b'\x189\x0b\x00\x00'
[0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=57, command_id=11, *is_reply=True)
[0x035F:1:0x0500] Decoded ZCL frame: IasZone:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
[0x035F:1:0x0500] Received ZCL frame: b'\x18;\x01\x00\x00\x000\x00'
[0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=59, command_id=1, *is_reply=True)
[0x035F:1:0x0500] Decoded ZCL frame: IasZone:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum8, value=<enum8.undefined_0x00: 0>))])
[0x035F:1:0x0500]: finished channel initialization
[0x035F:1:0x0001] Received ZCL frame: b'\x18=\x01 \x00\x00 \x00!\x00\x00 \x00'
[0x035F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=61, command_id=1, *is_reply=True)
[0x035F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0020, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0)), ReadAttributeRecord(attrid=0x0021, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0))])
[0x035F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=63, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[0x035F:1:0x0001] Sending request: Read_Attributes(attribute_ids=[49, 51])
[0x035f] Extending timeout for 0x3f request
[0x035F:1:0x0001] Received ZCL frame: b'\x18?\x011\x00\x863\x00\x86'
[0x035F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=63, command_id=1, *is_reply=True)
[0x035F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0031, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>), ReadAttributeRecord(attrid=0x0033, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)])
[0x035F:1:0x0001]: finished channel initialization
[0x035F:1:0x0500]: 'async_initialize' stage succeeded
[0x035F:1:0x0001]: 'async_initialize' stage succeeded
[0x035F:1:0x0000]: 'async_initialize' stage succeeded
[0x035F:1:0x0019]: 'async_initialize' stage succeeded
[0x035F](TS0601): power source: Battery or Unknown
[0x035F](TS0601): completed initialization
[0x035F:1:0x000a] Received ZCL frame: b'\x008\x00\x07\x00'
[0x035F:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=0, *is_reply=False)
[0x035F:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[7])
[0x035F:1:0x000a] Received command 0x00 (TSN 56): Read_Attributes(attribute_ids=[7])
[0x035F:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *is_reply=True)
[0x035F:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=LocalTime, value=703542683))])
[0x035F:1:0xef00] Received ZCL frame: b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
[0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *is_reply=True)
[0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] Received command 0x02 (TSN 57): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] Received ZCL frame: b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
[0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *is_reply=True)
[0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] Received command 0x02 (TSN 57): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x67EF:1:0x0402] Received ZCL frame: b'\x18\xab\n\x00\x00)\x95\x08'
[0x67EF:1:0x0402] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=171, command_id=10, *is_reply=True)
[0x67EF:1:0x0402] Decoded ZCL frame: TemperatureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2197))])
[0x67EF:1:0x0402] Received command 0x0A (TSN 171): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2197))])
[0x67EF:1:0x0402] Attribute report received: measured_value=2197
[0x67EF:1:0x0405] Received ZCL frame: b'\x18\xac\n\x00\x00!T\x0b'
[0x67EF:1:0x0405] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=172, command_id=10, *is_reply=True)
[0x67EF:1:0x0405] Decoded ZCL frame: RelativeHumidityCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=2900))])
[0x67EF:1:0x0405] Received command 0x0A (TSN 172): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=2900))])
[0x67EF:1:0x0405] Attribute report received: measured_value=2900
[0x67EF:1:0x0403] Received ZCL frame: b"\x18\xad\n\x00\x00)\xf6\x03\x14\x00(\xff\x10\x00)\xa1'"
[0x67EF:1:0x0403] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=173, command_id=10, *is_reply=True)
[0x67EF:1:0x0403] Decoded ZCL frame: PressureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1014)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10145))])
[0x67EF:1:0x0403] Received command 0x0A (TSN 173): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1014)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10145))])
[0x67EF:1:0x0403] Attribute report received: measured_value=1014, 0x0014=-1, 0x0010=10145
[0x035F:1:0xef00] Received ZCL frame: b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
[0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *is_reply=True)
[0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] Received command 0x02 (TSN 57): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
[0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
Also the log from Home Assistant with your advised settings. In this log the pairing can be found and my tries to force an update for ias_zone with moving the magnets of the sensor:
2022-04-17 20:31:03 INFO (MainThread) [zigpy.application] Removing device 0x877f (a4:c1:38:4d:82:df:a6:b0)
2022-04-17 20:31:03 DEBUG (MainThread) [zigpy.application] Sending leave request for a4:c1:38:4d:82:df:a6:b0 to 00:21:2e:ff:ff:07:79:3e parent
2022-04-17 20:31:03 DEBUG (MainThread) [zigpy.application] Sending leave request for a4:c1:38:4d:82:df:a6:b0 to 00:17:88:01:04:7a:5e:80 parent
2022-04-17 20:31:03 DEBUG (MainThread) [zigpy.device] [0x877f] Extending timeout for 0x21 request
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee broadcast with tsn 37 under 38 request id, data: b'253c00'
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 38, 0, <DeconzAddressEndpoint address_mode=2 address=65532 endpoint=0>, 0, 54, 0, b'%<\x00', 2, 0)
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x125c0019001200260002fcff0000003600000300253c000200
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x125c00090002002226
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 38]
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e5d000700a600
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x045d0007000000
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x045d0013000c00222602fcff00000000000000
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 38: 00
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x26 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xfffc endpoint=0>, status: 0x00
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<')
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b5e0009000200213c
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b5e000800010021
2022-04-17 20:31:04 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e5f000700a600
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x045f0007000000
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x045f0013000c002224027f870000f000000000
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 36: f0
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x24 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x877f endpoint=0>, status: 0xf0
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 36 req id frame: TXStatus.MAC_TRANSACTION_EXPIRED
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy.device] [0x877f] Delivery error for seq # 0x21, on endpoint id 0 cluster 0x0034: message send failure
2022-04-17 20:31:10 DEBUG (MainThread) [zigpy.application] Sending 'zdo_leave_req' failed: [0x877f:0:0x0034]: Message send failure
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e60000700aa00
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1760000800010001
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17600025001e0022020000010208380b04010800070018030a0000203b00af87ff101b00b3
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3808>, 11, 260, 8, b'\x18\x03\n\x00\x00 ;', 0, 175, 135, 255, 16, 27, 0, -77]
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy.zcl] [0x3808:11:0x0008] Received ZCL frame: b'\x18\x03\n\x00\x00 ;'
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy.zcl] [0x3808:11:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=3, command_id=10, *is_reply=True)
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy.zcl] [0x3808:11:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=59))])
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy.zcl] [0x3808:11:0x0008] Received command 0x0A (TSN 3): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=59))])
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy.zcl] [0x3808:11:0x0008] Attribute report received: current_level=59
2022-04-17 20:31:12 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x3808:11:0x0008]: received attribute: 0 update with value: 59
2022-04-17 20:31:12 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x3808>, ep: 11, profile: 0x0104, cluster_id: 0x0008, data: b'18030a0000203b'
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e61000700aa00
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1761000800010001
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1761002a0023002202fdff00025f0300000013000c00075f03b0a6df824d38c1a48000afff7b2b0020c2
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 0, 0, 19, b'\x07_\x03\xb0\xa6\xdf\x82M8\xc1\xa4\x80', 0, 175, 255, 123, 43, 0, 32, -62]
2022-04-17 20:31:16 INFO (MainThread) [zigpy_deconz.zigbee.application] New device joined: 0x035f, a4:c1:38:4d:82:df:a6:b0
2022-04-17 20:31:16 INFO (MainThread) [zigpy.application] New device 0x035f (a4:c1:38:4d:82:df:a6:b0) joined the network
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.device] [0x035f] Scheduling initialization
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 19: b'\x07_\x03\xb0\xa6\xdf\x82M8\xc1\xa4\x80'
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.zdo] [0x035f:zdo] ZDO request ZDOCmd.Device_annce: [0x035F, a4:c1:38:4d:82:df:a6:b0, 128]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-17 20:31:16 INFO (MainThread) [zigpy.device] [0x035f] Requesting 'Node Descriptor'
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.util] Tries remaining: 2
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x27 request
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 39 under 40 request id, data: b'275f03'
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 0, profile: 0x0000, cluster_id: 0x0013, data: b'075f03b0a6df824d38c1a480'
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 40, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b"'_\x03", 2, 0)
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x126200190012002800025f030000000200000300275f030200
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x126200090002002228
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 40]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e63000700a600
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04630007000000
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04630013000c002228025f0300000000000000
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 40: 00
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x28 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=0>, status: 0x00
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e64000700aa00
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1764000800010001
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1764002f0028002202000000025f030000000280110027005f030240804111424200002a42000000aff700000000c1
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [40, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 0, 0, 32770, b"'\x00_\x03\x02@\x80A\x11BB\x00\x00*B\x00\x00", 0, 175, 247, 0, 0, 0, 0, -63]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32770: b"'\x00_\x03\x02@\x80A\x11BB\x00\x00*B\x00\x00"
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 0, profile: 0x0000, cluster_id: 0x8002, data: b'27005f030240804111424200002a420000'
2022-04-17 20:31:16 INFO (MainThread) [zigpy.device] [0x035f] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 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)
2022-04-17 20:31:16 INFO (MainThread) [zigpy.device] [0x035f] Discovering endpoints
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x29 request
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 41 under 42 request id, data: b'295f03'
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 42, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=0>, 0, <ZDOCmd.Active_EP_req: 0x0005>, 0, b')_\x03', 2, 0)
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x126500190012002a00025f030000000500000300295f030200
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12650009000200222a
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 42]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e66000700a600
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04660007000000
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04660013000c00222a025f0300000000000000
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 42: 00
2022-04-17 20:31:16 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x2a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=0>, status: 0x00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e67000700aa00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1767000800010001
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17670024001d002202000000025f030000000580060029005f03010100aff700000000c1
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [29, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 0, 0, 32773, b')\x00_\x03\x01\x01', 0, 175, 247, 0, 0, 0, 0, -63]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32773: b')\x00_\x03\x01\x01'
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 0, profile: 0x0000, cluster_id: 0x8005, data: b'29005f030101'
2022-04-17 20:31:17 INFO (MainThread) [zigpy.device] [0x035f] Discovered endpoints: [1]
2022-04-17 20:31:17 INFO (MainThread) [zigpy.device] [0x035f] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
2022-04-17 20:31:17 INFO (MainThread) [zigpy.endpoint] [0x035f:1] Discovering endpoint information
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x2b request
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 43 under 44 request id, data: b'2b5f0301'
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (19, 44, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=0>, 0, <ZDOCmd.Simple_Desc_req: 0x0004>, 0, b'+_\x03\x01', 2, 0)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1268001a0013002c00025f0300000004000004002b5f03010200
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12680009000200222c
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 44]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e69000700a600
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04690007000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04690013000c00222c025f0300000000000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 44: 00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x2c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=0>, status: 0x00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e6a000700aa00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x176a000800010001
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x176a0035002e002202000000025f03000000048017002b005f0312010401020401030100000500000219000a0000aff757171b00c1
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [46, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 0, 0, 32772, b'+\x00_\x03\x12\x01\x04\x01\x02\x04\x01\x03\x01\x00\x00\x05\x00\x00\x02\x19\x00\n\x00', 0, 175, 247, 87, 23, 27, 0, -63]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'+\x00_\x03\x12\x01\x04\x01\x02\x04\x01\x03\x01\x00\x00\x05\x00\x00\x02\x19\x00\n\x00'
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 0, profile: 0x0000, cluster_id: 0x8004, data: b'2b005f0312010401020401030100000500000219000a00'
2022-04-17 20:31:17 INFO (MainThread) [zigpy.endpoint] [0x035f:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=1026, device_version=1, input_clusters=[1, 1280, 0], output_clusters=[25, 10])
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=45, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x2d request
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 45 under 46 request id, data: b'002d0004000500'
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (22, 46, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 0, 1, b'\x00-\x00\x04\x00\x05\x00', 2, 0)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x126b001d0016002e00025f030104010000010700002d00040005000200
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x126b0009000200222e
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 46]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e6c000700a600
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x046c0007000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x046c0013000c00222e025f0301010000000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 46: 00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x2e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e6d000700aa00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x176d000800010001
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x176d0041003a002202000001025f0301040100002300182d0104000042105f545a453230305f7061793262796178050000420654533036303100aff757171b00c1
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [58, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 0, b'\x18-\x01\x04\x00\x00B\x10_TZE200_pay2byax\x05\x00\x00B\x06TS0601', 0, 175, 247, 87, 23, 27, 0, -63]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0000] Received ZCL frame: b'\x18-\x01\x04\x00\x00B\x10_TZE200_pay2byax\x05\x00\x00B\x06TS0601'
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=45, command_id=1, *is_reply=True)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='_TZE200_pay2byax')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='TS0601'))])
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'182d0104000042105f545a453230305f70617932627961780500004206545330363031'
2022-04-17 20:31:17 INFO (MainThread) [zigpy.device] [0x035f] Read model 'TS0601' and manufacturer '_TZE200_pay2byax' from <Endpoint id=1 in=[power:0x0001, ias_zone:0x0500, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>>
2022-04-17 20:31:17 INFO (MainThread) [zigpy.device] [0x035f] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_pay2byax' nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True>
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='TS0601' manuf='_TZE200_pay2byax' nwk=0x035F ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True>
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for _TZE200_pay2byax TS0601 (a4:c1:38:4d:82:df:a6:b0)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'ts0601_magnet.TuyaContactSensor'>
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for a4:c1:38:4d:82:df:a6:b0: <class 'ts0601_magnet.TuyaContactSensor'>
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'binary_sensor' component -> 'IASZone' using ['ias_zone']
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'Battery' using ['power']
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'RSSISensor' using ['basic']
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'LQISensor' using ['basic']
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x035F:a4:c1:38:4d:82:df:a6:b0 entering async_device_initialized - is_new_join: True
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x035F:a4:c1:38:4d:82:df:a6:b0 has joined the ZHA zigbee network
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x035F](TS0601): started configuration
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:ZDO](TS0601): 'async_configure' stage succeeded
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request: Read_Attributes(attribute_ids=[1])
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x2f request
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 47 under 48 request id, data: b'002f000100'
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 48, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1280, 1, b'\x00/\x00\x01\x00', 2, 0)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x126e001b0014003000025f030104010005010500002f0001000200
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x31 request
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 49 under 50 request id, data: b'31b0a6df824d38c1a4010100033e7907ffff2e210001'
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0000]: finished channel configuration
2022-04-17 20:31:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0019]: finished channel configuration
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:4d:82:df:a6:b0, 1, 0, 4, '_TZE200_pay2byax') params: FOREIGN KEY constraint failed
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x126e00090002002230
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 48]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e6f000700a600
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:4d:82:df:a6:b0, 1, 0, 5, 'TS0601') params: FOREIGN KEY constraint failed
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (37, 50, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'1\xb0\xa6\xdf\x82M8\xc1\xa4\x01\x01\x00\x03>y\x07\xff\xff.!\x00\x01', 2, 0)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x126f002c0025003200025f03000000210000160031b0a6df824d38c1a4010100033e7907ffff2e2100010200
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x126f00090002002632
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 50]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04700007000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04700013000c002230025f0301010000000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 48: 00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x30 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e71000700a600
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04710007000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04710013000c002232025f0300000000000000
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 50: 00
2022-04-17 20:31:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x32 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=0>, status: 0x00
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e72000700aa00
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1772000800010001
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1772002b0024002202000001025f0301040100ef0d000937020039650200040000027a00aff760171b00c1
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z'
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=55, command_id=2, *is_reply=True)
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 55): set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'0937020039650200040000027a'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e73000700aa00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1773000800010001
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x177300270020002202000001025f0301040100050900182f0101000031280000aff760171b00c1
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1280, b'\x18/\x01\x01\x00\x001(\x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Received ZCL frame: b'\x18/\x01\x01\x00\x001(\x00'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=47, command_id=1, *is_reply=True)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame: IasZone:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0001, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum16, value=<enum16.undefined_0x0028: 40>))])
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0500, data: b'182f01010000312800'
2022-04-17 20:31:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: started IASZoneChannel configuration
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x33 request
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 51 under 52 request id, data: b'33b0a6df824d38c1a4010005033e7907ffff2e210001'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (37, 52, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'3\xb0\xa6\xdf\x82M8\xc1\xa4\x01\x00\x05\x03>y\x07\xff\xff.!\x00\x01', 2, 0)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1274002c0025003400025f03000000210000160033b0a6df824d38c1a4010005033e7907ffff2e2100010200
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x127400090002002234
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 52]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e75000700a600
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04750007000000
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04750013000c002234025f0300000000000000
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 52: 00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x34 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=0>, status: 0x00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e76000700aa00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1776000800010001
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1776002b0024002202000001025f0301040100ef0d000937020039650200040000027a00aff760171b00c1
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=55, command_id=2, *is_reply=True)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 55): set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'0937020039650200040000027a'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e77000700aa00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1777000800010001
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x177700200019002202000000025f0300000021800200310000aff760171b00c1
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 0, 0, 32801, b'1\x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'3100'
2022-04-17 20:31:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: bound 'power' cluster: Status.SUCCESS
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=<GeneralCommand.Configure_Reporting: 6>, *is_reply=False)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Sending request: Configure_Reporting(config_records=[AttributeReportingConfig(direction=0, attrid=0x0020, datatype=32, min_interval=3600, max_interval=10800, reportable_change=1), AttributeReportingConfig(direction=0, attrid=0x0021, datatype=32, min_interval=3600, max_interval=10800, reportable_change=1)])
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x35 request
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 53 under 54 request id, data: b'00350600200020100e302a0100210020100e302a01'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (36, 54, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1, 1, b'\x005\x06\x00 \x00 \x10\x0e0*\x01\x00!\x00 \x10\x0e0*\x01', 2, 0)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1278002b0024003600025f03010401010001150000350600200020100e302a0100210020100e302a010200
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x127800090002002236
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 54]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e79000700a600
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04790007000000
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04790013000c002236025f0301010000000000
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 54: 00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x36 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e7a000700aa00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x177a000800010001
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x177a00200019002202000000025f0300000021800200330000aff760171b00c1
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 0, 0, 32801, b'3\x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'3300'
2022-04-17 20:31:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: bound 'ias_zone' cluster: Status.SUCCESS
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=<GeneralCommand.Write_Attributes: 2>, *is_reply=False)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request: Write_Attributes(attributes=[Attribute(attrid=0x0010, value=TypeValue(type=EUI64, value=00:21:2e:ff:ff:07:79:3e))])
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x37 request
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 55 under 56 request id, data: b'0037021000f03e7907ffff2e2100'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (29, 56, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1280, 1, b'\x007\x02\x10\x00\xf0>y\x07\xff\xff.!\x00', 2, 0)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x127b0024001d003800025f030104010005010e000037021000f03e7907ffff2e21000200
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x127b00090002002238
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 56]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e7c000700a600
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x047c0007000000
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x047c0013000c002238025f0301010000000000
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 56: 00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x38 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e7d000700aa00
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x177d000800010001
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x177d00290022002202000001025f0301040101000b00183507000020000000210000aff760171b00c1
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [34, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1, b'\x185\x07\x00\x00 \x00\x00\x00!\x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Received ZCL frame: b'\x185\x07\x00\x00 \x00\x00\x00!\x00'
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=7, *is_reply=True)
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Configure_Reporting_rsp(status_records=[ConfigureReportingResponseRecord(status=0), ConfigureReportingResponseRecord(status=0)])
2022-04-17 20:31:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0001, data: b'1835070000200000002100'
2022-04-17 20:31:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: Successfully configured reporting for '{'battery_percentage_remaining', 'battery_voltage'}' on 'power' cluster
2022-04-17 20:31:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: Failed to configure reporting for '[]' on 'power' cluster: [ConfigureReportingResponseRecord(status=0), ConfigureReportingResponseRecord(status=0)]
2022-04-17 20:31:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: finished channel configuration
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e7e000700aa00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x177e000800010001
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x177e002b0024002202000001025f0301040100ef0d000937020039650200040000027a00aff760171b00c1
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t7\x02\x009e\x02\x00\x04\x00\x00\x02z'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=55, command_id=2, *is_reply=True)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122]))
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'0937020039650200040000027a'
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: wrote cie_addr: 00:21:2e:ff:ff:07:79:3e to 'ias_zone' cluster: Command(status=0, tsn=57, command_id=613, function=0, data=[4, 0, 0, 2, 122])
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: Sending pro-active IAS enroll response
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=0, *is_reply=False)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request: enroll_response(enroll_response_code=<EnrollResponse.Success: 0>, zone_id=0)
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: finished IASZoneChannel configuration
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x39 request
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 57 under 58 request id, data: b'0139000000'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 58, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1280, 1, b'\x019\x00\x00\x00', 2, 0)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x127f001b0014003a00025f03010401000501050001390000000200
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: 'async_configure' stage succeeded
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: 'async_configure' stage succeeded
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0000]: 'async_configure' stage succeeded
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0019]: 'async_configure' stage succeeded
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x035F](TS0601): completed configuration
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x035F](TS0601): stored in registry: ZhaDeviceEntry(name='_TZE200_pay2byax TS0601', ieee='a4:c1:38:4d:82:df:a6:b0', last_seen=1650220280.3852458)
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x035F](TS0601): started initialization
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:ZDO](TS0601): 'async_initialize' stage succeeded
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x127f0009000200223a
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 58]
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: initializing channel: from_cache: False
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=59, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Sending request: Read_Attributes(attribute_ids=[0])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x3b request
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 59 under 60 request id, data: b'003b000000'
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: initializing channel: from_cache: False
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=61, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Sending request: Read_Attributes(attribute_ids=[32, 33])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x3d request
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 61 under 62 request id, data: b'003d0020002100'
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0000]: initializing channel: from_cache: False
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0000]: finished channel initialization
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0019]: initializing channel: from_cache: False
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0019]: finished channel initialization
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 60, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1280, 1, b'\x00;\x00\x00\x00', 2, 0)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1280001b0014003c00025f030104010005010500003b0000000200
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e80000700a600
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12800009000200263c
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 60]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (22, 62, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1, 1, b'\x00=\x00 \x00!\x00', 2, 0)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1281001d0016003e00025f030104010100010700003d00200021000200
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e81000700a600
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12810009000200263e
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 62]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04820007000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04820013000c00263a025f0301010000000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 58: 00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x3a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04830007000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e83000700a600
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04830013000c00263c025f0301010000000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 60: 00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x3c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04840007000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04840013000c00223e025f0301010000000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 62: 00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x3e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04850007000000
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0485050800010022
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e86000700aa00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1786000800010001
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17860022001b002202000001025f03010401000504001837040000afff60171b00c2
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [27, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1280, b'\x187\x04\x00', 0, 175, 255, 96, 23, 27, 0, -62]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Received ZCL frame: b'\x187\x04\x00'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=4, *is_reply=True)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame: IasZone:Write_Attributes_rsp(status_records=[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Received command 0x04 (TSN 55): Write_Attributes_rsp(status_records=[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0500, data: b'18370400'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e87000700aa00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1787000800010001
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17870023001c002202000001025f030104010005050018390b000000aff760171b00c1
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1280, b'\x189\x0b\x00\x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Received ZCL frame: b'\x189\x0b\x00\x00'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=57, command_id=11, *is_reply=True)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame: IasZone:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0500, data: b'18390b0000'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e88000700aa00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1788000800010001
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17880026001f002202000001025f0301040100050800183b01000000300000afff60171b00c2
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1280, b'\x18;\x01\x00\x00\x000\x00', 0, 175, 255, 96, 23, 27, 0, -62]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Received ZCL frame: b'\x18;\x01\x00\x00\x000\x00'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=59, command_id=1, *is_reply=True)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0500] Decoded ZCL frame: IasZone:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=enum8, value=<enum8.undefined_0x00: 0>))])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0500, data: b'183b010000003000'
2022-04-17 20:31:20 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: finished channel initialization
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e89000700aa00
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1789000800010001
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1789002b0024002202000001025f0301040101000d00183d012000002000210000200000aff760171b00c1
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1, b'\x18=\x01 \x00\x00 \x00!\x00\x00 \x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Received ZCL frame: b'\x18=\x01 \x00\x00 \x00!\x00\x00 \x00'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=61, command_id=1, *is_reply=True)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0020, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0)), ReadAttributeRecord(attrid=0x0021, status=<Status.SUCCESS: 0>, value=TypeValue(type=uint8_t, value=0))])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0001, data: b'183d0120000020002100002000'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=63, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Sending request: Read_Attributes(attribute_ids=[49, 51])
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy.device] [0x035f] Extending timeout for 0x3f request
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 63 under 64 request id, data: b'003f0031003300'
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (22, 64, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 1, 1, b'\x00?\x001\x003\x00', 2, 0)
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x128a001d0016004000025f030104010100010700003f00310033000200
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x128a00090002002240
2022-04-17 20:31:20 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 64]
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e8b000700a600
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x048b0007000000
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x048b0013000c002240025f0301010000000000
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 64: 00
2022-04-17 20:31:21 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x40 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e8c000700aa00
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x178c000800010001
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x178c00270020002202000001025f0301040101000900183f0131008633008600aff760171b00c1
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [32, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 1, b'\x18?\x011\x00\x863\x00\x86', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Received ZCL frame: b'\x18?\x011\x00\x863\x00\x86'
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=63, command_id=1, *is_reply=True)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x0001] Decoded ZCL frame: PowerConfiguration:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0031, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>), ReadAttributeRecord(attrid=0x0033, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)])
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x0001, data: b'183f01310086330086'
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: finished channel initialization
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0500]: 'async_initialize' stage succeeded
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0001]: 'async_initialize' stage succeeded
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0000]: 'async_initialize' stage succeeded
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x035F:1:0x0019]: 'async_initialize' stage succeeded
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x035F](TS0601): power source: Battery or Unknown
2022-04-17 20:31:23 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x035F](TS0601): completed initialization
2022-04-17 20:31:23 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_pay2byax_ts0601_b0a6df82_power
2022-04-17 20:31:23 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_pay2byax_ts0601_b0a6df82_basic_rssi
2022-04-17 20:31:23 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_pay2byax_ts0601_b0a6df82_basic_lqi
2022-04-17 20:31:23 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new binary_sensor.zha entity: binary_sensor.tze200_pay2byax_ts0601_b0a6df82_ias_zone
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e8d000700aa00
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x178d000800010001
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x178d0023001c002202000001025f030104010a000500003800070000aff760171b00c1
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 10, b'\x008\x00\x07\x00', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x000a] Received ZCL frame: b'\x008\x00\x07\x00'
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=0, *is_reply=False)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[7])
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x000a] Received command 0x00 (TSN 56): Read_Attributes(attribute_ids=[7])
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *is_reply=True)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=LocalTime, value=703542683))])
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 56 under 65 request id, data: b'183801070000239b35ef29'
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0x000a, data: b'0038000700'
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (26, 65, 0, <DeconzAddressEndpoint address_mode=2 address=0x035F endpoint=1>, 260, 10, 1, b'\x188\x01\x07\x00\x00#\x9b5\xef)', 2, 0)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x128e0021001a004100025f030104010a00010b00183801070000239b35ef290200
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x128e00090002002241
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 65]
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e8f000700a600
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x048f0007000000
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x048f0013000c002241025f0301010000000000
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 65: 00
2022-04-17 20:31:23 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x41 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x035f endpoint=1>, status: 0x00
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e90000700aa00
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1790000800010001
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1790002b0024002202000001025f0301040100ef0d000939020040020200040000006400aff760171b00c1
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *is_reply=True)
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 57): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:24 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'09390200400202000400000064'
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e91000700aa00
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1791000800010001
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1791002b0024002202000001025f0301040100ef0d000939020040020200040000006400aff760171b00c1
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 247, 96, 23, 27, 0, -63]
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *is_reply=True)
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 57): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:25 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'09390200400202000400000064'
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e92000700aa00
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1792000800010001
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e93000700aa00
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17920026001f002a0200000102ef670104010204080018ab0a000029950800af7f60171b00b2
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x67ef>, 1, 260, 1026, b'\x18\xab\n\x00\x00)\x95\x08', 0, 175, 127, 96, 23, 27, 0, -78]
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0402] Received ZCL frame: b'\x18\xab\n\x00\x00)\x95\x08'
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0402] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=171, command_id=10, *is_reply=True)
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0402] Decoded ZCL frame: TemperatureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2197))])
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0402] Received command 0x0A (TSN 171): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2197))])
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0402] Attribute report received: measured_value=2197
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e93000700aa00
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x67ef>, ep: 1, profile: 0x0104, cluster_id: 0x0402, data: b'18ab0a0000299508'
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1793000800010001
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17930026001f002a0200000102ef670104010504080018ac0a000021540b00af7f60171b00b2
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x67ef>, 1, 260, 1029, b'\x18\xac\n\x00\x00!T\x0b', 0, 175, 127, 96, 23, 27, 0, -78]
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0405] Received ZCL frame: b'\x18\xac\n\x00\x00!T\x0b'
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0405] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=172, command_id=10, *is_reply=True)
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0405] Decoded ZCL frame: RelativeHumidityCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=2900))])
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0405] Received command 0x0A (TSN 172): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=2900))])
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0405] Attribute report received: measured_value=2900
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x67ef>, ep: 1, profile: 0x0104, cluster_id: 0x0405, data: b'18ac0a000021540b'
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1794000800010001
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1794002f002800220200000102ef670104010304110018ad0a000029f603140028ff100029a12700af7f60171b00b2
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [40, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x67ef>, 1, 260, 1027, b"\x18\xad\n\x00\x00)\xf6\x03\x14\x00(\xff\x10\x00)\xa1'", 0, 175, 127, 96, 23, 27, 0, -78]
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0403] Received ZCL frame: b"\x18\xad\n\x00\x00)\xf6\x03\x14\x00(\xff\x10\x00)\xa1'"
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0403] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=173, command_id=10, *is_reply=True)
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0403] Decoded ZCL frame: PressureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1014)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10145))])
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0403] Received command 0x0A (TSN 173): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1014)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10145))])
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy.zcl] [0x67EF:1:0x0403] Attribute report received: measured_value=1014, 0x0014=-1, 0x0010=10145
2022-04-17 20:31:26 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x67ef>, ep: 1, profile: 0x0104, cluster_id: 0x0403, data: b'18ad0a000029f603140028ff100029a127'
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e95000700aa00
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1795000800010001
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1795002b0024002202000001025f0301040100ef0d000939020040020200040000006400aff761171b00c1
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 247, 97, 23, 27, 0, -63]
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t9\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=57, command_id=2, *is_reply=True)
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 57): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:27 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'09390200400202000400000064'
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e96000700aa00
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1796000800010001
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1796002b0024002202000001025f0301040100ef0d00093a020040020200040000006400afff70171b00c2
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t:\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 255, 112, 23, 27, 0, -62]
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t:\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=58, command_id=2, *is_reply=True)
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 58): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:38 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'093a0200400202000400000064'
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e97000700aa00
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1797000800010001
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1797002b0024002202000001025f0301040100ef0d00093a020040020200040000006400afff70171b00c2
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t:\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 255, 112, 23, 27, 0, -62]
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t:\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=58, command_id=2, *is_reply=True)
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 58): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:39 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'093a0200400202000400000064'
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e98000700aa00
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1798000800010001
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1798002b0024002202000001025f0301040100ef0d00093a020040020200040000006400afff70171b00c2
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t:\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 255, 112, 23, 27, 0, -62]
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t:\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=58, command_id=2, *is_reply=True)
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 58): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:40 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'093a0200400202000400000064'
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e99000700aa00
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x1799000800010001
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1799002b0024002202000001025f0301040100ef0d00093b020040020200040000006400afff70171b00c2
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t;\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 255, 112, 23, 27, 0, -62]
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t;\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=59, command_id=2, *is_reply=True)
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 59): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:44 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'093b0200400202000400000064'
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e9a000700aa00
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x179a000800010001
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x179a002b0024002202000001025f0301040100ef0d00093b020040020200040000006400afff71171b00c2
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t;\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 255, 113, 23, 27, 0, -62]
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t;\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=59, command_id=2, *is_reply=True)
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 59): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:45 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'093b0200400202000400000064'
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e9b000700aa00
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x179b000800010001
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x179b002b0024002202000001025f0301040100ef0d00093b020040020200040000006400afff71171b00c2
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, 1, 260, 61184, b'\t;\x02\x00@\x02\x02\x00\x04\x00\x00\x00d', 0, 175, 255, 113, 23, 27, 0, -62]
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t;\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=59, command_id=2, *is_reply=True)
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 59): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:47 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x035f>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'093b0200400202000400000064'
2022-04-17 20:31:56 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t<\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:56 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=60, command_id=2, *is_reply=True)
2022-04-17 20:31:56 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:56 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 60): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:56 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:57 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t<\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:57 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=60, command_id=2, *is_reply=True)
2022-04-17 20:31:57 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:57 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 60): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:57 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:58 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t<\x02\x00@\x02\x02\x00\x04\x00\x00\x00d'
2022-04-17 20:31:58 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=60, command_id=2, *is_reply=True)
2022-04-17 20:31:58 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:58 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 60): set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:31:58 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=64, command_id=514, function=0, data=[4, 0, 0, 0, 100]))
2022-04-17 20:32:01 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t=\x02\x00G\x01\x01\x00\x01\x01'
2022-04-17 20:32:01 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=61, command_id=2, *is_reply=True)
2022-04-17 20:32:01 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:01 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 61): set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:01 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:02 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t=\x02\x00G\x01\x01\x00\x01\x01'
2022-04-17 20:32:02 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=61, command_id=2, *is_reply=True)
2022-04-17 20:32:02 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:02 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 61): set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:02 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:04 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t=\x02\x00G\x01\x01\x00\x01\x01'
2022-04-17 20:32:04 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=61, command_id=2, *is_reply=True)
2022-04-17 20:32:04 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:04 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 61): set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:04 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=71, command_id=257, function=0, data=[1, 1]))
2022-04-17 20:32:05 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t>\x02\x00N\x01\x01\x00\x01\x00'
2022-04-17 20:32:05 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=62, command_id=2, *is_reply=True)
2022-04-17 20:32:05 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:05 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 62): set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:05 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:06 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t>\x02\x00N\x01\x01\x00\x01\x00'
2022-04-17 20:32:06 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=62, command_id=2, *is_reply=True)
2022-04-17 20:32:06 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:06 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 62): set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:06 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:08 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t>\x02\x00N\x01\x01\x00\x01\x00'
2022-04-17 20:32:08 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=62, command_id=2, *is_reply=True)
2022-04-17 20:32:08 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:08 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 62): set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:08 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:14 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t?\x02\x00N\x01\x01\x00\x01\x00'
2022-04-17 20:32:14 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=63, command_id=2, *is_reply=True)
2022-04-17 20:32:14 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:14 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 63): set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:14 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:15 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t?\x02\x00N\x01\x01\x00\x01\x00'
2022-04-17 20:32:15 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=63, command_id=2, *is_reply=True)
2022-04-17 20:32:15 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:15 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 63): set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:15 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:16 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received ZCL frame: b'\t?\x02\x00N\x01\x01\x00\x01\x00'
2022-04-17 20:32:16 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=63, command_id=2, *is_reply=True)
2022-04-17 20:32:16 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Decoded ZCL frame: TuyaManufCluster:set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:16 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] Received command 0x02 (TSN 63): set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
2022-04-17 20:32:16 DEBUG (MainThread) [zigpy.zcl] [0x035F:1:0xef00] No explicit handler for cluster command 0x02: set_data_response(param=Command(status=0, tsn=78, command_id=257, function=0, data=[1, 0]))
What is the NWK-Address of the device you used? And can you put the logs in a more readable format please? It's really hard like this, you can do it like this: ``` logs.... ```
NWK-Adress should be 0x035f. Sorry about the logs, changed it for the last post.
Anything I can help with to get a solution? Would really appreciate it, if a solution can be found. Thank you :)
@centershock Sorry, the next two weeks I'm very busy and don't have much time. @javicalle Should be able to help better in this issue.
@Tropaion No need for apologies, thank you for forwarding the issue 😊
I believe that can be done. Device reports 2 DP:
command_id=257
--> 0/1 valuescommand_id=514
--> constant 100 valueDP1 is the sensor status. DP2 seems to be battery percentage remaining Maybe there is also a tamper sensor (when battery closure is opened) and/or a low battery sensor (not clear in the vendor site).
I have followed a different approach than @Tropaion . Neither better nor worse, just my approach. ts0601_magnet.py.txt
Download, rename (remove the .txt
in filename) and replace your local quirk with this one.
Remove any local cache (__pycache__
folder) and restart.
I'm 'coding' from secondary laptop and can't validate code. Lots of compile errors can be expected.
There is one chance, as the ias_zone now shows "Closed" all the time, instead of "Open" which it did before. Again the pairing log:
New device 0xf273 (a4:c1:38:4d:82:df:a6:b0) joined the network
[0xf273] Scheduling initialization
Received frame on uninitialized device <Device model=None manuf=None nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 19: b'\x00s\xf2\xb0\xa6\xdf\x82M8\xc1\xa4\x80'
[0xf273:zdo] ZDO request ZDOCmd.Device_annce: [0xF273, a4:c1:38:4d:82:df:a6:b0, 128]
Tries remaining: 3
[0xf273] Requesting 'Node Descriptor'
Tries remaining: 2
[0xf273] Extending timeout for 0xd5 request
Received frame on uninitialized device <Device model=None manuf=None nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'\xd5\x00s\xf2\x02@\x80A\x11BB\x00\x00*B\x00\x00'
[0xf273] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 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)
[0xf273] Discovering endpoints
Tries remaining: 3
[0xf273] Extending timeout for 0xd7 request
Received frame on uninitialized device <Device model=None manuf=None nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32773: b'\xd7\x00s\xf2\x01\x01'
[0xf273] Discovered endpoints: [1]
[0xf273] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>]
[0xf273:1] Discovering endpoint information
Tries remaining: 3
[0xf273] Extending timeout for 0xd9 request
Received frame on uninitialized device <Device model=None manuf=None nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 1 to ep 1, cluster 61184: b'\t\x14\x02\x00\x07\x01\x01\x00\x01\x01'
[0xf273] Received ZCL while uninitialized on endpoint id 1, cluster 0xef00 id, hdr: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=20, command_id=2, *is_reply=True), payload: b'\x00\x07\x01\x01\x00\x01\x01'
Received frame on uninitialized device <Device model=None manuf=None nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'\xd9\x00s\xf2\x12\x01\x04\x01\x02\x04\x01\x03\x01\x00\x00\x05\x00\x00\x02\x19\x00\n\x00'
[0xf273:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=1026, device_version=1, input_clusters=[1, 1280, 0], output_clusters=[25, 10])
[0xF273:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=219, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
[0xF273:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
[0xf273] Extending timeout for 0xdb request
[0xF273:1:0x0000] Received ZCL frame: b'\x18\xdb\x01\x04\x00\x00B\x10_TZE200_pay2byax\x05\x00\x00B\x06TS0601'
[0xF273:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=219, command_id=1, *is_reply=True)
[0xF273:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='_TZE200_pay2byax')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='TS0601'))])
[0xf273] Read model 'TS0601' and manufacturer '_TZE200_pay2byax' from <Endpoint id=1 in=[power:0x0001, ias_zone:0x0500, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>>
[0xf273] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_pay2byax' nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True>
Device is initialized <Device model='TS0601' manuf='_TZE200_pay2byax' nwk=0xF273 ieee=a4:c1:38:4d:82:df:a6:b0 is_initialized=True>
Checking quirks for _TZE200_pay2byax TS0601 (a4:c1:38:4d:82:df:a6:b0)
Considering <class 'ts0601_magnet.TuyaContactSensor'>
Found custom device replacement for a4:c1:38:4d:82:df:a6:b0: <class 'ts0601_magnet.TuyaContactSensor'>
device - 0xF273:a4:c1:38:4d:82:df:a6:b0 entering async_device_initialized - is_new_join: False
device - 0xF273:a4:c1:38:4d:82:df:a6:b0 has been reset and re-added or its nwk address changed
skipping discovery for previously discovered device - 5056:a4:c1:38:4d:82:df:a6:b0
[5056](TS0601): started configuration
[5056:ZDO](TS0601): 'async_configure' stage succeeded
[0x13c0] Extending timeout for 0xdd request
[5056:1:0x0500]: started IASZoneChannel configuration
[5056:1:0x0500]: bound 'ias_zone' cluster: Status.SUCCESS
[5056:1:0x0000]: finished channel configuration
[5056:1:0x0019]: finished channel configuration
[0xF273:1:0xef00] Received ZCL frame: b'\t\x14\x02\x00\x07\x01\x01\x00\x01\x01'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=20, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=7, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
[0xF273:1:0xef00] Received command 0x02 (TSN 20): set_data_response(data=TuyaCommand(status=0, tsn=7, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=20, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
[0xF273:1:0xef00] Received ZCL frame: b'\t\x15\x02\x00\x08\x02\x02\x00\x04\x00\x00\x00d'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=21, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=8, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'd\x00\x00\x00', *payload=100)))
[0xF273:1:0xef00] Received command 0x02 (TSN 21): set_data_response(data=TuyaCommand(status=0, tsn=8, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'd\x00\x00\x00', *payload=100)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=21, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
[0xF273:1:0xef00] Received ZCL frame: b'\t\x16\x02\x00\t\x01\x01\x00\x01\x01'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=22, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=9, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
[0xF273:1:0xef00] Received command 0x02 (TSN 22): set_data_response(data=TuyaCommand(status=0, tsn=9, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=22, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
[0xF273:1:0xef00] Received ZCL frame: b'\t\x17\x02\x00\n\x02\x02\x00\x04\x00\x00\x00d'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=23, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=10, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'd\x00\x00\x00', *payload=100)))
[0xF273:1:0xef00] Received command 0x02 (TSN 23): set_data_response(data=TuyaCommand(status=0, tsn=10, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'd\x00\x00\x00', *payload=100)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=23, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
[0xF273:1:0xef00] Received ZCL frame: b'\t\x18\x02\x00\x0be\x02\x00\x04\x00\x00\x02\x0e'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=24, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=11, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x0e\x02\x00\x00', *payload=526)))
[0xF273:1:0xef00] Received command 0x02 (TSN 24): set_data_response(data=TuyaCommand(status=0, tsn=11, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x0e\x02\x00\x00', *payload=526)))
[0xF273:1:0xef00] No datapoint handler for TuyaCommand(status=0, tsn=11, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x0e\x02\x00\x00', *payload=526))
[0xF273:1:0xef00] No 'handle_set_data_response' tuya handler found for set_data_response(data=TuyaCommand(status=0, tsn=11, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x0e\x02\x00\x00', *payload=526)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=24, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
[0x1A53:1:0x0402] Received ZCL frame: b'\x18\x1b\n\x00\x00)\xf2\x08'
[0x1A53:1:0x0402] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=27, command_id=10, *is_reply=True)
[0x1A53:1:0x0402] Decoded ZCL frame: TemperatureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2290))])
[0x1A53:1:0x0402] Received command 0x0A (TSN 27): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=2290))])
[0x1A53:1:0x0402] Attribute report received: measured_value=2290
[0x1A53:1:0x0405] Received ZCL frame: b'\x18\x1c\n\x00\x00!\x13\x10'
[0x1A53:1:0x0405] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=10, *is_reply=True)
[0x1A53:1:0x0405] Decoded ZCL frame: RelativeHumidityCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=4115))])
[0x1A53:1:0x0405] Received command 0x0A (TSN 28): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=4115))])
[0x1A53:1:0x0405] Attribute report received: measured_value=4115
[0x1A53:1:0x0403] Received ZCL frame: b"\x18\x1d\n\x00\x00)\xfa\x03\x14\x00(\xff\x10\x00)\xca'"
[0x1A53:1:0x0403] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=29, command_id=10, *is_reply=True)
[0x1A53:1:0x0403] Decoded ZCL frame: PressureMeasurementCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1018)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10186))])
[0x1A53:1:0x0403] Received command 0x0A (TSN 29): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=int16s, value=1018)), Attribute(attrid=0x0014, value=TypeValue(type=int8s, value=-1)), Attribute(attrid=0x0010, value=TypeValue(type=int16s, value=10186))])
[0x1A53:1:0x0403] Attribute report received: measured_value=1018, 0x0014=-1, 0x0010=10186
[0xF273:1:0x000a] Received ZCL frame: b'\x00\x19\x00\x07\x00'
[0xF273:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=25, command_id=0, *is_reply=False)
[0xF273:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[7])
[0xF273:1:0x000a] Received command 0x00 (TSN 25): Read_Attributes(attribute_ids=[7])
[0xF273:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=25, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *is_reply=True)
[0xF273:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=LocalTime, value=704407024))])
[0x3189:1:0x0500]: Updated alarm state: 0
[0xF273:1:0xef00] Received ZCL frame: b'\t\x1a\x02\x00\x0c\x01\x01\x00\x01\x01'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=26, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=12, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
[0xF273:1:0xef00] Received command 0x02 (TSN 26): set_data_response(data=TuyaCommand(status=0, tsn=12, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=26, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
[0xF273:1:0xef00] Received ZCL frame: b'\t\x1b\x02\x00\re\x02\x00\x04\x00\x00\x01\xaa'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=27, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=13, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xaa\x01\x00\x00', *payload=426)))
[0xF273:1:0xef00] Received command 0x02 (TSN 27): set_data_response(data=TuyaCommand(status=0, tsn=13, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xaa\x01\x00\x00', *payload=426)))
[0xF273:1:0xef00] No datapoint handler for TuyaCommand(status=0, tsn=13, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xaa\x01\x00\x00', *payload=426))
[0xF273:1:0xef00] No 'handle_set_data_response' tuya handler found for set_data_response(data=TuyaCommand(status=0, tsn=13, dp=101, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xaa\x01\x00\x00', *payload=426)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=27, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
[0xF273:1:0xef00] Received ZCL frame: b'\t\x1c\x02\x00\x0e\x02\x02\x00\x04\x00\x00\x00d'
[0xF273:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=28, command_id=2, *is_reply=True)
[0xF273:1:0xef00] Decoded ZCL frame: ContactSwitchManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=14, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'd\x00\x00\x00', *payload=100)))
[0xF273:1:0xef00] Received command 0x02 (TSN 28): set_data_response(data=TuyaCommand(status=0, tsn=14, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'd\x00\x00\x00', *payload=100)))
[0xF273:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
[0xF273:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
Is your feature request related to a problem? Please describe. The device is not supported, I request a full support of this device.
Describe the solution you'd like Full support for the device, primarily to check "open" and "close" of a window / door.
Device signature - this can be acquired by removing the device from ZHA and pairing it again from the add devices screen. Be sure to add the entire content of the log panel after pairing the device to a code block below this line.
Join logs:
Additional context Add any other context or screenshots about the feature request here.