zha-device-handlers
zha-device-handlers copied to clipboard
Published
20 hours ago •
zigpy
zigpy
[BUG]Unexpected interaction with New Dimmer quirk and Dimmer switch TS0601 _TZE200_1agwnems
Thanks to the great work by the contributors here I have full control of my dimmer switches. (dimmer switch TS0601 _TZE200_1agwnems)! Thank you very much! There is however a small problem, changing the brightness slider causes the light to momentarily turn off almost like a reset for a fraction of a second (time it takes to blink almost) and then from full brightness goes down to the selected brightness. Expected result would be for the dimmer to change from eg. 40% to 50% instead it goes from 40% to off(barely noticeable) to 100% and slowly dims to 50%.
To Reproduce Steps to reproduce the behavior:
- With the dimmer either on or off change the brightness value of dimmer using either automation or via the entity
- Become blinded for a time until the light slowly dims to the set value from its max value.
Expected behavior Sliding the brightness slider from eg. 0% to 40% should slowly step through the levels of brightness from 0 to 40% and sliding it bac from 40% to 0% should smoothly transition from 40% brightness to 0%
Device signature - this can be acquired by clicking on the "Zigbee Device Signature" button in the device settings
{
"node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": 260,
"device_type": "0x0100",
"in_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0x0006",
"0x0008",
"0xef00"
],
"out_clusters": [
"0x000a",
"0x0019"
]
}
},
"manufacturer": "_TZE200_1agwnems",
"model": "TS0601",
"class": "zhaquirks.tuya.ts0601_dimmer.TuyaSingleSwitchDimmer"
}
Diagnostic information - this can be acquired by clicking on the "Download Diagnostics" button in the device settings
{
"home_assistant": {
"installation_type": "Home Assistant OS",
"version": "2022.5.2",
"dev": false,
"hassio": true,
"virtualenv": false,
"python_version": "3.9.9",
"docker": true,
"arch": "x86_64",
"timezone": "Australia/Sydney",
"os_name": "Linux",
"os_version": "5.10.108",
"supervisor": "2022.05.0",
"host_os": "Home Assistant OS 7.6",
"docker_version": "20.10.9",
"chassis": "vm",
"run_as_root": true
},
"custom_components": {
"xiaomi_miot_raw": {
"version": "v1.3.1",
"requirements": [
"construct",
"python-miio>=0.5.3"
]
},
"localtuya": {
"version": "3.2.1",
"requirements": []
},
"webrtc": {
"version": "v2.3.0",
"requirements": []
},
"meross_lan": {
"version": "2.5.8",
"requirements": []
},
"hacs": {
"version": "1.24.5",
"requirements": [
"aiogithubapi>=21.11.0"
]
},
"reolink_dev": {
"version": "0.52",
"requirements": [
"reolink==0.0.61"
]
},
"samsungtv_smart": {
"version": "0.6.1",
"requirements": [
"websocket-client>=0.58.0",
"wakeonlan>=2.0.0",
"aiofiles>=0.8.0"
]
}
},
"integration_manifest": {
"domain": "zha",
"name": "Zigbee Home Automation",
"config_flow": true,
"documentation": "https://www.home-assistant.io/integrations/zha",
"requirements": [
"bellows==0.29.0",
"pyserial==3.5",
"pyserial-asyncio==0.6",
"zha-quirks==0.0.73",
"zigpy-deconz==0.16.0",
"zigpy==0.45.1",
"zigpy-xbee==0.14.0",
"zigpy-zigate==0.7.4",
"zigpy-znp==0.7.0"
],
"usb": [
{
"vid": "10C4",
"pid": "EA60",
"description": "*2652*",
"known_devices": [
"slae.sh cc2652rb stick"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*sonoff*plus*",
"known_devices": [
"sonoff zigbee dongle plus"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*zigstar*",
"known_devices": [
"ZigStar Coordinators"
]
},
{
"vid": "1CF1",
"pid": "0030",
"description": "*conbee*",
"known_devices": [
"Conbee II"
]
},
{
"vid": "10C4",
"pid": "8A2A",
"description": "*zigbee*",
"known_devices": [
"Nortek HUSBZB-1"
]
},
{
"vid": "10C4",
"pid": "8B34",
"description": "*bv 2010/10*",
"known_devices": [
"Bitron Video AV2010/10"
]
}
],
"codeowners": [
"@dmulcahey",
"@adminiuga"
],
"zeroconf": [
{
"type": "_esphomelib._tcp.local.",
"name": "tube*"
}
],
"after_dependencies": [
"usb",
"zeroconf"
],
"iot_class": "local_polling",
"loggers": [
"aiosqlite",
"bellows",
"crccheck",
"pure_pcapy3",
"zhaquirks",
"zigpy",
"zigpy_deconz",
"zigpy_xbee",
"zigpy_zigate",
"zigpy_znp"
],
"is_built_in": true
},
"data": {
"ieee": "**REDACTED**",
"nwk": 65143,
"manufacturer": "_TZE200_1agwnems",
"model": "TS0601",
"name": "_TZE200_1agwnems TS0601",
"quirk_applied": true,
"quirk_class": "zhaquirks.tuya.ts0601_dimmer.TuyaSingleSwitchDimmer",
"manufacturer_code": 4098,
"power_source": "Mains",
"lqi": 255,
"rssi": -84,
"last_seen": "2022-05-09T02:07:28",
"available": true,
"device_type": "Router",
"signature": {
"node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": 260,
"device_type": "0x0100",
"in_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0x0006",
"0x0008",
"0xef00"
],
"out_clusters": [
"0x000a",
"0x0019"
]
}
}
},
"entities": [
{
"entity_id": "light.youssefs_room_level_on_off",
"name": "_TZE200_1agwnems TS0601"
}
],
"neighbors": [
{
"device_type": "Coordinator",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x0000",
"permit_joining": "Unknown",
"depth": "0",
"lqi": "107"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x56EF",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "120"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x8073",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "89"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x83FA",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "190"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x88FE",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "117"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Parent",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x9527",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "236"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0xB150",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "114"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0xB797",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "120"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0xC54C",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "102"
}
],
"endpoint_names": [
{
"name": "ON_OFF_LIGHT"
}
],
"user_given_name": "REDACTEDS's Room",
"device_reg_id": "1bac9fa15b21a8d52d1e563874e74754",
"area_id": null
}
}
Logs - Here are some logs I found during the time I Changed the brightness, I don't know how useful this would be, but if you have any information on where and how I can dig up more useful information to diagnose this please let me know!
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x9527:1:0xef00] Received ZCL frame: b'\t.\x01\x00\x8c\x01\x01\x00\x01\x00'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x9527: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=46, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x9527:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=140, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'77fe69cbd1feffea3590ffc502fa834cc5'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xfe77, 90:35:ea:ff:fe:d1:cb:69, 255, -59, [0x83fa, 0xc54c]]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xfe77, 90:35:ea:ff:fe:d1:cb:69, 255, -59, [0x83fa, 0xc54c])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100000bffc577feffff0a096d01005a010100010106'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=11), 255, -59, 0xfe77, 255, 255, b'\tm\x01\x00Z\x01\x01\x00\x01\x01']
2022-05-10 02:01:06 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fcda0e9e300>)
2022-05-10 02:01:06 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fcda0e9e300>) completed
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Received ZCL frame: b'\tm\x01\x00Z\x01\x01\x00\x01\x01'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77: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=109, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=90, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Received command 0x01 (TSN 109): get_data(data=TuyaCommand(status=0, tsn=90, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77: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=109, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'412ab1ed542e14b2b6954b25ab5592b469e5542b12316d82c2c763bbfd7f3fa6ebc9a9bd7e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'512ab1f127aa7a0f886ab5cf9fc56de29db0afe2c57e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'612ab1f173bfdef68f6ab5cf9fc56de39db0aff9807e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2022-05-10 02:01:06 DEBUG (MainThread) [hass_nabucasa.google_report_state] Publishing message:
{'msgid': '5cb8a4fce42f465abb1beaec88216a6d',
'payload': {'devices': {'states': {'light.youssefs_room_level_on_off': {'brightness': 37,
'on': True,
'online': True}}}}}
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x8073, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=56), 149, b'\x188\x0b\x01\x00')
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef010100010000fdf5ab7380ffff0a093f010032010100010004'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=253), 245, -85, 0x8073, 255, 255, b'\t?\x01\x002\x01\x01\x00\x01\x00']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x8073:1:0xef00] Received ZCL frame: b'\t?\x01\x002\x01\x01\x00\x01\x00'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'172b219c545995b65894a524ab1593499c76b2aef5f66c8afdfd877e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x8073: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=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x8073:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=50, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.device] [0x8073] Invalid state on future for 0x3f seq -- probably duplicate response
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'73806fbdd1feffea3590ffab01fe88'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x8073, 90:35:ea:ff:fe:d1:bd:6f, 255, -85, [0x88fe]]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x8073, 90:35:ea:ff:fe:d1:bd:6f, 255, -85, [0x88fe])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'2795cb44d6feffea3590ffaa01fe88'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x9527, 90:35:ea:ff:fe:d6:44:cb, 255, -86, [0x88fe]]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x9527, 90:35:ea:ff:fe:d6:44:cb, 255, -86, [0x88fe])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'712ab1f1bb7c897a886ab5cf9fc568ee9cfac27e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'012ab1f123d47c79886ab5cf9fc56d8c9eb4a4e7289baf7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'112ab1ed542e14b2b6954b25ab55923063e5003e12316d82d3c76305fd7f3fa6ebc913e07e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'222ba19c54df3d607e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2022-05-10 02:01:06 DEBUG (MainThread) [homeassistant.components.http.view] Serving /api/hassio_ingress/-vSDmX6Eydea08IgIZXQ8kjyb41sAks9CmmU5CJmpRw/ to 192.168.0.191 (auth: False)
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'ef569cc8d1feffea3590faa700'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x56ef, 90:35:ea:ff:fe:d1:c8:9c, 250, -89, []]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x56ef, 90:35:ea:ff:fe:d1:c8:9c, 250, -89, [])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'77fe69cbd1feffea3590ffc502fa834cc5'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xfe77, 90:35:ea:ff:fe:d1:cb:69, 255, -59, [0x83fa, 0xc54c]]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xfe77, 90:35:ea:ff:fe:d1:cb:69, 255, -59, [0x83fa, 0xc54c])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef01010001000079ffab2795ffff0a092e01008c010100010004'
2022-05-10 02:01:06 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fcda0e9e300>, 'UPDATE devices_v8 SET last_seen=? WHERE ieee=?', (datetime.datetime(2022, 5, 9, 15, 38, 9, 3618, tzinfo=datetime.timezone.utc), 90:35:ea:ff:fe:d1:cb:69))
2022-05-10 02:01:06 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7fcda0e9e300>, 'UPDATE devices_v8 SET last_seen=? WHERE ieee=?', (datetime.datetime(2022, 5, 9, 15, 38, 9, 3618, tzinfo=datetime.timezone.utc), 90:35:ea:ff:fe:d1:cb:69)) completed
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=121), 255, -85, 0x9527, 255, 255, b'\t.\x01\x00\x8c\x01\x01\x00\x01\x00']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x9527:1:0xef00] Received ZCL frame: b'\t.\x01\x00\x8c\x01\x01\x00\x01\x00'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x9527: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=46, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'322bb1f1c39dbd72886ab5cf9fc56d8c9eb4a4e72874f97e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'422bb1ed542e14b2b6954b25ab55920e63e8c8fd12316d82bfc763bcf87a3fa6ebcfa9ce7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x9527:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=140, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.device] [0x9527] Invalid state on future for 0x2e seq -- probably duplicate response
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'00f5'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'522bb1ed542e14b2b6954b25ab559245638b505512316d8290c763d3fd7f3fa6eacb3dcc7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'622bb1ed542e14b2b6954b25ab559202638bb01c12316d82ccc76306f87a3fa6ebcb388d7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'97b7a8c0d1feffea3590ffc502fa834cc5'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xb797, 90:35:ea:ff:fe:d1:c0:a8, 255, -59, [0x83fa, 0xc54c]]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xb797, 90:35:ea:ff:fe:d1:c0:a8, 255, -59, [0x83fa, 0xc54c])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef01010001000047ffa6ef56ffff0a0942010035040400010002'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'722bb197545995b65894a524ab1593499cbbb2abed1a247e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=71), 255, -90, 0x56ef, 255, 255, b'\tB\x01\x005\x04\x04\x00\x01\x00']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x56EF:1:0xef00] Received ZCL frame: b'\tB\x01\x005\x04\x04\x00\x01\x00'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x56EF: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=66, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x56EF:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=53, dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>)))
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x56EF:1:0xef00] Received command 0x01 (TSN 66): get_data(data=TuyaCommand(status=0, tsn=53, dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>)))
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2022-05-10 02:01:06 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x56EF:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x56EF: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=66, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x56EF:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'022bb1ed542e14b2b6954b25ab5592b763e5542b12316d82c2c763bbfd7f3fa6ebc9bec67e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100000cffc577feffff0a096d01005a010100010106'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=12), 255, -59, 0xfe77, 255, 255, b'\tm\x01\x00Z\x01\x01\x00\x01\x01']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Received ZCL frame: b'\tm\x01\x00Z\x01\x01\x00\x01\x01'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77: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=109, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=90, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100004bffc597b7ffff0a093101008f040400010006'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=75), 255, -59, 0xb797, 255, 255, b'\t1\x01\x00\x8f\x04\x04\x00\x01\x00']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xB797:1:0xef00] Received ZCL frame: b'\t1\x01\x00\x8f\x04\x04\x00\x01\x00'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'122bb1f127aa7a0f886ab5cf9fc56de29db0afa1807e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xB797: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=49, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xB797:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=143, dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>)))
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xB797:1:0xef00] Received command 0x01 (TSN 49): get_data(data=TuyaCommand(status=0, tsn=143, dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>)))
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2022-05-10 02:01:06 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB797:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'222bb1f123d47c79886ab5cf9fc56d8c9eb4a4e728cd9c7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xB797: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=49, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xB797:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'007380040100ef010140010000f5950000'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 32883, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=245), 149, <EmberStatus.SUCCESS: 0>, b'']
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'322bb1ed542e14b2b6954b25ab559244638b505512316d8290c763d3fd7f3fa6eacb18e47e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.util] Duplicate 200 TSN
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef010100010000feffab7380ffff0a093f010032010100010004'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=254), 255, -85, 0x8073, 255, 255, b'\t?\x01\x002\x01\x01\x00\x01\x00']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (Thread-3) [aiosqlite] executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fcda0e9e300>)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x8073:1:0xef00] Received ZCL frame: b'\t?\x01\x002\x01\x01\x00\x01\x00'
2022-05-10 02:01:06 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7fcda0e9e300>) completed
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x8073: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=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0x8073:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=50, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'422bb1ed542e14b2b6954b25ab55923363e5003e12316d82d3c76305fd7f3fa6ebc956ce7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'522bb1f173bfdef68f6ab5cf9fc57de59db0af08957e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'622bb1f127aa7a0f886ab5cf9fc56de39db0af46a77e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'722bb1f127aa7a0f886ab5cf9fc56de29db0af004e7e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.device] [0x8073] Invalid state on future for 0x3f seq -- probably duplicate response
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'73806fbdd1feffea3590ffab01fe88'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x8073, 90:35:ea:ff:fe:d1:bd:6f, 255, -85, [0x88fe]]
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x8073, 90:35:ea:ff:fe:d1:bd:6f, 255, -85, [0x88fe])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'77fe69cbd1feffea3590ffc502fa834cc5'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xfe77, 90:35:ea:ff:fe:d1:cb:69, 255, -59, [0x83fa, 0xc54c]]
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'022bb1ed542e14b2b6954b25ab5592b663e5542b12316d82c2c763bbfd7f3fa6ebc960d97e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xfe77, 90:35:ea:ff:fe:d1:cb:69, 255, -59, [0x83fa, 0xc54c])
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2022-05-10 02:01:06 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface
2022-05-10 02:01:06 DEBUG (MainThread) [zeroconf] Ignoring duplicate message received from '::ffff:192.168.0.133':5353 [socket 12 (('::', 5353, 0, 0))] (126 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x01\x06iPhone\x07_rdlink\x04_tcp\x05local\x00\x00\x10\x80\x01\x00\x00\x11\x94\x008\x16rpBA=88:EC:9E:B8:47:14\x0erpVr=340.19.11\x11rpAD=7a2bf5330949\xc0\x0c\x00/\x80\x01\x00\x00\x11\x94\x00\t\xc0\x0c\x00\x05\x00\x00\x80\x00@']
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100000dffc577feffff0a096d01005a010100010106'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=13), 255, -59, 0xfe77, 255, 255, b'\tm\x01\x00Z\x01\x01\x00\x01\x01']
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Received ZCL frame: b'\tm\x01\x00Z\x01\x01\x00\x01\x01'
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77: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=109, command_id=1, *is_reply=True)
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=90, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-05-10 02:01:06 DEBUG (MainThread) [zigpy.device] [0xfe77] Invalid state on future for 0x6d seq -- probably duplicate response
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100007affab2795ffff0a092e01008c010100010004'
2022-05-10 02:01:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=122), 255, -85, 0x9527, 255, 255, b'\t.\x01\x00\x8c\x01\x01\x00\x01\x00']
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'122bb1f173bfdef68f6ab5cf9fc56de29db0af9c967e'
2022-05-10 02:01:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2022-05-10 02:01:06 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
Logs 2
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [139696786098016] Received {'type': 'call_service', 'domain': 'light', 'service': 'turn_on', 'service_data': {'entity_id': 'light.youssefs_room_level_on_off', 'brightness_pct': 61}, 'id': 89}
2022-05-10 13:30:23 DEBUG (Recorder) [homeassistant.components.recorder] Processing task: EventTask(event=<Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.youssefs_room_level_on_off, brightness_pct=61>)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0x0008] Sending Tuya Cluster Command. Cluster Command is 4, Arguments are (156, 1)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=1, expect_reply=True)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] get_dp_mapping --> found DP: 1
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] ztype: Bool.true
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_value: [1, 1]
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] raw: b'\x01'
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=34, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))
2022-05-10 13:30:23 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f0daf3d9e40>) completed
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), manufacturer=4098, tsn=35, command_id=0, *is_reply=False)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=34, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='current_level', attr_value=156, expect_reply=True)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] get_dp_mapping --> found DP: 2
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_cluster_data: 2, DPToAttributeMapping(ep_attribute='level', attribute_name='current_level', dp_type=<TuyaDPType.VALUE: 2>, converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f0daf5f4ca0>, dp_converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f0daf5f4d30>, endpoint_id=None)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] converted: 611
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] ztype: 611
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_value: [4, 0, 0, 2, 99]
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] raw: b'\x00\x00\x02c'
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=36, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02c', *payload=1661075456))
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), manufacturer=4098, tsn=37, command_id=0, *is_reply=False)
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'200db1f1bb7c897a886ab5cf9fc56fef9c140f7e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=36, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02c', *payload=1661075456)))
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xFE77:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(156, 1)' kwargs: '{}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'300db1ed542e14b2b6954b25ab55926363889de812316d82c2c763bbfd7f3fa6ebc9dd587e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'400db1f1bb7c897a886ab5cf9fc56eef9c67397e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'500db19754c543b65894a524ab1593499cd806abed7bb97e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xFE77, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=35), 38, b'\x05\x02\x10#\x00\x00"\x01\x01\x00\x01\x01')
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'0612219c545debb65894a524ab1593499c6d01a7e8cc77a8fdc64188fd7d5e3ea61e1f7e'
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'ef569cc8d1feffea3590fda600'
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x56ef, 90:35:ea:ff:fe:d1:c8:9c, 253, -90, []]
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x56ef, 90:35:ea:ff:fe:d1:c8:9c, 253, -90, [])
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.homekit.accessories] New_state: <state light.youssefs_room_level_on_off=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=156, off_brightness=None, friendly_name=Youssef's Room level, on_off, supported_features=33 @ 2022-05-10T13:29:08.224809+10:00>
2022-05-10 13:30:23 DEBUG (MainThread) [pyhap.characteristic] set_value: On to 1
2022-05-10 13:30:23 DEBUG (MainThread) [pyhap.characteristic] set_value: Brightness to 61.0
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100002affc6ba43ffff0a093f010032010100010004'
2022-05-10 13:30:23 DEBUG (Recorder) [homeassistant.components.recorder] Processing task: EventTask(event=<Event state_changed[L]: entity_id=light.youssefs_room_level_on_off, old_state=<state light.youssefs_room_level_on_off=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=53, off_brightness=None, friendly_name=Youssef's Room level, on_off, supported_features=33 @ 2022-05-10T13:29:08.224809+10:00>, new_state=<state light.youssefs_room_level_on_off=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=156, off_brightness=None, friendly_name=Youssef's Room level, on_off, supported_features=33 @ 2022-05-10T13:29:08.224809+10:00>>)
2022-05-10 13:30:23 DEBUG (Recorder) [homeassistant.components.recorder.pool.RecorderPool] Connection <sqlite3.Connection object at 0x7f0db4d82e40> checked out from pool
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'600db1ed542e14b2b6954b25ab55929670e8c8fd12316a828cc76b8bff7c3fa3ebcdde6e8db4667e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
Please let me know if there is any further information you require! Thank you!
Please, can you attach a debug log from the device operation? We need to see if there are multiple commands when light is dimmed or just one command send.
Please, can you attach a debug log from the device operation? We need to see if there are multiple commands when light is dimmed or just one command send.
Sorry, I am having trouble finding the debug logs for the device, are you looking for the /config/homeassistant.log file? I've set the logger to debug in configuration.yaml but there is a ton of text so sift through do I need to filter it? and if so how? Your help is appreciated!
Edit: I've added some logs to the original comment
Have you enabled the debug logs for zhaquirks?
It is expected that you will have traces of the type Sending Tuya Cluster Command... Cluster Command is %x, Arguments are %s when turn on/off the device or of the type Sending Tuya Cluster Command. Cluster Command is %x, Arguments are %s when dimming the device.
In addition there are traces of at least 5 different devices (the first value of the tuple [0x8073:1:0xef00] defines the device).
We need to get the correct info to be able to understand what is happening.
I am aware that it is not an easy task if you are not used to it, but it is necessary for someone with the device to do this analysis in order to give a correct answer.
Have you enabled the debug logs for
zhaquirks? It is expected that you will have traces of the typeSending Tuya Cluster Command... Cluster Command is %x, Arguments are %swhen turn on/off the device or of the typeSending Tuya Cluster Command. Cluster Command is %x, Arguments are %swhen dimming the device.
Diagnostic information - I enabled debug logs for zhaquriks and others found on the zha debug page on homeassistant.io and found some of the "Sending Tuya Cluster Command" lines you were looking for, Please let me know if you need more info, I greatly appreciate your help
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [139696786098016] Received {'type': 'call_service', 'domain': 'light', 'service': 'turn_on', 'service_data': {'entity_id': 'light.youssefs_room_level_on_off', 'brightness_pct': 61}, 'id': 89}
2022-05-10 13:30:23 DEBUG (Recorder) [homeassistant.components.recorder] Processing task: EventTask(event=<Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.youssefs_room_level_on_off, brightness_pct=61>)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0x0008] Sending Tuya Cluster Command. Cluster Command is 4, Arguments are (156, 1)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=1, expect_reply=True)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] get_dp_mapping --> found DP: 1
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] ztype: Bool.true
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_value: [1, 1]
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] raw: b'\x01'
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=34, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))
2022-05-10 13:30:23 DEBUG (Thread-3) [aiosqlite] operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f0daf3d9e40>) completed
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), manufacturer=4098, tsn=35, command_id=0, *is_reply=False)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=34, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='current_level', attr_value=156, expect_reply=True)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] get_dp_mapping --> found DP: 2
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_cluster_data: 2, DPToAttributeMapping(ep_attribute='level', attribute_name='current_level', dp_type=<TuyaDPType.VALUE: 2>, converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f0daf5f4ca0>, dp_converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f0daf5f4d30>, endpoint_id=None)
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] converted: 611
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] ztype: 611
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] from_value: [4, 0, 0, 2, 99]
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] raw: b'\x00\x00\x02c'
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=36, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02c', *payload=1661075456))
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), manufacturer=4098, tsn=37, command_id=0, *is_reply=False)
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'200db1f1bb7c897a886ab5cf9fc56fef9c140f7e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2022-05-10 13:30:23 DEBUG (MainThread) [zigpy.zcl] [0xFE77:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=36, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02c', *payload=1661075456)))
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xFE77:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(156, 1)' kwargs: '{}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'300db1ed542e14b2b6954b25ab55926363889de812316d82c2c763bbfd7f3fa6ebc9dd587e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'400db1f1bb7c897a886ab5cf9fc56eef9c67397e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'500db19754c543b65894a524ab1593499cd806abed7bb97e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xFE77, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=35), 38, b'\x05\x02\x10#\x00\x00"\x01\x01\x00\x01\x01')
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'0612219c545debb65894a524ab1593499c6d01a7e8cc77a8fdc64188fd7d5e3ea61e1f7e'
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'ef569cc8d1feffea3590fda600'
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x56ef, 90:35:ea:ff:fe:d1:c8:9c, 253, -90, []]
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x56ef, 90:35:ea:ff:fe:d1:c8:9c, 253, -90, [])
2022-05-10 13:30:23 DEBUG (MainThread) [homeassistant.components.homekit.accessories] New_state: <state light.youssefs_room_level_on_off=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=156, off_brightness=None, friendly_name=Youssef's Room level, on_off, supported_features=33 @ 2022-05-10T13:29:08.224809+10:00>
2022-05-10 13:30:23 DEBUG (MainThread) [pyhap.characteristic] set_value: On to 1
2022-05-10 13:30:23 DEBUG (MainThread) [pyhap.characteristic] set_value: Brightness to 61.0
2022-05-10 13:30:23 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040100ef0101000100002affc6ba43ffff0a093f010032010100010004'
2022-05-10 13:30:23 DEBUG (Recorder) [homeassistant.components.recorder] Processing task: EventTask(event=<Event state_changed[L]: entity_id=light.youssefs_room_level_on_off, old_state=<state light.youssefs_room_level_on_off=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=53, off_brightness=None, friendly_name=Youssef's Room level, on_off, supported_features=33 @ 2022-05-10T13:29:08.224809+10:00>, new_state=<state light.youssefs_room_level_on_off=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=156, off_brightness=None, friendly_name=Youssef's Room level, on_off, supported_features=33 @ 2022-05-10T13:29:08.224809+10:00>>)
2022-05-10 13:30:23 DEBUG (Recorder) [homeassistant.components.recorder.pool.RecorderPool] Connection <sqlite3.Connection object at 0x7f0db4d82e40> checked out from pool
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'600db1ed542e14b2b6954b25ab55929670e8c8fd12316a828cc76b8bff7c3fa3ebcdde6e8db4667e'
2022-05-10 13:30:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
Also, regarding the dimmer itself, tapping the dimming buttons works as expected. However, if it was set at 40% brightness for example and I turn it off and back on again from the middle button, the lights go from 100% to 40%(The previous state it was in before turning it off) instead of a smooth transition from 0% to 40%.
This behaviour is the same thing I'm experiencing if I remotely access it, the quirk seems to be not just sending a brightness command but also a command to turn it on again (since it's already on it flashes off and on really quick).
I'm not completely sure how the quirk functions but if its sending both brightness and on/off commands at the same time this would cause it to behave like this. A fix may be to send only brightness commands to the dimmer and off commands when they hit 0% brightness.
Well, let's analyze it step by step.
-
If the device has a behavior when you operate it manually, the quirk won't change it at all. The quirk acts as a translator between (for example) HA and the device, but cannot change the behavior of the physical device.
-
Recently a behavior has been 'corrected' in some of the quirks (including a few Tuya dimmers). HA sends a
move_to_level_with_on_offcommand when the dim bar is operated from the UI. With the corrected implementation, the quirk sends the correspondingon_offcommand and thedimcommand. This behavior is 'normal' in standard Zigbee devices and that is why it has been adapted in this way. Maybe it can be improved, but I'm not going to argue that the behavior has to be different on a particular device. If it is reviewed it will be for any implementation, not a device specific one.
About point 2, you can imagine how it goes. Someone complains that their device won't turn on when they move the dim bar. The reason is detected and corrected. Then someone complains that they have lost the ability to modify the value of dim without turning on the device and that this is a bug, and so on... That is why I am not going to look for a solution for 1 device. I'm only going to implement the solution that fits the 'standard' behavior. I hope you understand my reasons.
IMO the quirk is working (for your device) as expected. In your logs I don't see anything unusual and, as you as said, its behavior is consistent with what is expected.
However I'm going to check what the dim behavior is in HA (sends always on/off command when the dim bar is moved?). I will also review what is 'normal' behavior when (for example) a Zigbee device that is already on receives an on command. Maybe this point could be improved, but it adds complexity to the quirk and I'm not sure if there is an optimal solution. Give me some time to review it.
Regards.
Thank you for taking the time to work this out it's greatly appreciated and I understand making it work for a broad range of devices is the correct path. Is it possible however to add the individual dim up and dim down entities? This would certainly help. Thanks again, Cheers.
Great work from @javicalle and others contributing to this.
I have the exact same issue with this dimmer. Has anyone worked out a solution?
I have not had any success writing a custom quirk but my experience is pretty limited.
This dimmer is commonly available in Australia.
I'm happy to provide logs etc. Not sure if that's necessary as it's the same behaviour described by @yftj.
Thanks!
Great work from @javicalle and others contributing to this.
I have the exact same issue with this dimmer. Has anyone worked out a solution?
I have not had any success writing a custom quirk but my experience is pretty limited.
This dimmer is commonly available in Australia.
I'm happy to provide logs etc. Not sure if that's necessary as it's the same behaviour described by @yftj.
Thanks!
Here is some more information:
Device Signature
{
"node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": 260,
"device_type": "0x0100",
"in_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0x0006",
"0x0008",
"0xef00"
],
"out_clusters": [
"0x000a",
"0x0019"
]
}
},
"manufacturer": "_TZE200_1agwnems",
"model": "TS0601",
"class": "zhaquirks.tuya.ts0601_dimmer.TuyaSingleSwitchDimmer"
}
Diagnostic Information
{
"home_assistant": {
"installation_type": "Home Assistant OS",
"version": "2022.8.5",
"dev": false,
"hassio": true,
"virtualenv": false,
"python_version": "3.10.5",
"docker": true,
"arch": "aarch64",
"timezone": "Australia/Sydney",
"os_name": "Linux",
"os_version": "5.15.32-v8",
"supervisor": "2022.08.3",
"host_os": "Home Assistant OS 8.4",
"docker_version": "20.10.14",
"chassis": "embedded",
"run_as_root": true
},
"custom_components": {
"hacs": {
"version": "1.26.2",
"requirements": [
"aiogithubapi>=22.2.4"
]
},
"garbage_collection": {
"version": "4.8.3",
"requirements": [
"python-dateutil>=2.8.2"
]
},
"browser_mod": {
"version": "1.5.3",
"requirements": []
},
"dwains_dashboard": {
"version": "3.2.0",
"requirements": []
},
"nodered": {
"version": "1.0.9",
"requirements": []
},
"aarlo": {
"version": "0.7.2b8",
"requirements": [
"unidecode",
"cloudscraper>=1.2.58",
"paho-mqtt"
]
},
"ui_lovelace_minimalist": {
"version": "v1.0.3",
"requirements": []
},
"bureau_of_meteorology": {
"version": "1.1.13",
"requirements": [
"iso8601"
]
},
"webrtc": {
"version": "v2.3.1",
"requirements": []
},
"fullykiosk": {
"version": "1.1.0",
"requirements": [
"python-fullykiosk==0.0.11"
]
},
"spotcast": {
"version": "v3.6.29",
"requirements": [
"spotify_token==1.0.0"
]
},
"smartthinq_sensors": {
"version": "0.23.6",
"requirements": [
"pycountry>=20.7.3",
"xmltodict>=0.12.0",
"chardet>=4.0.0"
]
}
},
"integration_manifest": {
"domain": "zha",
"name": "Zigbee Home Automation",
"config_flow": true,
"documentation": "https://www.home-assistant.io/integrations/zha",
"requirements": [
"bellows==0.32.0",
"pyserial==3.5",
"pyserial-asyncio==0.6",
"zha-quirks==0.0.78",
"zigpy-deconz==0.18.0",
"zigpy==0.49.1",
"zigpy-xbee==0.15.0",
"zigpy-zigate==0.9.1",
"zigpy-znp==0.8.1"
],
"usb": [
{
"vid": "10C4",
"pid": "EA60",
"description": "*2652*",
"known_devices": [
"slae.sh cc2652rb stick"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*sonoff*plus*",
"known_devices": [
"sonoff zigbee dongle plus"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*tubeszb*",
"known_devices": [
"TubesZB Coordinator"
]
},
{
"vid": "1A86",
"pid": "7523",
"description": "*zigstar*",
"known_devices": [
"ZigStar Coordinators"
]
},
{
"vid": "1CF1",
"pid": "0030",
"description": "*conbee*",
"known_devices": [
"Conbee II"
]
},
{
"vid": "10C4",
"pid": "8A2A",
"description": "*zigbee*",
"known_devices": [
"Nortek HUSBZB-1"
]
},
{
"vid": "0403",
"pid": "6015",
"description": "*zigate*",
"known_devices": [
"ZiGate+"
]
},
{
"vid": "10C4",
"pid": "EA60",
"description": "*zigate*",
"known_devices": [
"ZiGate"
]
},
{
"vid": "10C4",
"pid": "8B34",
"description": "*bv 2010/10*",
"known_devices": [
"Bitron Video AV2010/10"
]
}
],
"codeowners": [
"@dmulcahey",
"@adminiuga",
"@puddly"
],
"zeroconf": [
{
"type": "_esphomelib._tcp.local.",
"name": "tube*"
},
{
"type": "_zigate-zigbee-gateway._tcp.local.",
"name": "*zigate*"
}
],
"after_dependencies": [
"onboarding",
"usb",
"zeroconf"
],
"iot_class": "local_polling",
"loggers": [
"aiosqlite",
"bellows",
"crccheck",
"pure_pcapy3",
"zhaquirks",
"zigpy",
"zigpy_deconz",
"zigpy_xbee",
"zigpy_zigate",
"zigpy_znp"
],
"is_built_in": true
},
"data": {
"ieee": "**REDACTED**",
"nwk": 30251,
"manufacturer": "_TZE200_1agwnems",
"model": "TS0601",
"name": "_TZE200_1agwnems TS0601",
"quirk_applied": true,
"quirk_class": "zhaquirks.tuya.ts0601_dimmer.TuyaSingleSwitchDimmer",
"manufacturer_code": 4098,
"power_source": "Mains",
"lqi": 83,
"rssi": null,
"last_seen": "2022-08-17T13:35:34",
"available": true,
"device_type": "Router",
"signature": {
"node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": 260,
"device_type": "0x0100",
"in_clusters": [
"0x0000",
"0x0004",
"0x0005",
"0x0006",
"0x0008",
"0xef00"
],
"out_clusters": [
"0x000a",
"0x0019"
]
}
}
},
"active_coordinator": false,
"entities": [
{
"entity_id": "light.main_bedroom_light_switch_dd1fd1fe_level_on_off",
"name": "_TZE200_1agwnems TS0601"
}
],
"neighbors": [
{
"device_type": "Coordinator",
"rx_on_when_idle": "On",
"relationship": "Parent",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x0000",
"permit_joining": "Unknown",
"depth": "0",
"lqi": "168"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x1E90",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "68"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x5061",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "108"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x8737",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "60"
},
{
"device_type": "Router",
"rx_on_when_idle": "On",
"relationship": "Sibling",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0xE6DD",
"permit_joining": "Unknown",
"depth": "15",
"lqi": "70"
},
{
"device_type": "EndDevice",
"rx_on_when_idle": "Off",
"relationship": "Child",
"extended_pan_id": "**REDACTED**",
"ieee": "**REDACTED**",
"nwk": "0x596C",
"permit_joining": "NotAccepting",
"depth": "2",
"lqi": "166"
}
],
"endpoint_names": [
{
"name": "ON_OFF_LIGHT"
}
],
"user_given_name": "Main Bedroom Light Switch",
"device_reg_id": "1ce3bdceaff061cb8faed3b7eb31f385",
"area_id": "main_bedroom",
"cluster_details": {
"1": {
"device_type": {
"name": "ON_OFF_LIGHT",
"id": 256
},
"profile_id": 260,
"in_clusters": {
"0x0000": {
"endpoint_attribute": "basic",
"attributes": {
"0x0000": {
"attribute_name": "zcl_version",
"value": 3
},
"0x0001": {
"attribute_name": "app_version",
"value": 64
},
"0x0002": {
"attribute_name": "stack_version",
"value": 0
},
"0x0003": {
"attribute_name": "hw_version",
"value": 1
},
"0x0004": {
"attribute_name": "manufacturer",
"value": "_TZE200_1agwnems"
},
"0x0005": {
"attribute_name": "model",
"value": "TS0601"
},
"0x0006": {
"attribute_name": "date_code",
"value": ""
},
"0x0007": {
"attribute_name": "power_source",
"value": 1
},
"0xfffd": {
"attribute_name": "cluster_revision",
"value": 2
},
"0xfffe": {
"attribute_name": "attr_reporting_status",
"value": 0
}
},
"unsupported_attributes": {
"0x4000": {
"attribute_name": "sw_build_id"
},
"0x0012": {
"attribute_name": "device_enabled"
},
"0x0009": {
"attribute_name": "generic_device_type"
},
"0x000a": {
"attribute_name": "product_code"
},
"0x000b": {
"attribute_name": "product_url"
},
"0x000c": {
"attribute_name": "manufacturer_version_details"
},
"0x0008": {
"attribute_name": "generic_device_class"
},
"0x000e": {
"attribute_name": "product_label"
},
"0x0010": {
"attribute_name": "location_desc"
},
"0x0011": {
"attribute_name": "physical_env"
},
"0x0013": {
"attribute_name": "alarm_mask"
},
"0x0014": {
"attribute_name": "disable_local_config"
}
}
},
"0x0004": {
"endpoint_attribute": "groups",
"attributes": {
"0x0000": {
"attribute_name": "name_support",
"value": 0
},
"0xfffd": {
"attribute_name": "cluster_revision",
"value": 2
}
},
"unsupported_attributes": {
"0xfffe": {
"attribute_name": "attr_reporting_status"
}
}
},
"0x0005": {
"endpoint_attribute": "scenes",
"attributes": {
"0x0000": {
"attribute_name": "count",
"value": 0
},
"0x0001": {
"attribute_name": "current_scene",
"value": 0
},
"0x0002": {
"attribute_name": "current_group",
"value": 0
},
"0x0003": {
"attribute_name": "scene_valid",
"value": 0
},
"0x0004": {
"attribute_name": "name_support",
"value": 0
},
"0xfffd": {
"attribute_name": "cluster_revision",
"value": 2
}
},
"unsupported_attributes": {}
},
"0xef00": {
"endpoint_attribute": "tuya_manufacturer",
"attributes": {
"0xef00": {
"attribute_name": "mcu_version",
"value": "1.0.0"
}
},
"unsupported_attributes": {}
},
"0x0006": {
"endpoint_attribute": "on_off",
"attributes": {
"0x0000": {
"attribute_name": "on_off",
"value": 1
}
},
"unsupported_attributes": {
"0x4003": {
"attribute_name": "start_up_on_off"
}
}
},
"0x0008": {
"endpoint_attribute": "level",
"attributes": {
"0x0000": {
"attribute_name": "current_level",
"value": 244
},
"0xef01": {
"attribute_name": "minimum_level",
"value": 0
},
"0xef02": {
"attribute_name": "bulb_type",
"value": 0
}
},
"unsupported_attributes": {}
}
},
"out_clusters": {
"0x000a": {
"endpoint_attribute": "time",
"attributes": {},
"unsupported_attributes": {}
},
"0x0019": {
"endpoint_attribute": "ota",
"attributes": {},
"unsupported_attributes": {}
}
}
}
}
}
}
Logs
Logger: zigpy.zcl
Source: runner.py:119
First occurred: 1:46:03 PM (69 occurrences)
Last logged: 1:49:08 PM
[0x762B:1:0xef00] No 'handle_get_data' tuya handler found for get_data(data=TuyaCommand(status=0, tsn=186, dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0)))
[0x762B:1:0xef00] No 'handle_get_data' tuya handler found for get_data(data=TuyaCommand(status=0, tsn=187, dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>)))
[0x762B:1:0xef00] No 'handle_get_data' tuya handler found for get_data(data=TuyaCommand(status=0, tsn=160, dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24)))
[0x762B:1:0xef00] No 'handle_get_data' tuya handler found for get_data(data=TuyaCommand(status=0, tsn=161, dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0)))
[0x762B:1:0xef00] No 'handle_get_data' tuya handler found for get_data(data=TuyaCommand(status=0, tsn=162, dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>)))
This is the manufacturer provided device handler for SmartThings. I wonder if this can be translated over to ZHA.
https://drive.google.com/file/d/1eqLByV3P_9wEDrZV9bQvIWGg2MIT5ySB/view
In the recent #1748 the behavior for some Tuya dimmers will change. I believe that this issue can be related.
In case someone wants to test the new version before next release, can enable the local quirks and copy the file from here:
- https://github.com/zigpy/zha-device-handlers/blob/dev/zhaquirks/tuya/mcu/init.py
Hi @javicalle I've tried to load that quirk file with the prior change but didn't notice any change to the behaviour of these dimmers.
When checking the device it wasn't showing that a different quirk had been loaded though.
They still flicker off and go full brightness before transitioning to the brightness setting requested.
I believe that you will need to copy also the ts0601_dimmer.py file and made the mcu imports relative to your custom_quirk_folder:
- https://github.com/zigpy/zha-device-handlers/blob/dev/zhaquirks/tuya/ts0601_dimmer.py
If you don't mind, you can wait for next week's version of HA or you can also try tomorrow's beta release.
Has this change made it to the stable HA release @javicalle ? I have browsed the changelog but I haven't seen anything. Thank you
Has this change made it to the stable HA release @javicalle ? I have browsed the changelog but I haven't seen anything. Thank you
Yep. Works like a charm
Has this change made it to the stable HA release @javicalle ? I have browsed the changelog but I haven't seen anything. Thank you
Yep. Works like a charm
Do you have the same Oz smart dimmers? I still have the same issue with latest home assistant build.
The dimming action from HA has a bug in some quirks (mainly Tuya ts0601_dimmer devices).
The latest HA version (2022.10.X) have the new implementation.
Double check that device is not loading any local quirk or that you have local changes in your installation. In your "Diagnostic Information" you must have the "zha-quirks==0.0.812" or 0.0.81.
If everything is correct, please enable the logs, reproduce the behavior and attach the logs.
Double check that device is not loading any local quirk or that you have local changes in your installation. In your "Diagnostic Information" you must have the
"zha-quirks==0.0.812"or0.0.81.
Thanks again for your help, I've just looked at my diagnostic info and it shows the version to be zha-quirks==0.0.82. I've also enabled the enhanced brightness slider and temperature transition from off-state options in the Zha configuration. Physically adjusting the brightness from the upper and lower buttons on the switch works as expected, stepping through the brightness levels. However, the same behaviour I explained at the beginning of the post is occurring when adjusting through HomeAssistant
Logs with reproduced behaviour
2022-10-12 16:32:20.900 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-12 16:32:20.900 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 1.25
2022-10-12 16:32:20.900 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'on_off': Default_Response(command_id=1, status=<Status.SUCCESS: 0>)}
2022-10-12 16:32:20.929 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:21.362 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: received onoff Bool.true while transitioning - skipping update
2022-10-12 16:32:21.837 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x566A](TS0012): Device seen - marking the device available and resetting counter
2022-10-12 16:32:21.838 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x566A](TS0012): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:21.956 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x812F](TS004F): Attempting to checkin with device - missed checkins: 2
2022-10-12 16:32:22.153 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
2022-10-12 16:32:22.618 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 223
2022-10-12 16:32:22.722 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:22.826 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:23.199 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 0 update with value: 74
2022-10-12 16:32:23.302 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:23.317 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: setting transitioning flag to True
2022-10-12 16:32:23.331 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '()' kwargs: '{'level': 97, 'transition_time': 0}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-10-12 16:32:23.332 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 0.25
2022-10-12 16:32:23.332 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-10-12 16:32:23.459 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:23.583 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
2022-10-12 16:32:23.725 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 96
2022-10-12 16:32:25.660 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 95
2022-10-12 16:32:25.787 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:25.895 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:25.925 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAF36](lumi.sensor_motion.aq2): Device seen - marking the device available and resetting counter
2022-10-12 16:32:25.925 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAF36](lumi.sensor_motion.aq2): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:26.361 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: setting transitioning flag to True
2022-10-12 16:32:26.364 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '()' kwargs: '{'level': 56, 'transition_time': 0}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-10-12 16:32:26.364 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 0.25
2022-10-12 16:32:26.364 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-10-12 16:32:26.622 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
2022-10-12 16:32:26.744 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 55
2022-10-12 16:32:27.161 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF905:1:0x0008]: received attribute: 0 update with value: 159
2022-10-12 16:32:27.280 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF905:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:27.408 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF905:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:27.873 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x720D](TS0207): Device seen - marking the device available and resetting counter
2022-10-12 16:32:27.874 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x720D](TS0207): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:27.929 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF3A4](lumi.sensor_motion.aq2): Device seen - marking the device available and resetting counter
2022-10-12 16:32:27.929 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF3A4](lumi.sensor_motion.aq2): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:28.404 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x82B7:1:0x0008]: received attribute: 0 update with value: 223
2022-10-12 16:32:28.531 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x82B7:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:28.644 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x82B7:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:28.684 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 53
2022-10-12 16:32:28.712 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x81AE:1:0x0008]: received attribute: 0 update with value: 137
2022-10-12 16:32:28.814 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:28.858 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x81AE:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:28.974 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:28.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x81AE:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:29.271 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: setting transitioning flag to True
2022-10-12 16:32:29.272 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '()' kwargs: '{'level': 207, 'transition_time': 0}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-10-12 16:32:29.272 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 0.25
2022-10-12 16:32:29.272 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-10-12 16:32:29.523 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
2022-10-12 16:32:29.706 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 206
2022-10-12 16:32:30.779 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1D4C](TS0013): Device seen - marking the device available and resetting counter
2022-10-12 16:32:30.779 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1D4C](TS0013): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:31.649 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 201
2022-10-12 16:32:31.752 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:31.861 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:32.134 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 0 update with value: 74
2022-10-12 16:32:32.256 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:32.355 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:33.865 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5521](SML002): Device seen - marking the device available and resetting counter
2022-10-12 16:32:33.866 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5521](SML002): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:36.092 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF905:1:0x0008]: received attribute: 0 update with value: 159
2022-10-12 16:32:36.211 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF905:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:36.328 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF905:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:36.861 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAAAC](TS0013): Device seen - marking the device available and resetting counter
2022-10-12 16:32:36.862 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAAAC](TS0013): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:37.410 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x82B7:1:0x0008]: received attribute: 0 update with value: 223
2022-10-12 16:32:37.523 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x82B7:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:37.650 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x82B7:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:37.684 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x81AE:1:0x0008]: received attribute: 0 update with value: 137
2022-10-12 16:32:37.882 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x81AE:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:38.007 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x81AE:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:39.834 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB378](TS0012): Device seen - marking the device available and resetting counter
2022-10-12 16:32:39.834 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB378](TS0012): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:39.902 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF8B6](TS0011): Device seen - marking the device available and resetting counter
2022-10-12 16:32:39.902 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF8B6](TS0011): Update device availability - device available: True - new availability: True - changed: False
2022-10-12 16:32:40.623 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 0 update with value: 201
2022-10-12 16:32:40.723 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:40.832 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2D5B:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-12 16:32:41.079 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 0 update with value: 74
2022-10-12 16:32:41.195 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-12 16:32:41.309 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5FEE:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
Can you enable also the zigpy and the zhaquirks debug logs?
There is something extrange with the 'transitioning timer'.
Can you enable also the
zigpyand thezhaquirksdebug logs? There is something extrange with the 'transitioning timer'.
sure thing, thanks for looking into this again!
Logs , ctrl f "Youssef" to see the entity im using
2022-10-23 13:57:33.686 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=5, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:33.686 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received command 0x01 (TSN 96): get_data(data=TuyaCommand(status=9, tsn=5, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:33.686 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x45B5:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-23 13:57:33.686 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=96, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:33.686 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:33.818 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: setting transitioning flag to True
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0x0006] Sending Tuya Cluster Command... Cluster Command is 0, Arguments are ()
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=0, expect_reply=True)
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] get_dp_mapping --> found DP: 1
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] ztype: Bool.false
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_value: [1, 0]
2022-10-23 13:57:33.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] raw: b'\x00'
2022-10-23 13:57:33.819 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=45, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))])
2022-10-23 13:57:33.819 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0006]: executed 'off' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:33.819 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 1.25
2022-10-23 13:57:33.819 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned off: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:33.819 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), manufacturer=4098, tsn=46, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-10-23 13:57:33.820 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=45, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:33.828 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x45B5), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=152, profile_id=260, cluster_id=61184, data=Serialized[b'\ta\x01\t\x06\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=235, rssi=-68)
2022-10-23 13:57:33.829 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received ZCL frame: b'\ta\x01\t\x06\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:33.829 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=97, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:33.829 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=6, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:33.829 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received command 0x01 (TSN 97): get_data(data=TuyaCommand(status=9, tsn=6, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:33.830 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:33.830 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=97, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:33.830 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:33.919 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=113, profile_id=260, cluster_id=61184, data=Serialized[b'\x0c\x02\x10.\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=252, rssi=-61)
2022-10-23 13:57:33.920 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\x0c\x02\x10.\x0b\x00\x00'
2022-10-23 13:57:33.920 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), manufacturer=4098, tsn=46, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:33.921 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:33.972 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x45B5), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=153, profile_id=260, cluster_id=61184, data=Serialized[b'\tb\x01\t\x07\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:33.972 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received ZCL frame: b'\tb\x01\t\x07\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:33.972 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=98, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:33.972 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=7, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:33.973 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received command 0x01 (TSN 98): get_data(data=TuyaCommand(status=9, tsn=7, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:33.973 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] No datapoint handler for TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))
2022-10-23 13:57:33.973 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=98, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:33.973 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:34.021 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=114, profile_id=260, cluster_id=61184, data=Serialized[b'\tZ\x01\x00-\x01\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=254, rssi=-61)
2022-10-23 13:57:34.021 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tZ\x01\x00-\x01\x01\x00\x01\x00'
2022-10-23 13:57:34.022 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=90, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:34.022 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=45, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:34.022 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 90): get_data(data=TuyaCommand(status=0, tsn=45, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:34.023 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: received onoff Bool.false while transitioning - skipping update
2022-10-23 13:57:34.023 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=90, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:34.023 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:34.099 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x45B5), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=154, profile_id=260, cluster_id=61184, data=Serialized[b'\tc\x01\t\x08\x0e\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:34.100 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received ZCL frame: b'\tc\x01\t\x08\x0e\x04\x00\x01\x00'
2022-10-23 13:57:34.100 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=99, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:34.102 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=8, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:34.103 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received command 0x01 (TSN 99): get_data(data=TuyaCommand(status=9, tsn=8, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:34.104 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] No datapoint handler for TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))
2022-10-23 13:57:34.107 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=99, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:34.108 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:35.070 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
2022-10-23 13:57:35.535 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=199, profile_id=260, cluster_id=61184, data=Serialized[b'\tF\x01\x08\xc6\x01\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:35.535 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tF\x01\x08\xc6\x01\x01\x00\x01\x00'
2022-10-23 13:57:35.535 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=70, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.536 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=198, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:35.536 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 70): get_data(data=TuyaCommand(status=8, tsn=198, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:35.536 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=70, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.537 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:35.673 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=200, profile_id=260, cluster_id=61184, data=Serialized[b'\tG\x01\x08\xc7\x02\x02\x00\x04\x00\x00\x03k'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:35.674 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tG\x01\x08\xc7\x02\x02\x00\x04\x00\x00\x03k'
2022-10-23 13:57:35.674 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=71, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.674 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=199, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'k\x03\x00\x00', *payload=875))]))
2022-10-23 13:57:35.675 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 71): get_data(data=TuyaCommand(status=8, tsn=199, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'k\x03\x00\x00', *payload=875))]))
2022-10-23 13:57:35.675 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xE99F:1:0x0008]: received attribute: 0 update with value: 223
2022-10-23 13:57:35.675 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=71, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.675 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:35.696 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=115, profile_id=260, cluster_id=61184, data=Serialized[b'\t[\x01\x00-\x01\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=254, rssi=-61)
2022-10-23 13:57:35.696 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\t[\x01\x00-\x01\x01\x00\x01\x00'
2022-10-23 13:57:35.696 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=91, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.696 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=45, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:35.696 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 91): get_data(data=TuyaCommand(status=0, tsn=45, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:35.697 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=91, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.698 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:35.806 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=201, profile_id=260, cluster_id=61184, data=Serialized[b'\tH\x01\x08\xc8\x03\x02\x00\x04\x00\x00\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=195, rssi=-68)
2022-10-23 13:57:35.806 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tH\x01\x08\xc8\x03\x02\x00\x04\x00\x00\x00\x01'
2022-10-23 13:57:35.806 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=72, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.806 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=200, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:35.807 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 72): get_data(data=TuyaCommand(status=8, tsn=200, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:35.807 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xE99F:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-23 13:57:35.807 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=72, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.807 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:35.836 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=116, profile_id=260, cluster_id=61184, data=Serialized[b'\t\\\x01\x00.\x02\x02\x00\x04\x00\x00\x03\xbe'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-62)
2022-10-23 13:57:35.836 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\t\\\x01\x00.\x02\x02\x00\x04\x00\x00\x03\xbe'
2022-10-23 13:57:35.836 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=92, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.837 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=46, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xbe\x03\x00\x00', *payload=958))]))
2022-10-23 13:57:35.837 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 92): get_data(data=TuyaCommand(status=0, tsn=46, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xbe\x03\x00\x00', *payload=958))]))
2022-10-23 13:57:35.837 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 0 update with value: 244
2022-10-23 13:57:35.840 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=92, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.840 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:35.937 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=202, profile_id=260, cluster_id=61184, data=Serialized[b'\tI\x01\x08\xc9\x04\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:35.937 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tI\x01\x08\xc9\x04\x04\x00\x01\x00'
2022-10-23 13:57:35.937 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=73, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.937 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=201, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:35.937 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 73): get_data(data=TuyaCommand(status=8, tsn=201, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:35.938 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xE99F:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-23 13:57:35.938 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=73, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.938 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:35.959 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=117, profile_id=260, cluster_id=61184, data=Serialized[b'\t]\x01\x00/\x03\x02\x00\x04\x00\x00\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:35.960 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\t]\x01\x00/\x03\x02\x00\x04\x00\x00\x00\x01'
2022-10-23 13:57:35.960 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=93, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.960 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=47, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:35.960 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 93): get_data(data=TuyaCommand(status=0, tsn=47, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:35.961 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-23 13:57:35.961 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=93, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:35.961 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:36.114 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=118, profile_id=260, cluster_id=61184, data=Serialized[b'\t^\x01\x000\x04\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:36.114 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\t^\x01\x000\x04\x04\x00\x01\x00'
2022-10-23 13:57:36.114 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=94, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.115 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=48, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:36.115 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 94): get_data(data=TuyaCommand(status=0, tsn=48, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:36.115 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-23 13:57:36.116 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=94, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.116 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:36.134 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=203, profile_id=260, cluster_id=61184, data=Serialized[b'\tJ\x01\x08\xca\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=241, rssi=-68)
2022-10-23 13:57:36.134 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tJ\x01\x08\xca\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:36.134 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=74, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.135 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=202, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:36.135 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 74): get_data(data=TuyaCommand(status=8, tsn=202, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:36.135 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:36.135 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=74, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.135 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.245 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=119, profile_id=260, cluster_id=61184, data=Serialized[b'\t_\x01\x001\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:36.245 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\t_\x01\x001\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:36.245 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=95, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.245 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=49, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:36.246 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 95): get_data(data=TuyaCommand(status=0, tsn=49, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:36.246 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:36.246 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=95, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.246 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.346 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=205, profile_id=260, cluster_id=61184, data=Serialized[b'\tK\x01\x08\xcb\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=242, rssi=-68)
2022-10-23 13:57:36.347 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tK\x01\x08\xcb\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:36.347 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=75, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.348 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=203, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:36.348 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 75): get_data(data=TuyaCommand(status=8, tsn=203, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:36.348 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))
2022-10-23 13:57:36.349 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=75, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.349 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.364 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=204, profile_id=260, cluster_id=61184, data=Serialized[b'\tJ\x01\x08\xca\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=225, rssi=-68)
2022-10-23 13:57:36.365 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tJ\x01\x08\xca\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:36.365 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=74, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.365 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=202, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:36.366 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 74): get_data(data=TuyaCommand(status=8, tsn=202, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:36.366 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:36.367 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=74, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.367 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.374 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=120, profile_id=260, cluster_id=61184, data=Serialized[b'\t`\x01\x002\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:36.374 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\t`\x01\x002\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:36.374 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=96, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.374 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=50, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:36.375 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 96): get_data(data=TuyaCommand(status=0, tsn=50, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:36.375 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))
2022-10-23 13:57:36.375 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=96, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.375 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.485 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xE99F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=206, profile_id=260, cluster_id=61184, data=Serialized[b'\tL\x01\x08\xcc\x0e\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:36.486 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received ZCL frame: b'\tL\x01\x08\xcc\x0e\x04\x00\x01\x00'
2022-10-23 13:57:36.486 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=76, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.486 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=204, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:36.487 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Received command 0x01 (TSN 76): get_data(data=TuyaCommand(status=8, tsn=204, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:36.487 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))
2022-10-23 13:57:36.488 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=76, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.488 DEBUG (MainThread) [zigpy.zcl] [0xE99F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.560 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=121, profile_id=260, cluster_id=61184, data=Serialized[b'\ta\x01\x003\x0e\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:36.560 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\ta\x01\x003\x0e\x04\x00\x01\x00'
2022-10-23 13:57:36.560 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=97, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.561 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=51, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:36.561 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 97): get_data(data=TuyaCommand(status=0, tsn=51, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:36.561 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))
2022-10-23 13:57:36.562 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=97, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:36.562 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:36.808 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xEBEE](lumi.magnet.acn001): Device seen - marking the device available and resetting counter
2022-10-23 13:57:36.808 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xEBEE](lumi.magnet.acn001): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:36.809 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x596D](lumi.sensor_motion.aq2): Device seen - marking the device available and resetting counter
2022-10-23 13:57:36.809 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x596D](lumi.sensor_motion.aq2): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:37.271 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=22, profile_id=260, cluster_id=61184, data=Serialized[b'\tU\x01\x08\xf0\x01\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-69)
2022-10-23 13:57:37.271 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tU\x01\x08\xf0\x01\x01\x00\x01\x00'
2022-10-23 13:57:37.271 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=85, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.272 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=240, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:37.272 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 85): get_data(data=TuyaCommand(status=8, tsn=240, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:37.272 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=85, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.273 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.409 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=23, profile_id=260, cluster_id=61184, data=Serialized[b'\tV\x01\x08\xf1\x02\x02\x00\x04\x00\x00\x02\x1d'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=219, rssi=-69)
2022-10-23 13:57:37.409 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tV\x01\x08\xf1\x02\x02\x00\x04\x00\x00\x02\x1d'
2022-10-23 13:57:37.412 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=86, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.412 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=241, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x1d\x02\x00\x00', *payload=541))]))
2022-10-23 13:57:37.413 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 86): get_data(data=TuyaCommand(status=8, tsn=241, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x1d\x02\x00\x00', *payload=541))]))
2022-10-23 13:57:37.413 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2FBE:1:0x0008]: received attribute: 0 update with value: 137
2022-10-23 13:57:37.414 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=86, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.414 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.472 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: setting transitioning flag to True
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0x0008] Sending Tuya Cluster Command. Cluster Command is 4, Arguments are ()
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=True, expect_reply=True)
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] get_dp_mapping --> found DP: 1
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] ztype: Bool.true
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_value: [1, 1]
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] raw: b'\x01'
2022-10-23 13:57:37.472 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=69, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))])
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='current_level', attr_value=66, expect_reply=True)
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] get_dp_mapping --> found DP: 2
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_cluster_data: 2, DPToAttributeMapping(ep_attribute='level', attribute_name='current_level', dp_type=<TuyaDPType.VALUE: 2>, converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f50d952e680>, dp_converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f50d952e710>, endpoint_id=None)
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] converted: 258
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] ztype: 258
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_value: [4, 0, 0, 1, 2]
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] raw: b'\x00\x00\x01\x02'
2022-10-23 13:57:37.473 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x01\x02', *payload=33619968))])
2022-10-23 13:57:37.473 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '()' kwargs: '{'level': 66, 'transition_time': 0}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.473 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 0.25
2022-10-23 13:57:37.473 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-10-23 13:57:37.474 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), manufacturer=4098, tsn=71, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-10-23 13:57:37.474 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=69, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:37.475 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), manufacturer=4098, tsn=73, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-10-23 13:57:37.475 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x01\x02', *payload=33619968))]))
2022-10-23 13:57:37.541 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=24, profile_id=260, cluster_id=61184, data=Serialized[b'\tW\x01\x08\xf2\x03\x02\x00\x04\x00\x00\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-69)
2022-10-23 13:57:37.541 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tW\x01\x08\xf2\x03\x02\x00\x04\x00\x00\x00\x01'
2022-10-23 13:57:37.541 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=87, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.542 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=242, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:37.542 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 87): get_data(data=TuyaCommand(status=8, tsn=242, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:37.542 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2FBE:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-23 13:57:37.542 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=87, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.543 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.677 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=122, profile_id=260, cluster_id=61184, data=Serialized[b'\x0c\x02\x10G\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=254, rssi=-61)
2022-10-23 13:57:37.677 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\x0c\x02\x10G\x0b\x00\x00'
2022-10-23 13:57:37.677 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), manufacturer=4098, tsn=71, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.678 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.714 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=123, profile_id=260, cluster_id=61184, data=Serialized[b'\x0c\x02\x10I\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:37.714 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\x0c\x02\x10I\x0b\x00\x00'
2022-10-23 13:57:37.715 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), manufacturer=4098, tsn=73, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.715 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.724 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
2022-10-23 13:57:37.776 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=25, profile_id=260, cluster_id=61184, data=Serialized[b'\tX\x01\x08\xf3\x04\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-69)
2022-10-23 13:57:37.776 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tX\x01\x08\xf3\x04\x04\x00\x01\x00'
2022-10-23 13:57:37.777 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=88, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.777 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=243, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:37.777 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 88): get_data(data=TuyaCommand(status=8, tsn=243, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:37.777 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x2FBE:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-23 13:57:37.778 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=88, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.778 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.789 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAAAC](TS0013): Device seen - marking the device available and resetting counter
2022-10-23 13:57:37.789 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAAAC](TS0013): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:37.817 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=124, profile_id=260, cluster_id=61184, data=Serialized[b'\tb\x01\x00E\x01\x01\x00\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:37.817 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tb\x01\x00E\x01\x01\x00\x01\x01'
2022-10-23 13:57:37.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=98, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=69, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:37.818 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 98): get_data(data=TuyaCommand(status=0, tsn=69, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:37.819 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=98, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.820 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:37.922 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=26, profile_id=260, cluster_id=61184, data=Serialized[b'\tX\x01\x08\xf3\x04\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-69)
2022-10-23 13:57:37.923 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tX\x01\x08\xf3\x04\x04\x00\x01\x00'
2022-10-23 13:57:37.923 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=88, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.924 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=243, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:37.962 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=27, profile_id=260, cluster_id=61184, data=Serialized[b'\tY\x01\x08\xf4\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=232, rssi=-69)
2022-10-23 13:57:37.962 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tY\x01\x08\xf4\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:37.962 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=89, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.963 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=244, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:37.963 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 89): get_data(data=TuyaCommand(status=8, tsn=244, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:37.963 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:37.964 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=89, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:37.964 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:38.065 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=125, profile_id=260, cluster_id=61184, data=Serialized[b'\tb\x01\x00E\x01\x01\x00\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:38.065 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tb\x01\x00E\x01\x01\x00\x01\x01'
2022-10-23 13:57:38.065 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=98, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.066 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=69, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:38.066 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=126, profile_id=260, cluster_id=61184, data=Serialized[b'\tc\x01\x00F\x02\x02\x00\x04\x00\x00\x01\x02'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:38.066 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tc\x01\x00F\x02\x02\x00\x04\x00\x00\x01\x02'
2022-10-23 13:57:38.067 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=99, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.067 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x02\x01\x00\x00', *payload=258))]))
2022-10-23 13:57:38.067 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 99): get_data(data=TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x02\x01\x00\x00', *payload=258))]))
2022-10-23 13:57:38.067 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 0 update with value: 65
2022-10-23 13:57:38.074 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=99, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.074 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:38.168 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=28, profile_id=260, cluster_id=61184, data=Serialized[b'\tY\x01\x08\xf4\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=239, rssi=-69)
2022-10-23 13:57:38.168 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tY\x01\x08\xf4\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:38.168 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=89, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.169 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=244, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:38.234 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=29, profile_id=260, cluster_id=61184, data=Serialized[b'\tZ\x01\x08\xf5\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-70)
2022-10-23 13:57:38.235 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tZ\x01\x08\xf5\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:38.235 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=90, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.236 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=245, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:38.236 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 90): get_data(data=TuyaCommand(status=8, tsn=245, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:38.237 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] No datapoint handler for TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))
2022-10-23 13:57:38.238 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=127, profile_id=260, cluster_id=61184, data=Serialized[b'\tc\x01\x00F\x02\x02\x00\x04\x00\x00\x01\x02'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:38.238 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tc\x01\x00F\x02\x02\x00\x04\x00\x00\x01\x02'
2022-10-23 13:57:38.239 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=99, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.239 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x02\x01\x00\x00', *payload=258))]))
2022-10-23 13:57:38.240 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=90, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.241 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:38.346 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=30, profile_id=260, cluster_id=61184, data=Serialized[b'\tZ\x01\x08\xf5\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-69)
2022-10-23 13:57:38.346 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\tZ\x01\x08\xf5\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:38.346 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=90, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.347 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=245, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:38.360 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x2FBE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=31, profile_id=260, cluster_id=61184, data=Serialized[b'\t[\x01\x08\xf6\x0e\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=223, rssi=-69)
2022-10-23 13:57:38.360 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received ZCL frame: b'\t[\x01\x08\xf6\x0e\x04\x00\x01\x00'
2022-10-23 13:57:38.360 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=91, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.361 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=8, tsn=246, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:38.361 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Received command 0x01 (TSN 91): get_data(data=TuyaCommand(status=8, tsn=246, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:38.361 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] No datapoint handler for TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))
2022-10-23 13:57:38.362 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=91, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:38.362 DEBUG (MainThread) [zigpy.zcl] [0x2FBE:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:38.836 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x812F](TS004F): Device seen - marking the device available and resetting counter
2022-10-23 13:57:38.836 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x812F](TS004F): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:39.794 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xFB29](TS0013): Device seen - marking the device available and resetting counter
2022-10-23 13:57:39.794 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xFB29](TS0013): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:40.464 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=43, profile_id=260, cluster_id=61184, data=Serialized[b'\tC\x01\t\x0e\x01\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:40.465 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tC\x01\t\x0e\x01\x01\x00\x01\x00'
2022-10-23 13:57:40.465 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=67, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.465 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=14, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:40.465 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 67): get_data(data=TuyaCommand(status=9, tsn=14, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:40.467 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=67, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.467 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:40.620 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=44, profile_id=260, cluster_id=61184, data=Serialized[b'\tD\x01\t\x0f\x02\x02\x00\x04\x00\x00\x01M'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=252, rssi=-61)
2022-10-23 13:57:40.621 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tD\x01\t\x0f\x02\x02\x00\x04\x00\x00\x01M'
2022-10-23 13:57:40.621 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=68, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.622 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=15, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'M\x01\x00\x00', *payload=333))]))
2022-10-23 13:57:40.622 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 68): get_data(data=TuyaCommand(status=9, tsn=15, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'M\x01\x00\x00', *payload=333))]))
2022-10-23 13:57:40.623 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x981D:1:0x0008]: received attribute: 0 update with value: 84
2022-10-23 13:57:40.624 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=68, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.624 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:40.699 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=128, profile_id=260, cluster_id=61184, data=Serialized[b'\td\x01\x00F\x01\x01\x00\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:40.700 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\td\x01\x00F\x01\x01\x00\x01\x01'
2022-10-23 13:57:40.700 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=100, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.700 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:40.700 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 100): get_data(data=TuyaCommand(status=0, tsn=70, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:40.701 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=100, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.701 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:40.761 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=45, profile_id=260, cluster_id=61184, data=Serialized[b'\tE\x01\t\x10\x03\x02\x00\x04\x00\x00\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:40.762 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tE\x01\t\x10\x03\x02\x00\x04\x00\x00\x00\x01'
2022-10-23 13:57:40.762 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=69, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.763 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=16, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:40.764 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 69): get_data(data=TuyaCommand(status=9, tsn=16, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:40.764 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x981D:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-23 13:57:40.765 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=69, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.766 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:40.784 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB1A5](LWA001): Device seen - marking the device available and resetting counter
2022-10-23 13:57:40.784 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB1A5](LWA001): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:40.797 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x2B65](lumi.sensor_motion.aq2): Device seen - marking the device available and resetting counter
2022-10-23 13:57:40.797 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x2B65](lumi.sensor_motion.aq2): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:40.809 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xD0BA](TS0012): Device seen - marking the device available and resetting counter
2022-10-23 13:57:40.809 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xD0BA](TS0012): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:40.823 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=129, profile_id=260, cluster_id=61184, data=Serialized[b'\te\x01\x00G\x02\x02\x00\x04\x00\x00\x00\xfa'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:40.823 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\te\x01\x00G\x02\x02\x00\x04\x00\x00\x00\xfa'
2022-10-23 13:57:40.824 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=101, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.824 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=71, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xfa\x00\x00\x00', *payload=250))]))
2022-10-23 13:57:40.824 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 101): get_data(data=TuyaCommand(status=0, tsn=71, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\xfa\x00\x00\x00', *payload=250))]))
2022-10-23 13:57:40.824 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 0 update with value: 63
2022-10-23 13:57:40.830 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=101, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.830 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:40.876 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=46, profile_id=260, cluster_id=61184, data=Serialized[b'\tF\x01\t\x11\x04\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:40.877 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tF\x01\t\x11\x04\x04\x00\x01\x00'
2022-10-23 13:57:40.877 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=70, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.877 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=17, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:40.877 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 70): get_data(data=TuyaCommand(status=9, tsn=17, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:40.878 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x981D:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-23 13:57:40.879 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=70, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.879 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:40.952 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=130, profile_id=260, cluster_id=61184, data=Serialized[b'\tf\x01\x00H\x03\x02\x00\x04\x00\x00\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:40.952 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tf\x01\x00H\x03\x02\x00\x04\x00\x00\x00\x01'
2022-10-23 13:57:40.952 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=102, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.952 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=72, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:40.953 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 102): get_data(data=TuyaCommand(status=0, tsn=72, datapoints=[TuyaDatapointData(dp=3, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x01\x00\x00\x00', *payload=1))]))
2022-10-23 13:57:40.953 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 61185 update with value: 0
2022-10-23 13:57:40.953 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=102, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:40.953 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:41.040 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=47, profile_id=260, cluster_id=61184, data=Serialized[b'\tG\x01\t\x12\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.041 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tG\x01\t\x12\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:41.042 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=71, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.043 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=18, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:41.043 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 71): get_data(data=TuyaCommand(status=9, tsn=18, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:41.043 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:41.044 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=71, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.045 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:41.062 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=131, profile_id=260, cluster_id=61184, data=Serialized[b'\tg\x01\x00I\x04\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.062 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tg\x01\x00I\x04\x04\x00\x01\x00'
2022-10-23 13:57:41.063 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=103, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.063 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=73, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:41.063 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 103): get_data(data=TuyaCommand(status=0, tsn=73, datapoints=[TuyaDatapointData(dp=4, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:41.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: received attribute: 61186 update with value: enum8.undefined_0x00
2022-10-23 13:57:41.066 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=103, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.066 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:41.223 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=48, profile_id=260, cluster_id=61184, data=Serialized[b'\tH\x01\t\x13\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.223 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tH\x01\t\x13\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:41.224 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=72, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.224 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=19, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:41.224 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 72): get_data(data=TuyaCommand(status=9, tsn=19, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:41.224 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] No datapoint handler for TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))
2022-10-23 13:57:41.225 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=132, profile_id=260, cluster_id=61184, data=Serialized[b'\th\x01\x00J\x05\x02\x00\x04\x00\x00\x00\x18'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.225 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\th\x01\x00J\x05\x02\x00\x04\x00\x00\x00\x18'
2022-10-23 13:57:41.226 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=104, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.226 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=74, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:41.226 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 104): get_data(data=TuyaCommand(status=0, tsn=74, datapoints=[TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))]))
2022-10-23 13:57:41.227 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=5, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x18\x00\x00\x00', *payload=24))
2022-10-23 13:57:41.230 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=72, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.230 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:41.233 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=104, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.234 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:41.380 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x981D), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=49, profile_id=260, cluster_id=61184, data=Serialized[b'\tI\x01\t\x14\x0e\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.381 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received ZCL frame: b'\tI\x01\t\x14\x0e\x04\x00\x01\x00'
2022-10-23 13:57:41.381 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=73, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.381 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=20, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:41.381 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Received command 0x01 (TSN 73): get_data(data=TuyaCommand(status=9, tsn=20, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:41.382 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] No datapoint handler for TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))
2022-10-23 13:57:41.382 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=73, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.382 DEBUG (MainThread) [zigpy.zcl] [0x981D:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:41.411 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=133, profile_id=260, cluster_id=61184, data=Serialized[b'\ti\x01\x00K\x06\x02\x00\x04\x00\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.412 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\ti\x01\x00K\x06\x02\x00\x04\x00\x00\x00\x00'
2022-10-23 13:57:41.412 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=105, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.412 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=75, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:41.412 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 105): get_data(data=TuyaCommand(status=0, tsn=75, datapoints=[TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))]))
2022-10-23 13:57:41.413 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=6, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00\x00', *payload=0))
2022-10-23 13:57:41.415 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=105, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.415 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:41.539 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=134, profile_id=260, cluster_id=61184, data=Serialized[b'\tj\x01\x00L\x0e\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:41.540 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\tj\x01\x00L\x0e\x04\x00\x01\x00'
2022-10-23 13:57:41.540 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=106, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.541 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=0, tsn=76, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:41.541 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received command 0x01 (TSN 106): get_data(data=TuyaCommand(status=0, tsn=76, datapoints=[TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))]))
2022-10-23 13:57:41.541 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] No datapoint handler for TuyaDatapointData(dp=14, data=TuyaData(dp_type=<TuyaDPType.ENUM: 4>, function=0, raw=b'\x00', *payload=<enum8.undefined_0x00: 0>))
2022-10-23 13:57:41.542 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=106, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:41.542 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.UNSUPPORTED_ATTRIBUTE: 134>)
2022-10-23 13:57:41.803 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x720D](TS0207): Device seen - marking the device available and resetting counter
2022-10-23 13:57:41.803 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x720D](TS0207): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:41.836 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE99F](TS0601): Device seen - marking the device available and resetting counter
2022-10-23 13:57:41.837 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE99F](TS0601): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:41.837 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA534](lumi.magnet.acn001): Device seen - marking the device available and resetting counter
2022-10-23 13:57:41.838 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA534](lumi.magnet.acn001): Update device availability - device available: True - new availability: True - changed: False
2022-10-23 13:57:41.931 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: setting transitioning flag to True
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0x0008] Sending Tuya Cluster Command. Cluster Command is 4, Arguments are ()
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=True, expect_reply=True)
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] get_dp_mapping --> found DP: 1
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] ztype: Bool.true
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_value: [1, 1]
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] raw: b'\x01'
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=96, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))])
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='current_level', attr_value=166, expect_reply=True)
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] get_dp_mapping --> found DP: 2
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_cluster_data: 2, DPToAttributeMapping(ep_attribute='level', attribute_name='current_level', dp_type=<TuyaDPType.VALUE: 2>, converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f50d952e680>, dp_converter=<function TuyaLevelControlManufCluster.<lambda> at 0x7f50d952e710>, endpoint_id=None)
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] converted: 650
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] ztype: 650
2022-10-23 13:57:41.932 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] from_value: [4, 0, 0, 2, 138]
2022-10-23 13:57:41.933 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] raw: b'\x00\x00\x02\x8a'
2022-10-23 13:57:41.933 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=97, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02\x8a', *payload=2315386880))])
2022-10-23 13:57:41.933 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6F8F:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '()' kwargs: '{'level': 166, 'transition_time': 0}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:41.933 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: starting transitioning timer for 0.25
2022-10-23 13:57:41.933 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-10-23 13:57:41.933 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), manufacturer=4098, tsn=98, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-10-23 13:57:41.934 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=96, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))]))
2022-10-23 13:57:41.935 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=True, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), manufacturer=4098, tsn=100, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-10-23 13:57:41.935 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=97, datapoints=[TuyaDatapointData(dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02\x8a', *payload=2315386880))]))
2022-10-23 13:57:42.060 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=135, profile_id=260, cluster_id=61184, data=Serialized[b'\x0c\x02\x10b\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:42.061 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\x0c\x02\x10b\x0b\x00\x00'
2022-10-23 13:57:42.061 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), manufacturer=4098, tsn=98, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:42.061 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:42.088 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6F8F), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=136, profile_id=260, cluster_id=61184, data=Serialized[b'\x0c\x02\x10d\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-61)
2022-10-23 13:57:42.089 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Received ZCL frame: b'\x0c\x02\x10d\x0b\x00\x00'
2022-10-23 13:57:42.089 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), manufacturer=4098, tsn=100, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:42.089 DEBUG (MainThread) [zigpy.zcl] [0x6F8F:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:42.171 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x45B5), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=155, profile_id=260, cluster_id=61184, data=Serialized[b'\td\x01\t\x08\x01\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=255, rssi=-68)
2022-10-23 13:57:42.171 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received ZCL frame: b'\td\x01\t\x08\x01\x01\x00\x01\x00'
2022-10-23 13:57:42.171 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=100, command_id=1, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:42.172 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:get_data(data=TuyaCommand(status=9, tsn=8, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:42.172 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Received command 0x01 (TSN 100): get_data(data=TuyaCommand(status=9, tsn=8, datapoints=[TuyaDatapointData(dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))]))
2022-10-23 13:57:42.173 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=100, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-10-23 13:57:42.173 DEBUG (MainThread) [zigpy.zcl] [0x45B5:1:0xef00] Sending reply: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-10-23 13:57:42.184 DEBUG (MainThread) [homeassistant.components.zha.entity] light.youssefs_room_level_on_off: transition complete - future attribute reports will write HA state
Hi @javicalle just wondering if this was still on your radar and whether there was anything I can do to help?
Well yes, I had it off my radar, but let's see if it's possible to move something forward.
The truth is that it is a difficult problem to analyze remotely. The logs don't seem to indicate anything strange, but probably the timing is important in this case.
This device also implements a power on/off transition time that generates additional traces (and may be the reason for #1854).
I'll try to check in the weekend.
I have found a bug in the payload part. Not sure if related or why there aren't many other faulty devices but there are something wrong.
Someone in the room that could be able to quirk the __init__.py and the ts0601_dimmer.py?
I have tried to quirk this @javicalle but after looking at your commits above I might have used the wrong init.py . I haven't done this before, do I need to preserve the folder structure for the files that I'm quirking?
The fix is already in the latest HA version. Can someone confirm if the issue persist? If is the case, please attach the logs from the device erratic operation.
Just updated to latest 12.0 version and it's pretty bad. Dimmer works on/off most of the time but dragging the slider to adjust brightness is completely random.
Apologies @javicalle I had tried to quirk this earlier but I'm not sure if I was using the correct init.py. Also do I need to preserve folder structure in the zhaquirks folder?
I can agree that the latest change appears to have broken the dimmer function to some extent and the result of adjusting the slider now gives a somewhat random result.