zigpy / zha-device-handlers

ZHA device handlers bridge the functionality gap created when manufacturers deviate from the ZCL specification, handling deviations and exceptions by parsing custom messages to and from Zigbee devices.
Apache License 2.0
719 stars 664 forks source link

[Device Support Request] TS0505A Lidl/Silvercrest RGBCCT light #2417

Open FachKraft42 opened 1 year ago

FachKraft42 commented 1 year ago

Problem description

I integrated two of them using ZHA with a HA SkyConnect. The color temperature slider is off, it is warm somewhere around the middle and cold at the ends of the slider. Setting a color doesn't work at all.

Solution description

Color and color temperature setting in HA working correctly.

Screenshots/Video

Screenshots/Video [Paste/upload your media here]

Device signature

Device signature ```json { "node_descriptor": "NodeDescriptor(logical_type=, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=, mac_capability_flags=, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=, *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": "0x0104", "device_type": "0x010d", "input_clusters": [ "0x0000", "0x0003", "0x0004", "0x0005", "0x0006", "0x0008", "0x0300", "0x1000" ], "output_clusters": [ "0x000a", "0x0019" ] }, "242": { "profile_id": "0xa1e0", "device_type": "0x0061", "input_clusters": [], "output_clusters": [ "0x0021" ] } }, "manufacturer": "_TZ3000_dbou1ap4", "model": "TS0505A", "class": "zhaquirks.lidl.rgbcct.RGBCCTLight" } ```

Diagnostic information

Diagnostic information ```json { "home_assistant": { "installation_type": "Home Assistant OS", "version": "2023.5.4", "dev": false, "hassio": true, "virtualenv": false, "python_version": "3.10.11", "docker": true, "arch": "x86_64", "timezone": "Europe/Amsterdam", "os_name": "Linux", "os_version": "6.1.25", "supervisor": "2023.04.1", "host_os": "Home Assistant OS 10.1", "docker_version": "23.0.3", "chassis": "embedded", "run_as_root": true }, "custom_components": { "hacs": { "version": "1.32.1", "requirements": [ "aiogithubapi>=22.10.1" ] }, "afvalinfo": { "version": "1.2.1", "requirements": [ "Babel==2.8.0", "python-dateutil==2.8.1" ] }, "openmediavault": { "version": "0.0.0", "requirements": [] } }, "integration_manifest": { "domain": "zha", "name": "Zigbee Home Automation", "after_dependencies": [ "onboarding", "usb" ], "codeowners": [ "@dmulcahey", "@adminiuga", "@puddly" ], "config_flow": true, "dependencies": [ "file_upload" ], "documentation": "https://www.home-assistant.io/integrations/zha", "iot_class": "local_polling", "loggers": [ "aiosqlite", "bellows", "crccheck", "pure_pcapy3", "zhaquirks", "zigpy", "zigpy_deconz", "zigpy_xbee", "zigpy_zigate", "zigpy_znp" ], "requirements": [ "bellows==0.35.5", "pyserial==3.5", "pyserial-asyncio==0.6", "zha-quirks==0.0.99", "zigpy-deconz==0.21.0", "zigpy==0.55.0", "zigpy-xbee==0.18.0", "zigpy-zigate==0.11.0", "zigpy-znp==0.11.1" ], "usb": [ { "vid": "10C4", "pid": "EA60", "description": "*2652*", "known_devices": [ "slae.sh cc2652rb stick" ] }, { "vid": "1A86", "pid": "55D4", "description": "*sonoff*plus*", "known_devices": [ "sonoff zigbee dongle plus v2" ] }, { "vid": "10C4", "pid": "EA60", "description": "*sonoff*plus*", "known_devices": [ "sonoff zigbee dongle plus" ] }, { "vid": "10C4", "pid": "EA60", "description": "*tubeszb*", "known_devices": [ "TubesZB Coordinator" ] }, { "vid": "1A86", "pid": "7523", "description": "*tubeszb*", "known_devices": [ "TubesZB Coordinator" ] }, { "vid": "1A86", "pid": "7523", "description": "*zigstar*", "known_devices": [ "ZigStar Coordinators" ] }, { "vid": "1CF1", "pid": "0030", "description": "*conbee*", "known_devices": [ "Conbee II" ] }, { "vid": "10C4", "pid": "8A2A", "description": "*zigbee*", "known_devices": [ "Nortek HUSBZB-1" ] }, { "vid": "0403", "pid": "6015", "description": "*zigate*", "known_devices": [ "ZiGate+" ] }, { "vid": "10C4", "pid": "EA60", "description": "*zigate*", "known_devices": [ "ZiGate" ] }, { "vid": "10C4", "pid": "8B34", "description": "*bv 2010/10*", "known_devices": [ "Bitron Video AV2010/10" ] } ], "zeroconf": [ { "type": "_esphomelib._tcp.local.", "name": "tube*" }, { "type": "_zigate-zigbee-gateway._tcp.local.", "name": "*zigate*" }, { "type": "_zigstar_gw._tcp.local.", "name": "*zigstar*" }, { "type": "_slzb-06._tcp.local.", "name": "slzb-06*" } ], "is_built_in": true }, "data": { "ieee": "**REDACTED**", "nwk": 58850, "manufacturer": "_TZ3000_dbou1ap4", "model": "TS0505A", "name": "_TZ3000_dbou1ap4 TS0505A", "quirk_applied": true, "quirk_class": "zhaquirks.lidl.rgbcct.RGBCCTLight", "manufacturer_code": 4098, "power_source": "Mains", "lqi": 255, "rssi": -36, "last_seen": "2023-05-29T12:02:11", "available": true, "device_type": "Router", "signature": { "node_descriptor": "NodeDescriptor(logical_type=, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=, mac_capability_flags=, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=, *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": "0x0104", "device_type": "0x010d", "input_clusters": [ "0x0000", "0x0003", "0x0004", "0x0005", "0x0006", "0x0008", "0x0300", "0x1000" ], "output_clusters": [ "0x000a", "0x0019" ] }, "242": { "profile_id": "0xa1e0", "device_type": "0x0061", "input_clusters": [], "output_clusters": [ "0x0021" ] } }, "manufacturer": "_TZ3000_dbou1ap4", "model": "TS0505A" }, "active_coordinator": false, "entities": [ { "entity_id": "button.tz3000_dbou1ap4_ts0505a_identify", "name": "_TZ3000_dbou1ap4 TS0505A" }, { "entity_id": "light.tz3000_dbou1ap4_ts0505a_light", "name": "_TZ3000_dbou1ap4 TS0505A" } ], "neighbors": [], "routes": [], "endpoint_names": [ { "name": "EXTENDED_COLOR_LIGHT" }, { "name": "unknown 97 device_type of 0xa1e0 profile id" } ], "user_given_name": null, "device_reg_id": "276702ea6d97847f96c73b94182b31a5", "area_id": "living_room", "cluster_details": { "1": { "device_type": { "name": "EXTENDED_COLOR_LIGHT", "id": 269 }, "profile_id": 260, "in_clusters": { "0x0000": { "endpoint_attribute": "basic", "attributes": {}, "unsupported_attributes": {} }, "0x0003": { "endpoint_attribute": "identify", "attributes": {}, "unsupported_attributes": {} }, "0x0004": { "endpoint_attribute": "groups", "attributes": {}, "unsupported_attributes": {} }, "0x0005": { "endpoint_attribute": "scenes", "attributes": {}, "unsupported_attributes": {} }, "0x0006": { "endpoint_attribute": "on_off", "attributes": { "0x0000": { "attribute_name": "on_off", "value": 0 }, "0x4002": { "attribute_name": "off_wait_time", "value": 0 } }, "unsupported_attributes": { "0x4003": { "attribute_name": "start_up_on_off" } } }, "0x0008": { "endpoint_attribute": "level", "attributes": { "0x0000": { "attribute_name": "current_level", "value": 254 } }, "unsupported_attributes": { "0x4000": { "attribute_name": "start_up_current_level" }, "0x0011": { "attribute_name": "on_level" }, "0x0010": { "attribute_name": "on_off_transition_time" }, "0x0013": { "attribute_name": "off_transition_time" }, "0x0012": { "attribute_name": "on_transition_time" }, "0x0014": { "attribute_name": "default_move_rate" } } }, "0x0300": { "endpoint_attribute": "light_color", "attributes": { "0x0000": { "attribute_name": "current_hue", "value": 0 }, "0x0001": { "attribute_name": "current_saturation", "value": 254 }, "0x0003": { "attribute_name": "current_x", "value": 44039 }, "0x0004": { "attribute_name": "current_y", "value": 19857 }, "0x0007": { "attribute_name": "color_temperature", "value": 237 }, "0x0008": { "attribute_name": "color_mode", "value": 1 }, "0x000f": { "attribute_name": "options", "value": 2 }, "0x400a": { "attribute_name": "color_capabilities", "value": 25 } }, "unsupported_attributes": { "0x4000": { "attribute_name": "enhanced_current_hue" }, "0x4002": { "attribute_name": "color_loop_active" }, "0x4010": { "attribute_name": "start_up_color_temperature" }, "0x400b": { "attribute_name": "color_temp_physical_min" }, "0x400c": { "attribute_name": "color_temp_physical_max" } } }, "0x1000": { "endpoint_attribute": "lightlink", "attributes": {}, "unsupported_attributes": {} } }, "out_clusters": { "0x000a": { "endpoint_attribute": "time", "attributes": {}, "unsupported_attributes": {} }, "0x0019": { "endpoint_attribute": "ota", "attributes": {}, "unsupported_attributes": {} } } }, "242": { "device_type": { "name": "unknown", "id": 97 }, "profile_id": 41440, "in_clusters": {}, "out_clusters": { "0x0021": { "endpoint_attribute": "green_power", "attributes": {}, "unsupported_attributes": {} } } } } } } ```

Logs

Logs ```python [Paste the logs here] ```

Custom quirk

Custom quirk ```python [Paste your custom quirk here] ```

Additional information

No response

MattWestb commented 1 year ago

One question do you have using ZHA-Tool kit and scanning attributes on the device ? I have the same problems onetime and also the dev for the tool kit and we was not getting it working OK until resetting the device and reading it. I think tuya is have made some magic that is being triggered if reading all possible attributes on the devices (that shall working OK if using find and bind as IKEA is doing in Dirigera but tuya is tuya).

FachKraft42 commented 1 year ago

One question do you have using ZHA-Tool kit and scanning attributes on the device ? I have the same problems onetime and also the dev for the tool kit and we was not getting it working OK until resetting the device and reading it. I think tuya is have made some magic that is being triggered if reading all possible attributes on the devices (that shall working OK if using find and bind as IKEA is doing in Dirigera but tuya is tuya).

One question do you have using ZHA-Tool kit and scanning attributes on the device ? I have the same problems onetime and also the dev for the tool kit and we was not getting it working OK until resetting the device and reading it. I think tuya is have made some magic that is being triggered if reading all possible attributes on the devices (that shall working OK if using find and bind as IKEA is doing in Dirigera but tuya is tuya).

Hi, thanks for your reply. I tried scan device and tuya magic. Both did not change anything. I am new to HA and this are the only two devices I have added. Is there anything I need to do differently? Are there steps need to follow?

MattWestb commented 1 year ago

LIDL lights is normally working out of the box and dont need tuya magic or other things only some need correcting the color settings that is wrong made is some devices and your device is using the right quirk for it so all things shall working OK.

Try deleting the light and restart HA and then its stable adding it new (resetting the light) and look if its working OK.

FachKraft42 commented 1 year ago

@MattWestb

I did. Still no color control and temperature setting is still off.

MattWestb commented 1 year ago

@TheJulianJES Do you having one of this devices that using quirk ? I have one TS0505 _TZ3000_odygigth and its not needing it and is working OK with both RGB and TC but the quirk is being used of _TZ3000_dbou1ap4 that is reported working OK.

TheJulianJES commented 1 year ago

No similar devices here.

Elvanthyel commented 1 year ago

Hi there, I have the exact same issue, but with a SONOFF Zigbee 3.0 USB Dongle V2, with ZHA. Same lightbulb from LIDL. (re-post from the issue openned here : https://github.com/home-assistant/core/issues/93746 )

Diagnostic information ```json { "home_assistant": { "installation_type": "Home Assistant Supervised", "version": "2023.5.4", "dev": false, "hassio": true, "virtualenv": false, "python_version": "3.10.11", "docker": true, "arch": "x86_64", "timezone": "Europe/Paris", "os_name": "Linux", "os_version": "5.10.0-21-amd64", "supervisor": "2023.04.1", "host_os": "Debian GNU/Linux 11 (bullseye)", "docker_version": "23.0.1", "chassis": "laptop", "run_as_root": true }, "custom_components": { "samsungtv_encrypted": { "version": "2022.3.1", "requirements": [ "wakeonlan>=1.1.6", "beautifulsoup4>=4.6.0", "netdisco>=2.8.3" ] }, "nodered": { "version": "1.2.0", "requirements": [] }, "hacs": { "version": "1.32.1", "requirements": [ "aiogithubapi>=22.10.1" ] }, "liveboxtvuhd": { "version": "1.0.0", "requirements": [ "fuzzywuzzy==0.18.0" ] } }, "integration_manifest": { "domain": "zha", "name": "Zigbee Home Automation", "after_dependencies": [ "onboarding", "usb" ], "codeowners": [ "@dmulcahey", "@adminiuga", "@puddly" ], "config_flow": true, "dependencies": [ "file_upload" ], "documentation": "https://www.home-assistant.io/integrations/zha", "iot_class": "local_polling", "loggers": [ "aiosqlite", "bellows", "crccheck", "pure_pcapy3", "zhaquirks", "zigpy", "zigpy_deconz", "zigpy_xbee", "zigpy_zigate", "zigpy_znp" ], "requirements": [ "bellows==0.35.5", "pyserial==3.5", "pyserial-asyncio==0.6", "zha-quirks==0.0.99", "zigpy-deconz==0.21.0", "zigpy==0.55.0", "zigpy-xbee==0.18.0", "zigpy-zigate==0.11.0", "zigpy-znp==0.11.1" ], "usb": [ { "vid": "10C4", "pid": "EA60", "description": "*2652*", "known_devices": [ "slae.sh cc2652rb stick" ] }, { "vid": "1A86", "pid": "55D4", "description": "*sonoff*plus*", "known_devices": [ "sonoff zigbee dongle plus v2" ] }, { "vid": "10C4", "pid": "EA60", "description": "*sonoff*plus*", "known_devices": [ "sonoff zigbee dongle plus" ] }, { "vid": "10C4", "pid": "EA60", "description": "*tubeszb*", "known_devices": [ "TubesZB Coordinator" ] }, { "vid": "1A86", "pid": "7523", "description": "*tubeszb*", "known_devices": [ "TubesZB Coordinator" ] }, { "vid": "1A86", "pid": "7523", "description": "*zigstar*", "known_devices": [ "ZigStar Coordinators" ] }, { "vid": "1CF1", "pid": "0030", "description": "*conbee*", "known_devices": [ "Conbee II" ] }, { "vid": "10C4", "pid": "8A2A", "description": "*zigbee*", "known_devices": [ "Nortek HUSBZB-1" ] }, { "vid": "0403", "pid": "6015", "description": "*zigate*", "known_devices": [ "ZiGate+" ] }, { "vid": "10C4", "pid": "EA60", "description": "*zigate*", "known_devices": [ "ZiGate" ] }, { "vid": "10C4", "pid": "8B34", "description": "*bv 2010/10*", "known_devices": [ "Bitron Video AV2010/10" ] } ], "zeroconf": [ { "type": "_esphomelib._tcp.local.", "name": "tube*" }, { "type": "_zigate-zigbee-gateway._tcp.local.", "name": "*zigate*" }, { "type": "_zigstar_gw._tcp.local.", "name": "*zigstar*" }, { "type": "_slzb-06._tcp.local.", "name": "slzb-06*" } ], "is_built_in": true }, "data": { "ieee": "**REDACTED**", "nwk": 43660, "manufacturer": "_TZ3000_dbou1ap4", "model": "TS0505A", "name": "_TZ3000_dbou1ap4 TS0505A", "quirk_applied": true, "quirk_class": "zhaquirks.lidl.rgbcct.RGBCCTLight", "manufacturer_code": 4098, "power_source": "Mains", "lqi": 236, "rssi": -41, "last_seen": "2023-06-03T16:24:28", "available": true, "device_type": "Router", "signature": { "node_descriptor": "NodeDescriptor(logical_type=, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=, mac_capability_flags=, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=, *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": "0x0104", "device_type": "0x010d", "input_clusters": [ "0x0000", "0x0003", "0x0004", "0x0005", "0x0006", "0x0008", "0x0300", "0x1000" ], "output_clusters": [ "0x000a", "0x0019" ] }, "242": { "profile_id": "0xa1e0", "device_type": "0x0061", "input_clusters": [], "output_clusters": [ "0x0021" ] } }, "manufacturer": "_TZ3000_dbou1ap4", "model": "TS0505A" }, "active_coordinator": false, "entities": [ { "entity_id": "button.z_l_salon_identify", "name": "_TZ3000_dbou1ap4 TS0505A" }, { "entity_id": "light.z_l_salon_light", "name": "_TZ3000_dbou1ap4 TS0505A" } ], "neighbors": [], "routes": [ { "dest_nwk": "0x0000", "route_status": "Active", "memory_constrained": true, "many_to_one": true, "route_record_required": false, "next_hop": "0x0000" } ], "endpoint_names": [ { "name": "EXTENDED_COLOR_LIGHT" }, { "name": "unknown 97 device_type of 0xa1e0 profile id" } ], "user_given_name": "Z_L_SALON", "device_reg_id": "8400a7160ad6ab36380d24416dd2dbef", "area_id": "salon", "cluster_details": { "1": { "device_type": { "name": "EXTENDED_COLOR_LIGHT", "id": 269 }, "profile_id": 260, "in_clusters": { "0x0000": { "endpoint_attribute": "basic", "attributes": {}, "unsupported_attributes": {} }, "0x0003": { "endpoint_attribute": "identify", "attributes": {}, "unsupported_attributes": {} }, "0x0004": { "endpoint_attribute": "groups", "attributes": {}, "unsupported_attributes": {} }, "0x0005": { "endpoint_attribute": "scenes", "attributes": {}, "unsupported_attributes": {} }, "0x0006": { "endpoint_attribute": "on_off", "attributes": { "0x0000": { "attribute_name": "on_off", "value": 1 }, "0x4002": { "attribute_name": "off_wait_time", "value": 0 } }, "unsupported_attributes": { "0x4003": { "attribute_name": "start_up_on_off" } } }, "0x0008": { "endpoint_attribute": "level", "attributes": { "0x0000": { "attribute_name": "current_level", "value": 140 } }, "unsupported_attributes": { "0x4000": { "attribute_name": "start_up_current_level" }, "0x0014": { "attribute_name": "default_move_rate" }, "0x0012": { "attribute_name": "on_transition_time" }, "0x0010": { "attribute_name": "on_off_transition_time" }, "0x0011": { "attribute_name": "on_level" }, "0x0013": { "attribute_name": "off_transition_time" } } }, "0x0300": { "endpoint_attribute": "light_color", "attributes": { "0x0000": { "attribute_name": "current_hue", "value": 254 }, "0x0001": { "attribute_name": "current_saturation", "value": 254 }, "0x0003": { "attribute_name": "current_x", "value": 0 }, "0x0004": { "attribute_name": "current_y", "value": 0 }, "0x0007": { "attribute_name": "color_temperature", "value": 291 }, "0x0008": { "attribute_name": "color_mode", "value": 2 }, "0x000f": { "attribute_name": "options", "value": 2 }, "0x400a": { "attribute_name": "color_capabilities", "value": 25 } }, "unsupported_attributes": { "0x4000": { "attribute_name": "enhanced_current_hue" }, "0x4002": { "attribute_name": "color_loop_active" }, "0x400b": { "attribute_name": "color_temp_physical_min" }, "0x400c": { "attribute_name": "color_temp_physical_max" }, "0x4010": { "attribute_name": "start_up_color_temperature" } } }, "0x1000": { "endpoint_attribute": "lightlink", "attributes": {}, "unsupported_attributes": {} } }, "out_clusters": { "0x000a": { "endpoint_attribute": "time", "attributes": {}, "unsupported_attributes": {} }, "0x0019": { "endpoint_attribute": "ota", "attributes": {}, "unsupported_attributes": {} } } }, "242": { "device_type": { "name": "unknown", "id": 97 }, "profile_id": 41440, "in_clusters": {}, "out_clusters": { "0x0021": { "endpoint_attribute": "green_power", "attributes": {}, "unsupported_attributes": {} } } } } } } ```

and some logs from activating debug mode on ZHA (these logs were generated with the bulb lit in white, and by selecting red in the color wheel) :

Logs ```python 2023-06-03 16:03:57.273 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAA8C](TS0505A): Device seen - marking the device available and resetting counter 2023-06-03 16:03:57.273 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAA8C](TS0505A): Update device availability - device available: True - new availability: True - changed: False 2023-06-03 16:03:58.196 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: () 2023-06-03 16:03:58.198 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'679f21a9a52adf6e7e' 2023-06-03 16:03:58.216 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'779fa1a9a52a73b27b940c25b55592499c4e27abedce788bf6c66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482461597e' 2023-06-03 16:03:58.216 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-06-03 16:03:58.218 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[102, 34, 70, 31, 0, 0, 0, 0, 31, 11, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]] 2023-06-03 16:03:58.220 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (,) 2023-06-03 16:03:58.220 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'709c21a9fe2a1668857e' 2023-06-03 16:03:58.226 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'009ca1a9fe2a15b3a0e4687e' 2023-06-03 16:03:58.226 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-06-03 16:03:58.228 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [, b'\xf9'] 2023-06-03 16:03:58.229 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 249 2023-06-03 16:03:58.229 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 448, MAC_TX_BROADCAST = 174, MAC_RX_UNICAST = 723, MAC_TX_UNICAST_SUCCESS = 374, MAC_TX_UNICAST_RETRY = 6, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 328, APS_DATA_TX_UNICAST_SUCCESS = 154, APS_DATA_TX_UNICAST_RETRY = 7, APS_DATA_TX_UNICAST_FAILED = 4, ROUTE_DISCOVERY_INITIATED = 4, NEIGHBOR_ADDED = 3, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 3, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 1, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 249] 2023-06-03 16:04:06.462 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x3D64](LDSENK10): Device seen - marking the device available and resetting counter 2023-06-03 16:04:06.462 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x3D64](LDSENK10): Update device availability - device available: True - new availability: True - changed: False 2023-06-03 16:04:07.657 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'109cb1a90d2a991884570fdb55763629709927dbe57e' 2023-06-03 16:04:07.657 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-06-03 16:04:07.658 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:07.659 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:07.659 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []) 2023-06-03 16:04:07.661 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:07.666 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:07.668 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:07.669 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:07.698 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'209cb1a9112a15b658944924ab1593499cf8cb7c61649874fade2983fc7e1f0ee900cd7e' 2023-06-03 16:04:07.698 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-06-03 16:04:07.700 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=182), 236, -41, 0xaa8c, 255, 255, b'\x18J\n\x00\x00 \xa9'] 2023-06-03 16:04:07.700 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=182), 236, -41, 0xaa8c, 255, 255, b'\x18J\n\x00\x00 \xa9'] 2023-06-03 16:04:07.701 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=182, profile_id=260, cluster_id=768, data=Serialized[b'\x18J\n\x00\x00 \xa9'], tx_options=, radius=0, non_member_radius=0, lqi=236, rssi=-41) 2023-06-03 16:04:07.701 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received ZCL frame: b'\x18J\n\x00\x00 \xa9' 2023-06-03 16:04:07.702 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=74, command_id=10, *direction=) 2023-06-03 16:04:07.703 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame: LidlRGBCCTColorCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=169))]) 2023-06-03 16:04:07.705 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received command 0x0A (TSN 74): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=169))]) 2023-06-03 16:04:07.706 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Attribute report received: current_hue=169 2023-06-03 16:04:07.707 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801047.701599, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:07.709 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801047.701599, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:07.710 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:07.714 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:07.716 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 0, 169)) 2023-06-03 16:04:07.717 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 0, 169)) completed 2023-06-03 16:04:07.719 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:07.721 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:08.233 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: () 2023-06-03 16:04:08.234 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'039d21a9a52ac2547e' 2023-06-03 16:04:08.253 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'319da1a9a52a7db27b9402258a5592499c4e27abedce478bf6c66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a2519048247bd37e' 2023-06-03 16:04:08.254 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-06-03 16:04:08.255 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[104, 34, 72, 32, 0, 0, 0, 0, 32, 11, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]] 2023-06-03 16:04:08.256 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (,) 2023-06-03 16:04:08.258 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'14a221a9fe2a16b5767e' 2023-06-03 16:04:08.263 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'42a2a1a9fe2a15b3a0a51b7e' 2023-06-03 16:04:08.263 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-06-03 16:04:08.265 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [, b'\xf9'] 2023-06-03 16:04:08.266 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 249 2023-06-03 16:04:08.267 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 450, MAC_TX_BROADCAST = 174, MAC_RX_UNICAST = 725, MAC_TX_UNICAST_SUCCESS = 375, MAC_TX_UNICAST_RETRY = 6, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 329, APS_DATA_TX_UNICAST_SUCCESS = 154, APS_DATA_TX_UNICAST_RETRY = 7, APS_DATA_TX_UNICAST_FAILED = 4, ROUTE_DISCOVERY_INITIATED = 4, NEIGHBOR_ADDED = 3, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 3, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 1, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 249] 2023-06-03 16:04:10.394 ERROR (MainThread) [homeassistant.components.homekit_controller.connection] Unexpected exception from > Traceback (most recent call last): File "/usr/src/homeassistant/homeassistant/helpers/debounce.py", line 117, in _handle_timer_finish await task File "/usr/src/homeassistant/homeassistant/components/homekit_controller/connection.py", line 756, in async_update self.process_new_events(new_values_dict) File "/usr/src/homeassistant/homeassistant/components/homekit_controller/connection.py", line 769, in process_new_events self.entity_map.process_changes(new_values_dict) File "/usr/local/lib/python3.10/site-packages/aiohomekit/model/__init__.py", line 370, in process_changes accessory = self.aid(aid) File "/usr/local/lib/python3.10/site-packages/aiohomekit/model/__init__.py", line 363, in aid return self._aid_to_accessory[aid] KeyError: 5 2023-06-03 16:04:11.963 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'52a2b1a90d2a991884570fdb5576362970992740257e' 2023-06-03 16:04:11.964 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-06-03 16:04:11.965 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:11.966 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:11.966 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []) 2023-06-03 16:04:11.967 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:11.968 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:11.970 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:11.971 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.002 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'62a2b1a9112a15b658944924ab1593499cf9d37261649874f5de2883fb7e1e0cebcf334c7e' 2023-06-03 16:04:12.002 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-06-03 16:04:12.005 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=183), 244, -39, 0xaa8c, 255, 255, b'\x18K\n\x07\x00!\xab\x00'] 2023-06-03 16:04:12.005 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=183), 244, -39, 0xaa8c, 255, 255, b'\x18K\n\x07\x00!\xab\x00'] 2023-06-03 16:04:12.006 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=183, profile_id=260, cluster_id=768, data=Serialized[b'\x18K\n\x07\x00!\xab\x00'], tx_options=, radius=0, non_member_radius=0, lqi=244, rssi=-39) 2023-06-03 16:04:12.006 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received ZCL frame: b'\x18K\n\x07\x00!\xab\x00' 2023-06-03 16:04:12.007 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=75, command_id=10, *direction=) 2023-06-03 16:04:12.010 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame: LidlRGBCCTColorCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0007, value=TypeValue(type=uint16_t, value=171))]) 2023-06-03 16:04:12.011 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received command 0x0A (TSN 75): Report_Attributes(attribute_reports=[Attribute(attrid=0x0007, value=TypeValue(type=uint16_t, value=171))]) 2023-06-03 16:04:12.012 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Attribute report received: color_temperature=171 2023-06-03 16:04:12.013 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.006618, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:12.015 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.006618, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:12.017 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.018 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.020 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 7, 171)) 2023-06-03 16:04:12.022 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 7, 171)) completed 2023-06-03 16:04:12.023 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.024 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.077 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: setting transitioning flag to True 2023-06-03 16:04:12.078 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=False, direction=, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=51, command_id=1, *direction=) 2023-06-03 16:04:12.079 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Sending request: on() 2023-06-03 16:04:12.080 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0xAA8C), dst_ep=1, source_route=None, extended_timeout=False, tsn=51, profile_id=260, cluster_id=6, data=Serialized[b'\x013\x01'], tx_options=, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-06-03 16:04:12.081 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (, 0xaa8c, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=51), 52, b'\x013\x01') 2023-06-03 16:04:12.084 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'27a321a9602a153ef3904b23aa5493499d4e2798d9cd66b8fc8aef7e' 2023-06-03 16:04:12.092 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'73a3a1a9602a15f709f17e' 2023-06-03 16:04:12.092 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-06-03 16:04:12.094 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [, 69] 2023-06-03 16:04:12.106 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'03a3b1a96b2a153ef3904b23aa5493499d4e27eed9ce673c207e' 2023-06-03 16:04:12.106 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-06-03 16:04:12.108 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [, 43660, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=69), 52, , b''] 2023-06-03 16:04:12.108 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [, 43660, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=69), 52, , b''] 2023-06-03 16:04:12.152 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'13a3b1a90d2a991884570fdb55763629709927af7f7e' 2023-06-03 16:04:12.152 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-06-03 16:04:12.154 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.155 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.155 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []) 2023-06-03 16:04:12.156 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:12.157 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:12.159 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.160 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'23a3b1a90d2a991884570fdb5576362968972736557e' 2023-06-03 16:04:12.160 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-06-03 16:04:12.160 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.163 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 244, -39, []] 2023-06-03 16:04:12.164 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 244, -39, []] 2023-06-03 16:04:12.164 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 244, -39, []) 2023-06-03 16:04:12.165 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:12.166 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33a3b1a90d2a991884570fdb55763629709927cfc57e' 2023-06-03 16:04:12.166 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-06-03 16:04:12.167 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.167 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.168 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []) 2023-06-03 16:04:12.168 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:12.170 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.171 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.173 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:12.174 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:12.175 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'43a3b1a9112a15b658924a24ab1593499cf6d37261649874f8ce5082fd7e3d0f657e' 2023-06-03 16:04:12.176 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-06-03 16:04:12.176 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.178 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=184), 244, -39, 0xaa8c, 255, 255, b'\x083\x0b\x01\x00'] 2023-06-03 16:04:12.178 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=184), 244, -39, 0xaa8c, 255, 255, b'\x083\x0b\x01\x00'] 2023-06-03 16:04:12.179 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=184, profile_id=260, cluster_id=6, data=Serialized[b'\x083\x0b\x01\x00'], tx_options=, radius=0, non_member_radius=0, lqi=244, rssi=-39) 2023-06-03 16:04:12.179 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Received ZCL frame: b'\x083\x0b\x01\x00' 2023-06-03 16:04:12.180 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=51, command_id=11, *direction=) 2023-06-03 16:04:12.181 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Decoded ZCL frame: OnOff:Default_Response(command_id=1, status=) 2023-06-03 16:04:12.182 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.183 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xAA8C:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: Default_Response(command_id=1, status=) 2023-06-03 16:04:12.183 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: handling color commands after turning on/level 2023-06-03 16:04:12.184 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=False, direction=, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=53, command_id=6, *direction=) 2023-06-03 16:04:12.185 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Sending request: move_to_hue_and_saturation(hue=254, saturation=254, transition_time=0, options_mask=None, options_override=None) 2023-06-03 16:04:12.186 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0xAA8C), dst_ep=1, source_route=None, extended_timeout=False, tsn=53, profile_id=260, cluster_id=768, data=Serialized[b'\x015\x06\xfe\xfe\x00\x00'], tx_options=, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-06-03 16:04:12.187 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (, 0xaa8c, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=53), 54, b'\x015\x06\xfe\xfe\x00\x00') 2023-06-03 16:04:12.189 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.179836, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:12.190 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'35a021a9602a153ef3904b25a95493499d4e279edbc966befb389d89fc27de7e' 2023-06-03 16:04:12.191 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.179836, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:12.193 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.194 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.194 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'53a3b1a9112a15b658924a24ab1593499cf7d37261649874fade2e83fc7e2fa7e9bff77e' 2023-06-03 16:04:12.195 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-06-03 16:04:12.196 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=185), 244, -39, 0xaa8c, 255, 255, b'\x18M\n\x00\x00\x10\x00'] 2023-06-03 16:04:12.197 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=185), 244, -39, 0xaa8c, 255, 255, b'\x18M\n\x00\x00\x10\x00'] 2023-06-03 16:04:12.197 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=185, profile_id=260, cluster_id=6, data=Serialized[b'\x18M\n\x00\x00\x10\x00'], tx_options=, radius=0, non_member_radius=0, lqi=244, rssi=-39) 2023-06-03 16:04:12.198 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Received ZCL frame: b'\x18M\n\x00\x00\x10\x00' 2023-06-03 16:04:12.199 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=77, command_id=10, *direction=) 2023-06-03 16:04:12.200 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=))]) 2023-06-03 16:04:12.200 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Received command 0x0A (TSN 77): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=))]) 2023-06-03 16:04:12.202 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Attribute report received: on_off= 2023-06-03 16:04:12.202 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: received onoff Bool.false while transitioning - skipping update 2023-06-03 16:04:12.203 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.198063, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:12.204 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'63a3b1a9112a15b658924a24ab5593499cf4d37261649874fade2f83fc7e2fa6e984df7e' 2023-06-03 16:04:12.204 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-06-03 16:04:12.206 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=186), 244, -39, 0xaa8c, 255, 255, b'\x18L\n\x00\x00\x10\x01'] 2023-06-03 16:04:12.207 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=186), 244, -39, 0xaa8c, 255, 255, b'\x18L\n\x00\x00\x10\x01'] 2023-06-03 16:04:12.207 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=186, profile_id=260, cluster_id=6, data=Serialized[b'\x18L\n\x00\x00\x10\x01'], tx_options=, radius=0, non_member_radius=0, lqi=244, rssi=-39) 2023-06-03 16:04:12.208 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Received ZCL frame: b'\x18L\n\x00\x00\x10\x01' 2023-06-03 16:04:12.208 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=76, command_id=10, *direction=) 2023-06-03 16:04:12.209 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=))]) 2023-06-03 16:04:12.210 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Received command 0x0A (TSN 76): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=))]) 2023-06-03 16:04:12.211 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0006] Attribute report received: on_off= 2023-06-03 16:04:12.212 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: received onoff Bool.true while transitioning - skipping update 2023-06-03 16:04:12.214 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'74a0a1a9602a15f426087e' 2023-06-03 16:04:12.214 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-06-03 16:04:12.214 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.198063, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:12.216 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [, 70] 2023-06-03 16:04:12.217 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.218 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.221 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 6, 0, )) 2023-06-03 16:04:12.224 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 6, 0, )) completed 2023-06-03 16:04:12.225 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'04a0b1a96b2a153ef3904b25a95493499d4e27eddbce6727207e' 2023-06-03 16:04:12.225 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-06-03 16:04:12.227 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [, 43660, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=70), 54, , b''] 2023-06-03 16:04:12.227 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [, 43660, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=70), 54, , b''] 2023-06-03 16:04:12.228 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.229 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.230 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.207891, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:12.233 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.207891, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:12.235 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.236 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.238 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 6, 0, )) 2023-06-03 16:04:12.243 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 6, 0, )) completed 2023-06-03 16:04:12.244 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.244 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.251 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'14a0b1a90d2a991884570fdb5576362970992795127e' 2023-06-03 16:04:12.252 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-06-03 16:04:12.253 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.253 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.253 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []) 2023-06-03 16:04:12.254 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:12.255 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:12.256 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.257 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.258 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'24a0b1a90d2a991884570fdb55763629709927c5f57e' 2023-06-03 16:04:12.258 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-06-03 16:04:12.258 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingRouteRecordHandler: [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.259 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []] 2023-06-03 16:04:12.259 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0xaa8c, 60:a4:23:ff:fe:45:c3:dd, 236, -41, []) 2023-06-03 16:04:12.259 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) 2023-06-03 16:04:12.260 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO relays_v11 VALUES (:ieee, :relays)\n ON CONFLICT (ieee)\n DO UPDATE SET relays=excluded.relays WHERE relays != :relays', {'ieee': 60:a4:23:ff:fe:45:c3:dd, 'relays': b'\x00'}) completed 2023-06-03 16:04:12.261 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.262 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.298 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'34a0b1a9112a15b658944924ab1593499cf5cb7c61649874f8ce5682fa7e3d6e157e' 2023-06-03 16:04:12.298 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-06-03 16:04:12.300 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=187), 236, -41, 0xaa8c, 255, 255, b'\x085\x0b\x06\x00'] 2023-06-03 16:04:12.301 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=187), 236, -41, 0xaa8c, 255, 255, b'\x085\x0b\x06\x00'] 2023-06-03 16:04:12.301 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=187, profile_id=260, cluster_id=768, data=Serialized[b'\x085\x0b\x06\x00'], tx_options=, radius=0, non_member_radius=0, lqi=236, rssi=-41) 2023-06-03 16:04:12.301 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received ZCL frame: b'\x085\x0b\x06\x00' 2023-06-03 16:04:12.302 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=11, *direction=) 2023-06-03 16:04:12.303 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame: LidlRGBCCTColorCluster:Default_Response(command_id=6, status=) 2023-06-03 16:04:12.304 DEBUG (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xAA8C:1:0x0300]: executed 'move_to_hue_and_saturation' command with args: '()' kwargs: '{'hue': 254, 'saturation': 254, 'transition_time': 0}' result: Default_Response(command_id=6, status=) 2023-06-03 16:04:12.304 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: starting transitioning timer for 0.25 2023-06-03 16:04:12.304 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: turned on: {'on_off': Default_Response(command_id=1, status=), 'move_to_hue_and_saturation': Default_Response(command_id=6, status=)} 2023-06-03 16:04:12.305 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.301844, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:12.307 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.301844, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:12.309 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'44a0b1a9112a15b658944924ab5593499cf2cb7c61649874f2de2d83fc7e1f59eacdfe918e0fe7fbd755307e' 2023-06-03 16:04:12.309 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-06-03 16:04:12.312 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=188), 236, -41, 0xaa8c, 255, 255, b'\x18N\n\x00\x00 \xfe\x01\x00 \xfe\x01\xf0 '] 2023-06-03 16:04:12.312 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=188), 236, -41, 0xaa8c, 255, 255, b'\x18N\n\x00\x00 \xfe\x01\x00 \xfe\x01\xf0 '] 2023-06-03 16:04:12.313 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=, address=0xAA8C), src_ep=1, dst=AddrModeAddress(addr_mode=, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=188, profile_id=260, cluster_id=768, data=Serialized[b'\x18N\n\x00\x00 \xfe\x01\x00 \xfe\x01\xf0 '], tx_options=, radius=0, non_member_radius=0, lqi=236, rssi=-41) 2023-06-03 16:04:12.313 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received ZCL frame: b'\x18N\n\x00\x00 \xfe\x01\x00 \xfe\x01\xf0 ' 2023-06-03 16:04:12.314 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=, is_manufacturer_specific=0, direction=, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=78, command_id=10, *direction=) 2023-06-03 16:04:12.315 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Decoded ZCL frame: LidlRGBCCTColorCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254)), Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=254)), Attribute(attrid=0xF001, value=TypeValue(type=uint8_t, value=32))]) 2023-06-03 16:04:12.315 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Received command 0x0A (TSN 78): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254)), Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=254)), Attribute(attrid=0xF001, value=TypeValue(type=uint8_t, value=32))]) 2023-06-03 16:04:12.316 DEBUG (MainThread) [zigpy.zcl] [0xAA8C:1:0x0300] Attribute report received: current_hue=254, current_saturation=254, 0xF001=32 2023-06-03 16:04:12.317 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.318 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.318 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.313608, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) 2023-06-03 16:04:12.319 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'UPDATE devices_v11\n SET last_seen=:ts\n WHERE ieee=:ieee AND :ts - last_seen > :min_last_seen_delta', {'ts': 1685801052.313608, 'ieee': 60:a4:23:ff:fe:45:c3:dd, 'min_last_seen_delta': 30.0}) completed 2023-06-03 16:04:12.319 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.320 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.320 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 0, 254)) 2023-06-03 16:04:12.321 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 0, 254)) completed 2023-06-03 16:04:12.321 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.322 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.323 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 1, 254)) 2023-06-03 16:04:12.323 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 1, 254)) completed 2023-06-03 16:04:12.324 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.324 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.325 DEBUG (Thread-2) [aiosqlite] executing functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 61441, 32)) 2023-06-03 16:04:12.325 DEBUG (Thread-2) [aiosqlite] operation functools.partial(, 'INSERT INTO attributes_cache_v11 VALUES (?, ?, ?, ?, ?)\n ON CONFLICT (ieee, endpoint_id, cluster, attrid)\n DO UPDATE SET\n value=excluded.value WHERE value != excluded.value', (60:a4:23:ff:fe:45:c3:dd, 1, 768, 61441, 32)) completed 2023-06-03 16:04:12.326 DEBUG (Thread-2) [aiosqlite] executing functools.partial() 2023-06-03 16:04:12.326 DEBUG (Thread-2) [aiosqlite] operation functools.partial() completed 2023-06-03 16:04:12.555 DEBUG (MainThread) [homeassistant.components.zha.entity] light.z_l_salon_light: transition complete - future attribute reports will write HA state ```

additionnal behavior :

pipiche38 commented 10 months ago

It looks like under certain circumstance only Color command 0x06 Move to Hue and Saturation works. In addition Tuya use a specific command on cluster 0x0300 to switch to RGB or White mode

Tuya Color Mode Cluster 0x0300 Cmd 0xf0 Payload 0 for white light 1 for color light

Tuya move to Hue and Saturation Cluster 0x0300 Cmd 0x06 Payload: hue + saturation + brightness

MattWestb commented 10 months ago

Great findings @pipiche38 !! Do you knowing if its for only "normal" B versions (NOT Zigbee certified ones) or also the A version that LIDL is using or opposite ? Was scanning all attributes on one LIDL and it was not able going out color mode until resetting it so this sounds being the answer of what it was not changing to CT.

Atiroocky commented 5 months ago

Hello,

Same issue here. I have 6 TS505A_TZ3000_odygigth and and can’t apply color. Only white and the behavior is pretty weird, as described earlier in this thread.

But I also have three other RGB bulbs from LIDL like this one. ampoule led LIDL

These are shown as TS505A_TZ3000_kdpxju99 They all work perfectly in ZHA with Sonoff Zigbee (type P). Both white and colors. And on/off states are reliable.

Is there a way to fake the TS505A_TZ3000_odygigth with TS505A_TZ3000_kdpxju99 integration ?

(ps : Is there any link with "quirk" method ?)

Thanks ;-)

MattWestb commented 5 months ago

Try deleting the device and restart HA and adding it new. I was having the same problems and also the dev of ZHA-toolkit after scanning the device for commands and attribute with toolkit but both was getting it working after adding it new. tuya have make special commands color changes that is not standard but it looks working aslong only using standard commands on this devices (its one A series so its Zigbee certified that most tuya device is not).

Atiroocky commented 5 months ago

Try deleting the device and restart HA and adding it new. I was having the same problems and also the dev of ZHA-toolkit after scanning the device for commands and attribute with toolkit but both was getting it working after adding it new. tuya have make special commands color changes that is not standard but it looks working aslong only using standard commands on this devices (its one A series so its Zigbee certified that most tuya device is not).

Delete devices, restart HA, re-pair and that's it ! Big thanks to you ;-)