zha-device-handlers
zha-device-handlers copied to clipboard
[Device Support Request] Smartwings WM25/L-Z Blinds
Is your feature request related to a problem? Please describe.
Pressing the open and close buttons in the UI will not open nor close the blinds however using the position slider you can adjust the blinds. The blinds are also inverted, open is closed and closed is open
Describe the solution you'd like
I would like the open and close buttons to work, for the state to not be inverted.
Device signature
{
"node_descriptor": "NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 128>, manufacturer_code=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=True, *is_full_function_device=False, *is_mains_powered=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": 260,
"device_type": "0x0202",
"in_clusters": [
"0x0000",
"0x0001",
"0x0003",
"0x0004",
"0x0005",
"0x0102"
],
"out_clusters": [
"0x0003",
"0x0019"
]
}
},
"manufacturer": "Smartwings",
"model": "WM25/L-Z",
"class": "zigpy.device.Device"
}
Diagnostic information
{
"home_assistant": {
"installation_type": "Home Assistant Container",
"version": "2022.8.0",
"dev": false,
"hassio": false,
"virtualenv": false,
"python_version": "3.10.5",
"docker": true,
"arch": "aarch64",
"timezone": "America/Chicago",
"os_name": "Linux",
"os_version": "4.2.8",
"run_as_root": true
},
"custom_components": {
"alexa_media": {
"version": "4.1.0",
"requirements": [
"alexapy==1.26.1",
"packaging>=20.3",
"wrapt>=1.12.1"
]
},
"frigate": {
"version": "2.3",
"requirements": []
},
"hacs": {
"version": "1.26.2",
"requirements": [
"aiogithubapi>=22.2.4"
]
},
"mass": {
"version": "2022.8.0",
"requirements": [
"music-assistant==1.8.3"
]
}
},
"integration_manifest": {
"domain": "zha",
"name": "Zigbee Home Automation",
"config_flow": true,
"documentation": "https://www.home-assistant.io/integrations/zha",
"requirements": [
"bellows==0.31.2",
"pyserial==3.5",
"pyserial-asyncio==0.6",
"zha-quirks==0.0.78",
"zigpy-deconz==0.18.0",
"zigpy==0.48.0",
"zigpy-xbee==0.15.0",
"zigpy-zigate==0.9.0",
"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": 16874,
"manufacturer": "Smartwings",
"model": "WM25/L-Z",
"name": "Smartwings WM25/L-Z",
"quirk_applied": false,
"quirk_class": "zigpy.device.Device",
"manufacturer_code": 4098,
"power_source": "Battery or Unknown",
"lqi": 247,
"rssi": -63,
"last_seen": "2022-08-10T11:40:35",
"available": true,
"device_type": "EndDevice",
"signature": {
"node_descriptor": "NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 128>, manufacturer_code=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=True, *is_full_function_device=False, *is_mains_powered=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)",
"endpoints": {
"1": {
"profile_id": 260,
"device_type": "0x0202",
"in_clusters": [
"0x0000",
"0x0001",
"0x0003",
"0x0004",
"0x0005",
"0x0102"
],
"out_clusters": [
"0x0003",
"0x0019"
]
}
}
},
"active_coordinator": false,
"entities": [
{
"entity_id": "button.smartwings_wm25_l_z_identifybutton",
"name": "Smartwings WM25/L-Z"
},
{
"entity_id": "cover.smartwings_wm25_l_z_cover",
"name": "Smartwings WM25/L-Z"
},
{
"entity_id": "sensor.smartwings_wm25_l_z_battery",
"name": "Smartwings WM25/L-Z"
}
],
"neighbors": [],
"endpoint_names": [
{
"name": "WINDOW_COVERING_DEVICE"
}
],
"user_given_name": null,
"device_reg_id": "d4a3a0070de08547e4a2aa2cf5cfc297",
"area_id": "2c665d15682749eba33548a362e5471c",
"cluster_details": {
"1": {
"device_type": {
"name": "WINDOW_COVERING_DEVICE",
"id": 514
},
"profile_id": 260,
"in_clusters": {
"0x0000": {
"endpoint_attribute": "basic",
"attributes": {
"0x0004": {
"attribute_name": "manufacturer",
"value": "Smartwings"
},
"0x0005": {
"attribute_name": "model",
"value": "WM25/L-Z"
}
},
"unsupported_attributes": {}
},
"0x0001": {
"endpoint_attribute": "power",
"attributes": {
"0x0020": {
"attribute_name": "battery_voltage",
"value": 0
},
"0x0021": {
"attribute_name": "battery_percentage_remaining",
"value": 86
}
},
"unsupported_attributes": {
"0x0031": {
"attribute_name": "battery_size"
},
"0x0033": {
"attribute_name": "battery_quantity"
}
}
},
"0x0003": {
"endpoint_attribute": "identify",
"attributes": {},
"unsupported_attributes": {}
},
"0x0004": {
"endpoint_attribute": "groups",
"attributes": {},
"unsupported_attributes": {}
},
"0x0005": {
"endpoint_attribute": "scenes",
"attributes": {},
"unsupported_attributes": {}
},
"0x0102": {
"endpoint_attribute": "window_covering",
"attributes": {
"0x0000": {
"attribute_name": "window_covering_type",
"value": 0
},
"0x0007": {
"attribute_name": "config_status",
"value": 3
},
"0x0008": {
"attribute_name": "current_position_lift_percentage",
"value": 0
},
"0x0010": {
"attribute_name": "installed_open_limit_lift",
"value": 0
},
"0x0011": {
"attribute_name": "installed_closed_limit_lift",
"value": 65535
},
"0x0013": {
"attribute_name": "installed_closed_limit_tilt",
"value": 65535
},
"0x0017": {
"attribute_name": "window_covering_mode",
"value": 20
}
},
"unsupported_attributes": {
"0x0003": {
"attribute_name": "current_position_lift"
},
"0x0004": {
"attribute_name": "current_position_tilt"
},
"0x0009": {
"attribute_name": "current_position_tilt_percentage"
},
"0x0014": {
"attribute_name": "velocity_lift"
},
"0x0016": {
"attribute_name": "deceleration_time_lift"
}
}
}
},
"out_clusters": {
"0x0003": {
"endpoint_attribute": "identify",
"attributes": {},
"unsupported_attributes": {}
},
"0x0019": {
"endpoint_attribute": "ota",
"attributes": {},
"unsupported_attributes": {}
}
}
}
}
}
}
Additional logs
2022-08-10 12:03:32.105 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x41ea>, 1, 260, 258, b'\x08Y\n\x08\x00 d', 0, 175, 239, 190, 106, 252, 0, -64]
2022-08-10 12:03:32.105 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] Received ZCL frame: b'\x08Y\n\x08\x00 d'
2022-08-10 12:03:32.106 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=89, command_id=10, *is_reply=True)
2022-08-10 12:03:32.107 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] Decoded ZCL frame: WindowCovering:Report_Attributes(attribute_reports=[Attribute(attrid=0x0008, value=TypeValue(type=uint8_t, value=100))])
2022-08-10 12:03:32.107 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] Received command 0x0A (TSN 89): Report_Attributes(attribute_reports=[Attribute(attrid=0x0008, value=TypeValue(type=uint8_t, value=100))])
2022-08-10 12:03:32.107 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] Attribute report received: current_position_lift_percentage=100
2022-08-10 12:03:32.107 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x41EA:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 100
2022-08-10 12:03:32.107 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 100
2022-08-10 12:03:32.108 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.smartwings_wm25_l_z_cover, old_state=<state cover.smartwings_wm25_l_z_cover=closing; current_position=100, friendly_name=MasterBedroomBlinds, supported_features=15 @ 2022-08-10T12:03:12.624814-05:00>, new_state=<state cover.smartwings_wm25_l_z_cover=closed; current_position=0, friendly_name=MasterBedroomBlinds, supported_features=15 @ 2022-08-10T12:03:32.108134-05:00>>
2022-08-10 12:03:32.109 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] 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=89, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-10 12:03:32.109 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0102] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-10 12:03:32.114 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 89 under 134 request id, data: b'18590b0a00'
2022-08-10 12:03:32.115 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x41ea>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08590a08002064'
2022-08-10 12:03:32.118 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 134, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x41EA endpoint=1>, 260, 258, 1, b'\x18Y\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-10 12:03:32.121 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 134]
2022-08-10 12:03:32.131 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-10 12:03:32.131 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-10 12:03:32.133 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 134: 00
2022-08-10 12:03:32.133 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x86 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x41ea endpoint=1>, status: 0x00
2022-08-10 12:03:32.156 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-10T12:03:31.992250-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-10T12:03:32.155892-05:00>>
2022-08-10 12:03:32.570 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-10 12:03:32.570 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-10 12:03:32.572 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x41ea>, 1, 260, 1, b'\x08Z\n!\x00 U', 0, 175, 239, 190, 106, 252, 0, -64]
2022-08-10 12:03:32.572 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] Received ZCL frame: b'\x08Z\n!\x00 U'
2022-08-10 12:03:32.573 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=90, command_id=10, *is_reply=True)
2022-08-10 12:03:32.574 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] Decoded ZCL frame: PowerConfiguration:Report_Attributes(attribute_reports=[Attribute(attrid=0x0021, value=TypeValue(type=uint8_t, value=85))])
2022-08-10 12:03:32.574 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] Received command 0x0A (TSN 90): Report_Attributes(attribute_reports=[Attribute(attrid=0x0021, value=TypeValue(type=uint8_t, value=85))])
2022-08-10 12:03:32.574 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] Attribute report received: battery_percentage_remaining=85
2022-08-10 12:03:32.575 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.smartwings_wm25_l_z_battery, old_state=<state sensor.smartwings_wm25_l_z_battery=43; state_class=measurement, battery_voltage=0.0, unit_of_measurement=%, device_class=battery, friendly_name=Smartwings WM25/L-Z Battery @ 2022-08-10T11:02:43.488219-05:00>, new_state=<state sensor.smartwings_wm25_l_z_battery=42; state_class=measurement, battery_voltage=0.0, unit_of_measurement=%, device_class=battery, friendly_name=Smartwings WM25/L-Z Battery @ 2022-08-10T12:03:32.575158-05:00>>
2022-08-10 12:03:32.576 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] 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=90, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-10 12:03:32.576 DEBUG (MainThread) [zigpy.zcl] [0x41EA:1:0x0001] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-10 12:03:32.578 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 90 under 135 request id, data: b'185a0b0a00'
2022-08-10 12:03:32.579 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x41ea>, ep: 1, profile: 0x0104, cluster_id: 0x0001, data: b'085a0a21002055'
2022-08-10 12:03:32.579 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 135, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x41EA endpoint=1>, 260, 1, 1, b'\x18Z\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-10 12:03:32.582 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 135]
2022-08-10 12:03:32.590 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-10 12:03:32.590 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-10 12:03:32.592 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 135: 00
Additional context
Proposed Quirk - This makes the open and close button work correctly however the position is still inverted.
"""Device handler for Smartwings WM25L-Z roller blinds."""
from zigpy.profiles import zha
from zigpy.quirks import CustomCluster, CustomDevice
from zigpy.zcl.clusters.closures import WindowCovering
from zigpy.zcl.clusters.general import (
Basic,
Groups,
Identify,
Ota,
PowerConfiguration,
Scenes,
)
from zhaquirks.const import (
DEVICE_TYPE,
ENDPOINTS,
INPUT_CLUSTERS,
MODELS_INFO,
OUTPUT_CLUSTERS,
PROFILE_ID,
)
class InvertedWindowCoveringCluster(CustomCluster, WindowCovering):
"""WindowCovering cluster implementation.
This implementation inverts the reported covering percent for non standard
devices that don't follow the reporting spec.
"""
cluster_id = WindowCovering.cluster_id
CURRENT_POSITION_LIFT_PERCENTAGE = 0x0008
def _update_attribute(self, attrid, value):
if attrid == self.CURRENT_POSITION_LIFT_PERCENTAGE:
value = 100 - value
super()._update_attribute(attrid, value)
class WM25LBlinds(CustomDevice):
"""Custom device representing Smartwings WM25/L-Z roller blinds."""
signature = {
# <SimpleDescriptor endpoint=1 profile=260 device_type=514
# device_version=1
# input_clusters=[0, 1, 3, 4, 5, 32, 258]
# output_clusters=[3, 25]>
MODELS_INFO: [
("Smartwings", "WM25/L-Z"),
],
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
PowerConfiguration.cluster_id,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
WindowCovering.cluster_id,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
},
}
replacement = {
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
PowerConfiguration.cluster_id,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
InvertedWindowCoveringCluster,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
}
}
Can you please try to get this attribute values from the InvertedWindowCoveringCluster
:
- 0x0007 'config_status'
- 0x0017 'window_covering_mode'
Thanks in advance.
Thanks for your response! I really appreciate the help. Here's the requested info.
-
0x0007 | ConfigStatus.Online|Operational
-
0x0017 | WindowCoveringMode.16|Motor_in_maintenance_mode
I think that (in general) all the quirks to invert the cover behavior is nonsense, since the operation of each cover will depend on how the motor has been installed. For this reason all the covers have a mechanism to reverse the direction of rotation of the motor (normally with the remote).
If that's okay with you, I'd like to suggest trying to reverse the direction of rotation of the blind without using a quirk.
To do this, it would be necessary to remove the quirk from your installation (clear the cache and reboot) and try to assign a new value to the window_covering_mode
attribute.
The value to assign I think would be 1
I have no device to test and no experience with zigbee cover devices, but IMO there must be a better way to manage all that inverted cover issues.
I will certainly give this a try however without the quirk for some reason the cover-open and close commands don't work. You can control the blinds via percentage though. I also noticed that Home Assistant shows the battery percentage as 42% while reading the percentage remaining attribute shows 84%, which I'm guessing would need to be fixed with a quirk. I just don't understand why manufactures won't stick the the Zigbee spec...
I also noticed that Home Assistant shows the battery percentage as 42% while reading the percentage remaining attribute shows 84%, which I'm guessing would need to be fixed with a quirk.
It is common in Tuya devices to have to fix the battery value. You can use the DoublingPowerConfigurationCluster
for it.
Probably the quirk will be need anyway, but maybe the inverted part can be addressed in a better way. 👍🏻
After disabling the quirk and setting window_covering_mode attribute to 1 the state is no longer inverted. However the open and close buttons no longer provide control of the shade. Setting the position using the slider does control the shades. Any suggestion on making the cover.cover_close and cover.cover_open services work? Once again thank you very much for the suggestions.
I did re-write the quirk to only correct the battery information and that is working perfectly.
Could you share with us the values of the attributes again?
Any suggestion on making the cover.cover_close and cover.cover_open services work?
Ummm, could you try to add an attribute to your cover?
-
assumed_state: true
That can be done from the 'developers view' selecting the cover and adding to the device's attributes.
Meanwhile I'll check how can be fixed.
I'm not sure why are the controls disabled. Could you share the values from your device?:
- from the WindowCovering cluster attribute:
current_position_lift_percentage
- from the HA device status (from the developer tools view):
state
andcurrent_position
Are some of this values changing when you operate the cover with the slider?
From the WindowCovering cluster attribute:
current_position_lift_percentage:0
From Dev Tools View:
state: open
current_position: 100
friendly_name: MasterBedroomBlinds
supported_features: 15
The values only change when operating the cover with the slider. When using the slider the blinds function as expected. When using the open and close button the state will switch to opening or closing but the blinds don't perform any action.
🤦🏻♂️ I had misunderstood the problem. So the buttons are enabled in HA, but when you press them they only work when the quirk is configured, is that correct?
Could you attach the HA logs when you press the button in HA with the quirk and without the quirk (i.e. when it works and when it doesn't work).
You're correct. The buttons are enabled in HA, but only work when pressed if the quirk is configured with the inverted blind code as shown.
def _update_attribute(self, attrid, value):
if attrid == self.CURRENT_POSITION_LIFT_PERCENTAGE:
value = 100 - value
super()._update_attribute(attrid, value)
I find this strange because after setting the window_covering_mode
to 1 as suggested, the state in HA developer tab is no longer inverted, even without the quirk.
I believe the quirk will still be needed to double the battery percentage but one step at a time :smiley:
I won't be home until tomorrow so I won't be able to get you the log info until then.
Thanks again!
I had a chance to get the logs you requested. In both examples the Cover Up button was pressed. I did also learn that the state of the cover is correct without the quirk. However the controls are backwards. Please see below.
If the state=open and your call the cover.cover_open service the blinds will close.
If the state=closed and you call the cover.cover_close the blinds will open.
Logs with quirk enabled:
logs with quirk enabled
2022-08-13 12:20:05.545 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x1dff>, ep: 1, profile: 0x0104, cluster_id: 0x0300, data: b'083d0a0300218d37040021b6130700217201'
2022-08-13 12:20:05.546 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 143, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x1DFF endpoint=1>, 260, 768, 1, b'\x18=\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:05.548 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 143]
2022-08-13 12:20:05.557 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:05.558 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:05.559 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 143: 00
2022-08-13 12:20:05.560 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x8f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1dff endpoint=1>, status: 0x00
2022-08-13 12:20:05.583 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:05.419363-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:05.583318-05:00>>
2022-08-13 12:20:06.294 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA14F](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:20:06.295 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA14F](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:16.944 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:16.945 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:16.947 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa14f>, 1, 260, 8, b'\x08N\n\x00\x00 \x07', 0, 175, 223, 211, 22, 253, 0, -66]
2022-08-13 12:20:16.948 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] Received ZCL frame: b'\x08N\n\x00\x00 \x07'
2022-08-13 12:20:16.949 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=78, command_id=10, *is_reply=True)
2022-08-13 12:20:16.949 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=7))])
2022-08-13 12:20:16.950 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] Received command 0x0A (TSN 78): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=7))])
2022-08-13 12:20:16.950 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] Attribute report received: current_level=7
2022-08-13 12:20:16.950 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xA14F:1:0x0008]: received attribute: 0 update with value: 7
2022-08-13 12:20:16.951 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] 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=78, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:20:16.951 DEBUG (MainThread) [zigpy.zcl] [0xA14F:1:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:16.952 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 78 under 144 request id, data: b'184e0b0a00'
2022-08-13 12:20:16.952 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa14f>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'084e0a00002007'
2022-08-13 12:20:16.952 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 144, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xA14F endpoint=1>, 260, 8, 1, b'\x18N\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:16.958 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 144]
2022-08-13 12:20:16.967 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:16.968 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:16.969 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 144: 00
2022-08-13 12:20:16.970 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x90 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa14f endpoint=1>, status: 0x00
2022-08-13 12:20:17.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=59; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:19:52.012020-05:00>, new_state=<state sensor.cpu1_inference_speed=57; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:17.011228-05:00>>
2022-08-13 12:20:21.337 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.kid_s_cam, old_state=<state camera.kid_s_cam=idle; access_token=939bd28fdb3bc943501ef9609c89d654298388e4bb66aeabf7f5094389cf3d6, frontend_stream_type=hls, entity_picture=/api/camera_proxy/camera.kid_s_cam?token=939bd28fdb3bc943501ef9609c89d654298388e4bb66aeabf7f5094389cf3d6, friendly_name=Kid's Cam, supported_features=CameraEntityFeature.STREAM @ 2022-08-13T12:15:27.062670-05:00>, new_state=<state camera.kid_s_cam=idle; access_token=18ed82c6c952da9dc47b1c3455eaa872a2d622ce2e34f96ed07bf55b7f40cdbe, frontend_stream_type=hls, entity_picture=/api/camera_proxy/camera.kid_s_cam?token=18ed82c6c952da9dc47b1c3455eaa872a2d622ce2e34f96ed07bf55b7f40cdbe, friendly_name=Kid's Cam, supported_features=CameraEntityFeature.STREAM @ 2022-08-13T12:15:27.062670-05:00>>
2022-08-13 12:20:21.341 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.foyer, old_state=<state camera.foyer=recording; access_token=14a9049e857cd0df9516cad644f5099237f6ec14c783505cba1f993372704b7a, frontend_stream_type=hls, entity_picture=/api/camera_proxy/camera.foyer?token=14a9049e857cd0df9516cad644f5099237f6ec14c783505cba1f993372704b7a, friendly_name=Foyer, supported_features=2 @ 2022-08-13T12:15:32.791739-05:00>, new_state=<state camera.foyer=recording; access_token=8c917afe98abbc67824e5d5eff2ab88fa31ed275c00ac8ec4fef0a80b38cb619, frontend_stream_type=hls, entity_picture=/api/camera_proxy/camera.foyer?token=8c917afe98abbc67824e5d5eff2ab88fa31ed275c00ac8ec4fef0a80b38cb619, friendly_name=Foyer, supported_features=2 @ 2022-08-13T12:15:32.791739-05:00>>
2022-08-13 12:20:21.343 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.front_driveway, old_state=<state camera.front_driveway=recording; access_token=fce5b739698a122ee036da7521fb6a43e16415814bb49a5943bf7ba011906f0b, frontend_stream_type=hls, entity_picture=/api/camera_proxy/camera.front_driveway?token=fce5b739698a122ee036da7521fb6a43e16415814bb49a5943bf7ba011906f0b, friendly_name=Front Driveway, supported_features=2 @ 2022-08-13T12:15:32.803448-05:00>, new_state=<state camera.front_driveway=recording; access_token=a2bd46e9711d88d0faec80960a8009c0e210aa36736ee6bacab809b76d7b17c9, frontend_stream_type=hls, entity_picture=/api/camera_proxy/camera.front_driveway?token=a2bd46e9711d88d0faec80960a8009c0e210aa36736ee6bacab809b76d7b17c9, friendly_name=Front Driveway, supported_features=2 @ 2022-08-13T12:15:32.803448-05:00>>
2022-08-13 12:20:21.344 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.front_driveway_car, old_state=<state camera.front_driveway_car=active; access_token=31fdea6d5713ed400a79253b88052a8923b977343b7090a94512674fdabd2139, entity_picture=/api/camera_proxy/camera.front_driveway_car?token=31fdea6d5713ed400a79253b88052a8923b977343b7090a94512674fdabd2139, friendly_name=Front Driveway Car, supported_features=0 @ 2022-08-13T12:15:33.549097-05:00>, new_state=<state camera.front_driveway_car=active; access_token=70fc0a15d39f1b4048bb67eaae17d152c209fe863aa02c8da268ff5d1f9a3715, entity_picture=/api/camera_proxy/camera.front_driveway_car?token=70fc0a15d39f1b4048bb67eaae17d152c209fe863aa02c8da268ff5d1f9a3715, friendly_name=Front Driveway Car, supported_features=0 @ 2022-08-13T12:15:33.549097-05:00>>
2022-08-13 12:20:21.346 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.foyer_person, old_state=<state camera.foyer_person=active; access_token=3d08a9e3abfa9bce9914d7e5c55d3a3906ed06147cfda89e56eb13b05b7c850d, entity_picture=/api/camera_proxy/camera.foyer_person?token=3d08a9e3abfa9bce9914d7e5c55d3a3906ed06147cfda89e56eb13b05b7c850d, friendly_name=Foyer Person, supported_features=0 @ 2022-08-13T12:15:33.549423-05:00>, new_state=<state camera.foyer_person=active; access_token=1bf0f85977f89d2cacef7987d2c3597ef0698960dc0274748b7d3144a189f651, entity_picture=/api/camera_proxy/camera.foyer_person?token=1bf0f85977f89d2cacef7987d2c3597ef0698960dc0274748b7d3144a189f651, friendly_name=Foyer Person, supported_features=0 @ 2022-08-13T12:15:33.549423-05:00>>
2022-08-13 12:20:21.348 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.foyer_car, old_state=<state camera.foyer_car=active; access_token=76d15b4b94e29914d6b66de34e07446614fd09b5d69d94fad4ed7e2291a5dd5b, entity_picture=/api/camera_proxy/camera.foyer_car?token=76d15b4b94e29914d6b66de34e07446614fd09b5d69d94fad4ed7e2291a5dd5b, friendly_name=Foyer Car, supported_features=0 @ 2022-08-13T12:15:33.549728-05:00>, new_state=<state camera.foyer_car=active; access_token=eff8c8cd519d0c9401990d6042634fe6f1bc07b9367b67ccaf7e5d552c5d6a35, entity_picture=/api/camera_proxy/camera.foyer_car?token=eff8c8cd519d0c9401990d6042634fe6f1bc07b9367b67ccaf7e5d552c5d6a35, friendly_name=Foyer Car, supported_features=0 @ 2022-08-13T12:15:33.549728-05:00>>
2022-08-13 12:20:21.349 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=camera.front_driveway_person, old_state=<state camera.front_driveway_person=active; access_token=5537efedf27a866e33a3faa11c22246cba00ef13dd0105bb37f4c2604dba84b5, entity_picture=/api/camera_proxy/camera.front_driveway_person?token=5537efedf27a866e33a3faa11c22246cba00ef13dd0105bb37f4c2604dba84b5, friendly_name=Front Driveway Person, supported_features=0 @ 2022-08-13T12:15:33.550049-05:00>, new_state=<state camera.front_driveway_person=active; access_token=bbb1bfe93ff9e54be4d5c5a9733c2647b22895170b7fde4d7f9b375579ad5cbd, entity_picture=/api/camera_proxy/camera.front_driveway_person?token=bbb1bfe93ff9e54be4d5c5a9733c2647b22895170b7fde4d7f9b375579ad5cbd, friendly_name=Front Driveway Person, supported_features=0 @ 2022-08-13T12:15:33.550049-05:00>>
2022-08-13 12:20:22.009 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=0; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:02.012882-05:00>, new_state=<state sensor.detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:22.009031-05:00>>
2022-08-13 12:20:22.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=0; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:02.014060-05:00>, new_state=<state sensor.front_driveway_detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:22.009931-05:00>>
2022-08-13 12:20:22.339 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x96A7](E11-G13): Device seen - marking the device available and resetting counter
2022-08-13 12:20:22.339 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x96A7](E11-G13): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:27.012 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:22.009031-05:00>, new_state=<state sensor.detection_fps=11; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:27.012495-05:00>>
2022-08-13 12:20:27.013 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=57; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:17.011228-05:00>, new_state=<state sensor.cpu1_inference_speed=58; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:27.013153-05:00>>
2022-08-13 12:20:27.013 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:22.009931-05:00>, new_state=<state sensor.front_driveway_detection_fps=11; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:27.013739-05:00>>
2022-08-13 12:20:27.014 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_process_fps, old_state=<state sensor.front_driveway_process_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:17:02.011607-05:00>, new_state=<state sensor.front_driveway_process_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:27.014096-05:00>>
2022-08-13 12:20:32.009 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=11; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:27.012495-05:00>, new_state=<state sensor.detection_fps=17; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:32.009482-05:00>>
2022-08-13 12:20:32.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=58; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:27.013153-05:00>, new_state=<state sensor.cpu1_inference_speed=53; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:32.010139-05:00>>
2022-08-13 12:20:32.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=11; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:27.013739-05:00>, new_state=<state sensor.front_driveway_detection_fps=17; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:32.010758-05:00>>
2022-08-13 12:20:32.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_process_fps, old_state=<state sensor.front_driveway_process_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:27.014096-05:00>, new_state=<state sensor.front_driveway_process_fps=3; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:32.011136-05:00>>
2022-08-13 12:20:32.034 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.thermostat, old_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:20:02.033048+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:20:02.033048+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:20:02.033048+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:20:02.033048+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:20:02.033048+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T12:15:31.985727-05:00>, new_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:20:32.033768+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:20:32.033768+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:20:32.033768+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:20:32.033768+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:20:32.033768+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T12:15:31.985727-05:00>>
2022-08-13 12:20:33.467 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Choose at step 1: Frigate Event: Repeat at step 4: Executing step setting variables
2022-08-13 12:20:33.470 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Choose at step 1: Frigate Event: Repeating sequence: Iteration 12
2022-08-13 12:20:33.471 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Choose at step 1: Frigate Event: Repeat at step 4: Running automation actions
2022-08-13 12:20:33.471 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Choose at step 1: Frigate Event: Repeat at step 4: Executing step wait for trigger (timeout: 0:02:00)
2022-08-13 12:20:33.476 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Choose at step 1: Frigate Event: Repeat at step 4: Initialized trigger
2022-08-13 12:20:34.321 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8177](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:20:34.322 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8177](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:35.420 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:05.583318-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:35.420110-05:00>>
2022-08-13 12:20:35.582 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:35.420110-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:35.582299-05:00>>
2022-08-13 12:20:36.387 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event automation_triggered[L]: name=DrivewayCameraNotification, entity_id=automation.drivewaycameranotification, source=mqtt topic frigate/events>
2022-08-13 12:20:36.388 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.drivewaycameranotification, old_state=<state automation.drivewaycameranotification=on; last_triggered=2022-08-13T12:16:08.993100-05:00, mode=parallel, current=1, max=10, id=1651502775694, friendly_name=DrivewayCameraNotification @ 2022-08-13T12:15:33.255527-05:00>, new_state=<state automation.drivewaycameranotification=on; last_triggered=2022-08-13T12:20:36.387972-05:00, mode=parallel, current=2, max=10, id=1651502775694, friendly_name=DrivewayCameraNotification @ 2022-08-13T12:15:33.255527-05:00>>
2022-08-13 12:20:36.391 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Running automation actions
2022-08-13 12:20:36.393 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.drivewaycameranotification, old_state=<state automation.drivewaycameranotification=on; last_triggered=2022-08-13T12:20:36.387972-05:00, mode=parallel, current=2, max=10, id=1651502775694, friendly_name=DrivewayCameraNotification @ 2022-08-13T12:15:33.255527-05:00>, new_state=<state automation.drivewaycameranotification=on; last_triggered=2022-08-13T12:20:36.387972-05:00, mode=parallel, current=1, max=10, id=1651502775694, friendly_name=DrivewayCameraNotification @ 2022-08-13T12:15:33.255527-05:00>>
2022-08-13 12:20:36.397 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_car, old_state=<state sensor.front_driveway_car=3; unit_of_measurement=objects, icon=mdi:shield-car, friendly_name=Front Driveway Car @ 2022-08-13T12:16:14.731483-05:00>, new_state=<state sensor.front_driveway_car=4; unit_of_measurement=objects, icon=mdi:shield-car, friendly_name=Front Driveway Car @ 2022-08-13T12:20:36.396998-05:00>>
2022-08-13 12:20:37.051 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=17; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:32.009482-05:00>, new_state=<state sensor.detection_fps=18; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:37.051296-05:00>>
2022-08-13 12:20:37.052 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=53; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:32.010139-05:00>, new_state=<state sensor.cpu1_inference_speed=56; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:37.051967-05:00>>
2022-08-13 12:20:37.052 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=17; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:32.010758-05:00>, new_state=<state sensor.front_driveway_detection_fps=18; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:37.052651-05:00>>
2022-08-13 12:20:37.266 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:37.267 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:37.269 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [35, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 25, b'\x01\x1a\x01\x00\x02\x10\x00\x00\x02\x00\x00\x00', 0, 175, 127, 214, 22, 253, 0, -78]
2022-08-13 12:20:37.269 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] Received ZCL frame: b'\x01\x1a\x01\x00\x02\x10\x00\x00\x02\x00\x00\x00'
2022-08-13 12:20:37.270 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=26, command_id=1, *is_reply=False)
2022-08-13 12:20:37.271 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] Decoded ZCL frame: Ota:query_next_image(field_control=<FieldControl.0: 0>, manufacturer_code=4098, image_type=0, current_file_version=2)
2022-08-13 12:20:37.271 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] Received command 0x01 (TSN 26): query_next_image(field_control=<FieldControl.0: 0>, manufacturer_code=4098, image_type=0, current_file_version=2)
2022-08-13 12:20:37.273 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] OTA query_next_image handler for 'Smartwings WM25/L-Z': field_control=FieldControl.0, manufacture_id=4098, image_type=0, current_file_version=2, hardware_version=None, model=WM25/L-Z
2022-08-13 12:20:37.273 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] No OTA image is available
2022-08-13 12:20:37.274 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=26, command_id=2, *is_reply=True)
2022-08-13 12:20:37.274 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0019] Sending reply: query_next_image_response(status=<Status.NO_IMAGE_AVAILABLE: 152>, manufacturer_code=None, image_type=None, file_version=None, image_size=None)
2022-08-13 12:20:37.275 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 26 under 145 request id, data: b'191a0298'
2022-08-13 12:20:37.275 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0019, data: b'011a01000210000002000000'
2022-08-13 12:20:37.276 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (19, 145, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8279 endpoint=1>, 260, 25, 1, b'\x19\x1a\x02\x98', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:37.278 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 145]
2022-08-13 12:20:37.289 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:37.290 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:37.291 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 145: 00
2022-08-13 12:20:37.291 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x91 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8279 endpoint=1>, status: 0x00
2022-08-13 12:20:37.624 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=open_cover, service_data=entity_id=cover.smartwings_wm25_l_z_cover>
2022-08-13 12:20:37.628 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=146, command_id=0, *is_reply=False)
2022-08-13 12:20:37.628 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Sending request: up_open()
2022-08-13 12:20:37.629 DEBUG (MainThread) [zigpy.device] [0x8279] Extending timeout for 0x92 request
2022-08-13 12:20:37.629 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 146 under 147 request id, data: b'019200'
2022-08-13 12:20:37.629 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 147, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8279 endpoint=1>, 260, 258, 1, b'\x01\x92\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:37.633 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 147]
2022-08-13 12:20:37.642 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:37.643 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:37.645 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 147: 00
2022-08-13 12:20:37.646 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x93 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8279 endpoint=1>, status: 0x00
2022-08-13 12:20:38.333 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5389](LIGHTIFY Dimming Switch): Device seen - marking the device available and resetting counter
2022-08-13 12:20:38.334 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5389](LIGHTIFY Dimming Switch): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:38.371 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA61D](S31 Lite zb): Device seen - marking the device available and resetting counter
2022-08-13 12:20:38.372 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA61D](S31 Lite zb): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:38.384 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8279](WM25/L-Z): Device seen - marking the device available and resetting counter
2022-08-13 12:20:38.384 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8279](WM25/L-Z): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:38.614 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:38.615 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:38.616 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x25b7>, 1, 260, 8, b'\x087\n\x00\x00 \xf8', 0, 175, 255, 214, 22, 253, 0, -55]
2022-08-13 12:20:38.617 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] Received ZCL frame: b'\x087\n\x00\x00 \xf8'
2022-08-13 12:20:38.617 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=10, *is_reply=True)
2022-08-13 12:20:38.618 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=248))])
2022-08-13 12:20:38.618 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] Received command 0x0A (TSN 55): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=248))])
2022-08-13 12:20:38.619 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] Attribute report received: current_level=248
2022-08-13 12:20:38.619 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x25B7:1:0x0008]: received attribute: 0 update with value: 248
2022-08-13 12:20:38.620 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] 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=55, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:20:38.620 DEBUG (MainThread) [zigpy.zcl] [0x25B7:1:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:38.621 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 55 under 148 request id, data: b'18370b0a00'
2022-08-13 12:20:38.624 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x25b7>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'08370a000020f8'
2022-08-13 12:20:38.627 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 148, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x25B7 endpoint=1>, 260, 8, 1, b'\x187\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:38.631 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 148]
2022-08-13 12:20:38.641 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:38.642 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:38.643 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 148: 00
2022-08-13 12:20:38.644 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x94 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x25b7 endpoint=1>, status: 0x00
2022-08-13 12:20:38.948 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:38.949 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:38.950 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x1dff>, 1, 260, 8, b'\x08>\n\x00\x00 \n', 0, 175, 223, 214, 22, 253, 0, -66]
2022-08-13 12:20:38.951 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] Received ZCL frame: b'\x08>\n\x00\x00 \n'
2022-08-13 12:20:38.951 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=62, command_id=10, *is_reply=True)
2022-08-13 12:20:38.952 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=10))])
2022-08-13 12:20:38.952 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] Received command 0x0A (TSN 62): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=10))])
2022-08-13 12:20:38.953 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] Attribute report received: current_level=10
2022-08-13 12:20:38.953 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1DFF:1:0x0008]: received attribute: 0 update with value: 10
2022-08-13 12:20:38.954 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] 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=62, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:20:38.954 DEBUG (MainThread) [zigpy.zcl] [0x1DFF:1:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:38.958 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 62 under 149 request id, data: b'183e0b0a00'
2022-08-13 12:20:38.960 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x1dff>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'083e0a0000200a'
2022-08-13 12:20:38.961 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 149, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x1DFF endpoint=1>, 260, 8, 1, b'\x18>\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:38.962 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 149]
2022-08-13 12:20:38.972 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:38.973 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:38.974 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 149: 00
2022-08-13 12:20:38.974 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x95 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1dff endpoint=1>, status: 0x00
2022-08-13 12:20:41.244 INFO (MainThread) [homeassistant.components.automation.drivewaycameranotification] DrivewayCameraNotification: Choose at step 1: Frigate Event: Repeat at step 4: Executing step setting variables
2022-08-13 12:20:41.249 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=automation.drivewaycameranotification, old_state=<state automation.drivewaycameranotification=on; last_triggered=2022-08-13T12:20:36.387972-05:00, mode=parallel, current=1, max=10, id=1651502775694, friendly_name=DrivewayCameraNotification @ 2022-08-13T12:15:33.255527-05:00>, new_state=<state automation.drivewaycameranotification=on; last_triggered=2022-08-13T12:20:36.387972-05:00, mode=parallel, current=0, max=10, id=1651502775694, friendly_name=DrivewayCameraNotification @ 2022-08-13T12:15:33.255527-05:00>>
2022-08-13 12:20:41.331 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:41.332 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:41.340 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x92\x0b\x00\x00', 0, 175, 127, 214, 22, 253, 0, -78]
2022-08-13 12:20:41.341 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x92\x0b\x00\x00'
2022-08-13 12:20:41.341 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=146, command_id=11, *is_reply=True)
2022-08-13 12:20:41.342 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: InvertedWindowCoveringCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:41.344 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08920b0000'
2022-08-13 12:20:41.345 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8279:1:0x0102]: executed 'up_open' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:41.345 DEBUG (MainThread) [homeassistant.components.zha.cover] state=opening
2022-08-13 12:20:41.345 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.smartwings_wm25_l_z_cover, old_state=<state cover.smartwings_wm25_l_z_cover=closed; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:15:35.370407-05:00>, new_state=<state cover.smartwings_wm25_l_z_cover=opening; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:20:41.345868-05:00>>
2022-08-13 12:20:41.352 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:41.353 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:41.355 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x92\x0b\x00\x81', 0, 175, 127, 214, 22, 253, 0, -78]
2022-08-13 12:20:41.356 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x92\x0b\x00\x81'
2022-08-13 12:20:41.356 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=146, command_id=11, *is_reply=True)
2022-08-13 12:20:41.357 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: InvertedWindowCoveringCluster:Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:20:41.357 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 146): Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:20:41.360 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08920b0081'
2022-08-13 12:20:41.448 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_car, old_state=<state sensor.front_driveway_car=4; unit_of_measurement=objects, icon=mdi:shield-car, friendly_name=Front Driveway Car @ 2022-08-13T12:20:36.396998-05:00>, new_state=<state sensor.front_driveway_car=3; unit_of_measurement=objects, icon=mdi:shield-car, friendly_name=Front Driveway Car @ 2022-08-13T12:20:41.448111-05:00>>
2022-08-13 12:20:42.012 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=18; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:37.051296-05:00>, new_state=<state sensor.detection_fps=15; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:42.011946-05:00>>
2022-08-13 12:20:42.012 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=56; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:37.051967-05:00>, new_state=<state sensor.cpu1_inference_speed=58; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:42.012599-05:00>>
2022-08-13 12:20:42.013 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=18; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:37.052651-05:00>, new_state=<state sensor.front_driveway_detection_fps=15; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:42.013191-05:00>>
2022-08-13 12:20:42.013 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_process_fps, old_state=<state sensor.front_driveway_process_fps=3; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:32.011136-05:00>, new_state=<state sensor.front_driveway_process_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:42.013549-05:00>>
2022-08-13 12:20:42.190 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:42.191 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:42.193 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb54b>, 1, 260, 8, b'\x088\n\x00\x00 \xfa', 0, 175, 231, 214, 22, 253, 0, -65]
2022-08-13 12:20:42.194 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Received ZCL frame: b'\x088\n\x00\x00 \xfa'
2022-08-13 12:20:42.194 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=10, *is_reply=True)
2022-08-13 12:20:42.195 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=250))])
2022-08-13 12:20:42.195 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Received command 0x0A (TSN 56): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=250))])
2022-08-13 12:20:42.196 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Attribute report received: current_level=250
2022-08-13 12:20:42.196 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB54B:1:0x0008]: received attribute: 0 update with value: 250
2022-08-13 12:20:42.197 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:20:42.197 DEBUG (MainThread) [zigpy.zcl] [0xB54B:1:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:42.198 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 56 under 150 request id, data: b'18380b0a00'
2022-08-13 12:20:42.198 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb54b>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'08380a000020fa'
2022-08-13 12:20:42.198 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 150, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB54B endpoint=1>, 260, 8, 1, b'\x188\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:42.200 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 150]
2022-08-13 12:20:42.210 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:42.211 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:42.213 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 150: 00
2022-08-13 12:20:42.214 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x96 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb54b endpoint=1>, status: 0x00
2022-08-13 12:20:46.933 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:46.934 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:46.936 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8177>, 1, 260, 8, b'\x088\n\x00\x00 \xfe', 0, 175, 255, 226, 22, 253, 0, -58]
2022-08-13 12:20:46.937 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Received ZCL frame: b'\x088\n\x00\x00 \xfe'
2022-08-13 12:20:46.937 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=10, *is_reply=True)
2022-08-13 12:20:46.938 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254))])
2022-08-13 12:20:46.938 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Received command 0x0A (TSN 56): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254))])
2022-08-13 12:20:46.939 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Attribute report received: current_level=254
2022-08-13 12:20:46.939 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8177:1:0x0008]: received attribute: 0 update with value: 254
2022-08-13 12:20:46.939 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=56, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:20:46.940 DEBUG (MainThread) [zigpy.zcl] [0x8177:1:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:46.940 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 56 under 151 request id, data: b'18380b0a00'
2022-08-13 12:20:46.941 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8177>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'08380a000020fe'
2022-08-13 12:20:46.941 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 151, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8177 endpoint=1>, 260, 8, 1, b'\x188\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:46.947 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 151]
2022-08-13 12:20:46.955 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:46.956 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:46.958 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 151: 00
2022-08-13 12:20:46.958 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x97 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8177 endpoint=1>, status: 0x00
2022-08-13 12:20:47.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=15; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:42.011946-05:00>, new_state=<state sensor.detection_fps=7; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:47.011134-05:00>>
2022-08-13 12:20:47.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=58; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:42.012599-05:00>, new_state=<state sensor.cpu1_inference_speed=59; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:20:47.011779-05:00>>
2022-08-13 12:20:47.012 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=15; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:42.013191-05:00>, new_state=<state sensor.front_driveway_detection_fps=7; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:47.012385-05:00>>
2022-08-13 12:20:47.014 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_process_fps, old_state=<state sensor.front_driveway_process_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:42.013549-05:00>, new_state=<state sensor.front_driveway_process_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Process Fps @ 2022-08-13T12:20:47.012749-05:00>>
2022-08-13 12:20:49.301 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1DFF](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:20:49.302 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1DFF](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:50.714 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:50.715 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:50.723 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9878>, 1, 260, 8, b'\x087\n\x00\x00 \xfe', 0, 175, 183, 226, 22, 253, 0, -71]
2022-08-13 12:20:50.724 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] Received ZCL frame: b'\x087\n\x00\x00 \xfe'
2022-08-13 12:20:50.724 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=55, command_id=10, *is_reply=True)
2022-08-13 12:20:50.725 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254))])
2022-08-13 12:20:50.726 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] Received command 0x0A (TSN 55): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=254))])
2022-08-13 12:20:50.726 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] Attribute report received: current_level=254
2022-08-13 12:20:50.726 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9878:1:0x0008]: received attribute: 0 update with value: 254
2022-08-13 12:20:50.727 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] 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=55, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:20:50.728 DEBUG (MainThread) [zigpy.zcl] [0x9878:1:0x0008] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:50.734 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 55 under 152 request id, data: b'18370b0a00'
2022-08-13 12:20:50.734 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9878>, ep: 1, profile: 0x0104, cluster_id: 0x0008, data: b'08370a000020fe'
2022-08-13 12:20:50.735 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 152, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x9878 endpoint=1>, 260, 8, 1, b'\x187\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:20:50.740 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 152]
2022-08-13 12:20:50.748 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:20:50.749 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:20:50.751 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 152: 00
2022-08-13 12:20:50.752 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x98 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9878 endpoint=1>, status: 0x00
2022-08-13 12:20:52.009 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=7; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:47.011134-05:00>, new_state=<state sensor.detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:52.009347-05:00>>
2022-08-13 12:20:52.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=7; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:47.012385-05:00>, new_state=<state sensor.front_driveway_detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:52.010213-05:00>>
2022-08-13 12:20:54.307 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x25B7](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:20:54.307 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x25B7](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:57.017 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:52.009347-05:00>, new_state=<state sensor.detection_fps=0; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:20:57.017581-05:00>>
2022-08-13 12:20:57.018 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.front_driveway_detection_fps, old_state=<state sensor.front_driveway_detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:52.010213-05:00>, new_state=<state sensor.front_driveway_detection_fps=0; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Front Driveway Detection Fps @ 2022-08-13T12:20:57.018441-05:00>>
2022-08-13 12:20:58.352 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x362F](TRADFRI bulb GU10 WW 400lm): Device seen - marking the device available and resetting counter
2022-08-13 12:20:58.352 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x362F](TRADFRI bulb GU10 WW 400lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:20:59.330 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9878](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:20:59.330 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9878](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:21:02.034 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.thermostat, old_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:20:32.033768+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:20:32.033768+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:20:32.033768+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:20:32.033768+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:20:32.033768+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T12:15:31.985727-05:00>, new_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:21:02.033630+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:21:02.033630+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:21:02.033630+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:21:02.033630+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:21:02.033630+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T12:15:31.985727-05:00>>
2022-08-13 12:21:05.418 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:20:35.582299-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:21:05.418195-05:00>>
2022-08-13 12:21:05.582 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:21:05.418195-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:21:05.582186-05:00>>
2022-08-13 12:21:08.022 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event ios.entered_background[R]: sourceDeviceID=lwiphone, sourceDeviceName=lwiphone, sourceDevicePermanentID=9CFE263D-1651-489D-99FD-C9CDA459BA49>
2022-08-13 12:21:08.232 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [4395939641472] Connection closed by client
2022-08-13 12:21:09.084 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [4395855605104] Connection closed by client
2022-08-13 12:21:09.289 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5D4F](TRADFRI bulb E26 CWS opal 600lm): Device seen - marking the device available and resetting counter
2022-08-13 12:21:09.289 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5D4F](TRADFRI bulb E26 CWS opal 600lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:21:09.312 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB54B](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:21:09.312 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB54B](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:21:14.295 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA14F](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:21:14.295 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA14F](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:21:14.344 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x869B](E11-G13): Device seen - marking the device available and resetting counter
2022-08-13 12:21:14.344 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x869B](E11-G13): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:21:18.359 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF169](TRADFRI bulb GU10 WW 400lm): Device seen - marking the device available and resetting counter
2022-08-13 12:21:18.359 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF169](TRADFRI bulb GU10 WW 400lm): Update device availability - device available: True - new availability: True - changed: False
Logs without quirk enabled.
Logs without quirk:
2022-08-13 12:06:39.934 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5389:1:0x0020]: executed 'set_long_poll_interval' command with args: '(24,)' kwargs: '{}' result: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-13 12:06:39.935 DEBUG (MainThread) [zigpy.zcl] [0x5389:1:0x0020] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=25, command_id=1, *is_reply=False)
2022-08-13 12:06:39.935 DEBUG (MainThread) [zigpy.zcl] [0x5389:1:0x0020] Sending request: fast_poll_stop()
2022-08-13 12:06:39.935 DEBUG (MainThread) [zigpy.device] [0x5389] Extending timeout for 0x19 request
2022-08-13 12:06:39.936 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 25 under 26 request id, data: b'011901'
2022-08-13 12:06:39.936 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 26, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x5389 endpoint=1>, 260, 32, 1, b'\x01\x19\x01', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:06:39.941 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 26]
2022-08-13 12:06:39.948 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:06:39.949 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:06:39.951 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 26: 00
2022-08-13 12:06:39.952 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x5389 endpoint=1>, status: 0x00
2022-08-13 12:06:40.271 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.thermostat, old_state=<state weather.thermostat=partlycloudy; temperature=69, temperature_unit=°F, humidity=81, pressure=30.0, pressure_unit=inHg, wind_bearing=38, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 38, 'datetime': '2022-08-13T17:06:10.270642+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:06:10.270642+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:06:10.270642+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:06:10.270642+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:06:10.270642+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 16:32:14 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>, new_state=<state weather.thermostat=partlycloudy; temperature=69, temperature_unit=°F, humidity=81, pressure=30.0, pressure_unit=inHg, wind_bearing=38, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 38, 'datetime': '2022-08-13T17:06:40.271361+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:06:40.271361+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:06:40.271361+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:06:40.271361+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:06:40.271361+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 16:32:14 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>>
2022-08-13 12:06:40.420 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:06:40.421 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:06:40.423 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5389>, 1, 260, 32, b'\x08\x19\x0b\x01\x00', 0, 175, 255, 227, 21, 253, 0, -62]
2022-08-13 12:06:40.424 DEBUG (MainThread) [zigpy.zcl] [0x5389:1:0x0020] Received ZCL frame: b'\x08\x19\x0b\x01\x00'
2022-08-13 12:06:40.425 DEBUG (MainThread) [zigpy.zcl] [0x5389:1:0x0020] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=25, command_id=11, *is_reply=True)
2022-08-13 12:06:40.425 DEBUG (MainThread) [zigpy.zcl] [0x5389:1:0x0020] Decoded ZCL frame: PollControl:Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-08-13 12:06:40.427 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5389>, ep: 1, profile: 0x0104, cluster_id: 0x0020, data: b'08190b0100'
2022-08-13 12:06:40.428 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5389:1:0x0020]: executed 'fast_poll_stop' command with args: '()' kwargs: '{}' result: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-08-13 12:06:49.580 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8279](WM25/L-Z): Device seen - marking the device available and resetting counter
2022-08-13 12:06:49.580 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8279](WM25/L-Z): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:06:49.648 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x25B7](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:06:49.648 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x25B7](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:06:49.668 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9878](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:06:49.669 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9878](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:06:49.891 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:06:49.892 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:06:49.895 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa61d>, 1, 260, 6, b'\x08\x04\n\x00\x00\x10\x00', 0, 175, 127, 238, 21, 253, 0, -78]
2022-08-13 12:06:49.896 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] Received ZCL frame: b'\x08\x04\n\x00\x00\x10\x00'
2022-08-13 12:06:49.897 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=4, command_id=10, *is_reply=True)
2022-08-13 12:06:49.898 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-08-13 12:06:49.899 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] Received command 0x0A (TSN 4): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-08-13 12:06:49.899 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] Attribute report received: on_off=<Bool.false: 0>
2022-08-13 12:06:49.908 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] 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=4, command_id=<GeneralCommand.Default_Response: 11>, *is_reply=True)
2022-08-13 12:06:49.909 DEBUG (MainThread) [zigpy.zcl] [0xA61D:1:0x0006] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-13 12:06:49.914 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 4 under 27 request id, data: b'18040b0a00'
2022-08-13 12:06:49.914 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa61d>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'08040a00001000'
2022-08-13 12:06:49.917 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 27, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=42525 endpoint=1>, 260, 6, 1, b'\x18\x04\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:06:49.919 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 27]
2022-08-13 12:06:49.930 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:06:49.932 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:06:49.933 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 27: 00
2022-08-13 12:06:49.934 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa61d endpoint=1>, status: 0x00
2022-08-13 12:06:51.681 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x362F](TRADFRI bulb GU10 WW 400lm): Device seen - marking the device available and resetting counter
2022-08-13 12:06:51.681 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x362F](TRADFRI bulb GU10 WW 400lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:06:51.692 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF169](TRADFRI bulb GU10 WW 400lm): Device seen - marking the device available and resetting counter
2022-08-13 12:06:51.692 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF169](TRADFRI bulb GU10 WW 400lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:04.649 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB54B](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:07:04.649 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB54B](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:05.422 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:06:35.586618-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:05.421902-05:00>>
2022-08-13 12:07:05.585 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:05.421902-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:05.585864-05:00>>
2022-08-13 12:07:09.632 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA14F](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:07:09.632 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xA14F](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:09.639 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1DFF](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:07:09.639 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1DFF](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:09.663 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8177](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:07:09.664 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8177](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:09.676 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x869B](E11-G13): Device seen - marking the device available and resetting counter
2022-08-13 12:07:09.676 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x869B](E11-G13): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:10.273 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.thermostat, old_state=<state weather.thermostat=partlycloudy; temperature=69, temperature_unit=°F, humidity=81, pressure=30.0, pressure_unit=inHg, wind_bearing=38, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 38, 'datetime': '2022-08-13T17:06:40.271361+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:06:40.271361+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:06:40.271361+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:06:40.271361+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:06:40.271361+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 16:32:14 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>, new_state=<state weather.thermostat=partlycloudy; temperature=69, temperature_unit=°F, humidity=81, pressure=30.0, pressure_unit=inHg, wind_bearing=38, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 38, 'datetime': '2022-08-13T17:07:10.272790+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:07:10.272790+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:07:10.272790+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:07:10.272790+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:07:10.272790+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 16:32:14 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>>
2022-08-13 12:07:19.626 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5D4F](TRADFRI bulb E26 CWS opal 600lm): Device seen - marking the device available and resetting counter
2022-08-13 12:07:19.627 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5D4F](TRADFRI bulb E26 CWS opal 600lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:19.782 DEBUG (MainThread) [homeassistant.components.zha.core.device] [42525](S31 Lite zb): Device seen - marking the device available and resetting counter
2022-08-13 12:07:19.783 DEBUG (MainThread) [homeassistant.components.zha.core.device] [42525](S31 Lite zb): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:19.954 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event ios.became_active[R]: sourceDeviceID=lwiphone, sourceDeviceName=lwiphone, sourceDevicePermanentID=9CFE263D-1651-489D-99FD-C9CDA459BA49>
2022-08-13 12:07:20.305 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_distance, old_state=<state sensor.lwiphone_distance=1795; unit_of_measurement=m, icon=mdi:hiking, friendly_name=lwiphone Distance @ 2022-08-13T11:59:22.082193-05:00>, new_state=<state sensor.lwiphone_distance=1831; unit_of_measurement=m, icon=mdi:hiking, friendly_name=lwiphone Distance @ 2022-08-13T12:07:20.305670-05:00>>
2022-08-13 12:07:20.306 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_floors_descended, old_state=<state sensor.lwiphone_floors_descended=1; unit_of_measurement=floors, icon=mdi:stairs-down, friendly_name=lwiphone Floors Descended @ 2022-08-13T11:49:45.703017-05:00>, new_state=<state sensor.lwiphone_floors_descended=2; unit_of_measurement=floors, icon=mdi:stairs-down, friendly_name=lwiphone Floors Descended @ 2022-08-13T12:07:20.306861-05:00>>
2022-08-13 12:07:20.307 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_steps, old_state=<state sensor.lwiphone_steps=2868; unit_of_measurement=steps, icon=mdi:walk, friendly_name=lwiphone Steps @ 2022-08-13T11:59:22.083444-05:00>, new_state=<state sensor.lwiphone_steps=2933; unit_of_measurement=steps, icon=mdi:walk, friendly_name=lwiphone Steps @ 2022-08-13T12:07:20.307520-05:00>>
2022-08-13 12:07:20.308 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_battery_level, old_state=<state sensor.lwiphone_battery_level=65; unit_of_measurement=%, device_class=battery, icon=mdi:battery-60, friendly_name=lwiphone Battery Level @ 2022-08-13T11:57:02.063473-05:00>, new_state=<state sensor.lwiphone_battery_level=64; unit_of_measurement=%, device_class=battery, icon=mdi:battery-60, friendly_name=lwiphone Battery Level @ 2022-08-13T12:07:20.308397-05:00>>
2022-08-13 12:07:20.309 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_storage, old_state=<state sensor.lwiphone_storage=68.61; Available=79.49 GB, Available (Important)=91.50 GB, Available (Opportunistic)=87.73 GB, Total=127.87 GB, unit_of_measurement=% available, icon=mdi:database, friendly_name=lwiphone Storage @ 2022-08-13T12:04:05.694423-05:00>, new_state=<state sensor.lwiphone_storage=68.60; Available=79.48 GB, Available (Important)=91.50 GB, Available (Opportunistic)=87.72 GB, Total=127.87 GB, unit_of_measurement=% available, icon=mdi:database, friendly_name=lwiphone Storage @ 2022-08-13T12:07:20.309312-05:00>>
2022-08-13 12:07:20.317 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_last_update_trigger, old_state=<state sensor.lwiphone_last_update_trigger=Signaled; icon=mdi:cellphone-wireless, friendly_name=lwiphone Last Update Trigger @ 2022-08-13T12:04:33.771156-05:00>, new_state=<state sensor.lwiphone_last_update_trigger=Launch; icon=mdi:cellphone-wireless, friendly_name=lwiphone Last Update Trigger @ 2022-08-13T12:07:20.317070-05:00>>
2022-08-13 12:07:25.669 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5389](LIGHTIFY Dimming Switch): Device seen - marking the device available and resetting counter
2022-08-13 12:07:25.669 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5389](LIGHTIFY Dimming Switch): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:26.455 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=open_cover, service_data=entity_id=cover.smartwings_wm25_l_z_cover>
2022-08-13 12:07:26.458 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=28, command_id=0, *is_reply=False)
2022-08-13 12:07:26.458 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Sending request: up_open()
2022-08-13 12:07:26.459 DEBUG (MainThread) [zigpy.device] [0x8279] Extending timeout for 0x1c request
2022-08-13 12:07:26.459 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 28 under 29 request id, data: b'011c00'
2022-08-13 12:07:26.459 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 29, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8279 endpoint=1>, 260, 258, 1, b'\x01\x1c\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:07:26.463 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 29]
2022-08-13 12:07:26.471 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:07:26.472 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:07:26.474 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 29: 00
2022-08-13 12:07:26.475 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8279 endpoint=1>, status: 0x00
2022-08-13 12:07:27.474 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:07:27.475 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:07:27.477 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1c\x0b\x00\x00', 0, 175, 127, 243, 21, 253, 0, -78]
2022-08-13 12:07:27.478 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1c\x0b\x00\x00'
2022-08-13 12:07:27.478 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=11, *is_reply=True)
2022-08-13 12:07:27.479 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:07:27.482 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081c0b0000'
2022-08-13 12:07:27.482 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8279:1:0x0102]: executed 'up_open' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:07:27.482 DEBUG (MainThread) [homeassistant.components.zha.cover] state=opening
2022-08-13 12:07:27.482 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.smartwings_wm25_l_z_cover, old_state=<state cover.smartwings_wm25_l_z_cover=closed; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:05:28.654911-05:00>, new_state=<state cover.smartwings_wm25_l_z_cover=opening; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:07:27.482935-05:00>>
2022-08-13 12:07:27.490 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:07:27.491 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:07:27.493 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1c\x0b\x00\x81', 0, 175, 119, 243, 21, 253, 0, -79]
2022-08-13 12:07:27.494 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1c\x0b\x00\x81'
2022-08-13 12:07:27.494 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=11, *is_reply=True)
2022-08-13 12:07:27.495 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:07:27.495 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 28): Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:07:27.496 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081c0b0081'
2022-08-13 12:07:29.490 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:07:29.490 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:07:29.492 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1c\x0b\x00\x00', 0, 175, 135, 243, 21, 253, 0, -77]
2022-08-13 12:07:29.493 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1c\x0b\x00\x00'
2022-08-13 12:07:29.493 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=11, *is_reply=True)
2022-08-13 12:07:29.494 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:07:29.494 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 28): Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:07:29.494 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081c0b0000'
2022-08-13 12:07:29.495 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:07:29.499 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1c\x0b\x00\x81', 0, 175, 127, 101, 21, 253, 0, -78]
2022-08-13 12:07:29.500 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1c\x0b\x00\x81'
2022-08-13 12:07:29.501 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=11, *is_reply=True)
2022-08-13 12:07:29.501 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:07:29.501 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 28): Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:07:29.503 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081c0b0081'
2022-08-13 12:07:32.112 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.tasmota_power_strip_status, old_state=<state sensor.tasmota_power_strip_status=64; Version=8.2.0(tasmota), BuildDateTime=2020-03-20T14:45:23, Core=STAGE, SDK=2.2.2-dev(38a443e), Module=ACENX 3-Outlet, RestartReason=Software/System restart, Uptime=112T20:05:08, WiFi LinkCount=16, WiFi Downtime=0T00:00:36, MqttCount=21, BootCount=275, SaveCount=432, IPAddress=192.168.2.211, RSSI=64, LoadAvg=19, unit_of_measurement=%, icon=mdi:information-outline, friendly_name=Tasmota-Power-Strip status @ 2022-08-13T12:02:32.071586-05:00>, new_state=<state sensor.tasmota_power_strip_status=64; Version=8.2.0(tasmota), BuildDateTime=2020-03-20T14:45:23, Core=STAGE, SDK=2.2.2-dev(38a443e), Module=ACENX 3-Outlet, RestartReason=Software/System restart, Uptime=112T20:10:09, WiFi LinkCount=16, WiFi Downtime=0T00:00:36, MqttCount=21, BootCount=275, SaveCount=432, IPAddress=192.168.2.211, RSSI=64, LoadAvg=19, unit_of_measurement=%, icon=mdi:information-outline, friendly_name=Tasmota-Power-Strip status @ 2022-08-13T12:02:32.071586-05:00>>
2022-08-13 12:07:35.426 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:05.585864-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:35.425955-05:00>>
2022-08-13 12:07:35.584 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:35.425955-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:35.584428-05:00>>
2022-08-13 12:07:38.734 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:07:38.735 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:07:38.737 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1c\x0b\x00\x81', 0, 175, 135, 243, 21, 253, 0, -77]
2022-08-13 12:07:38.738 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1c\x0b\x00\x81'
2022-08-13 12:07:38.738 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=28, command_id=11, *is_reply=True)
2022-08-13 12:07:38.739 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:07:38.739 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 28): Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:07:38.741 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081c0b0081'
2022-08-13 12:07:40.274 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.thermostat, old_state=<state weather.thermostat=partlycloudy; temperature=69, temperature_unit=°F, humidity=81, pressure=30.0, pressure_unit=inHg, wind_bearing=38, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 38, 'datetime': '2022-08-13T17:07:10.272790+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:07:10.272790+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:07:10.272790+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:07:10.272790+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:07:10.272790+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 16:32:14 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>, new_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:07:40.273681+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:07:40.273681+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:07:40.273681+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:07:40.273681+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:07:40.273681+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>>
2022-08-13 12:07:47.053 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=0; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:06:37.012855-05:00>, new_state=<state sensor.detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:07:47.052915-05:00>>
2022-08-13 12:07:47.053 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=56; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:06:27.055366-05:00>, new_state=<state sensor.cpu1_inference_speed=48; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:07:47.053602-05:00>>
2022-08-13 12:07:47.054 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.foyer_detection_fps, old_state=<state sensor.foyer_detection_fps=0; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:06:37.013512-05:00>, new_state=<state sensor.foyer_detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:07:47.054066-05:00>>
2022-08-13 12:07:49.649 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x25B7](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:07:49.649 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x25B7](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:49.670 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x96A7](E11-G13): Device seen - marking the device available and resetting counter
2022-08-13 12:07:49.670 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x96A7](E11-G13): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:49.671 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9878](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:07:49.671 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9878](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:07:52.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:07:47.052915-05:00>, new_state=<state sensor.detection_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:07:52.010611-05:00>>
2022-08-13 12:07:52.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=48; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:07:47.053602-05:00>, new_state=<state sensor.cpu1_inference_speed=56; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:07:52.011259-05:00>>
2022-08-13 12:07:52.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.foyer_detection_fps, old_state=<state sensor.foyer_detection_fps=2; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:07:47.054066-05:00>, new_state=<state sensor.foyer_detection_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:07:52.011694-05:00>>
2022-08-13 12:07:56.829 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event ios.entered_background[R]: sourceDeviceID=lwiphone, sourceDeviceName=lwiphone, sourceDevicePermanentID=9CFE263D-1651-489D-99FD-C9CDA459BA49>
2022-08-13 12:07:57.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:07:52.010611-05:00>, new_state=<state sensor.detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:07:57.010428-05:00>>
2022-08-13 12:07:57.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=56; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:07:52.011259-05:00>, new_state=<state sensor.cpu1_inference_speed=51; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:07:57.011082-05:00>>
2022-08-13 12:07:57.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.foyer_detection_fps, old_state=<state sensor.foyer_detection_fps=4; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:07:52.011694-05:00>, new_state=<state sensor.foyer_detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:07:57.011517-05:00>>
2022-08-13 12:07:57.084 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [4395252528896] Connection closed by client
2022-08-13 12:07:57.997 INFO (MainThread) [homeassistant.components.websocket_api.http.connection] [4395239645888] Connection closed by client
2022-08-13 12:08:01.489 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event ios.became_active[R]: sourceDeviceID=lwiphone, sourceDeviceName=lwiphone, sourceDevicePermanentID=9CFE263D-1651-489D-99FD-C9CDA459BA49>
2022-08-13 12:08:01.789 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lwiphone_storage, old_state=<state sensor.lwiphone_storage=68.60; Available=79.48 GB, Available (Important)=91.50 GB, Available (Opportunistic)=87.72 GB, Total=127.87 GB, unit_of_measurement=% available, icon=mdi:database, friendly_name=lwiphone Storage @ 2022-08-13T12:07:20.309312-05:00>, new_state=<state sensor.lwiphone_storage=68.49; Available=79.34 GB, Available (Important)=91.36 GB, Available (Opportunistic)=87.58 GB, Total=127.87 GB, unit_of_measurement=% available, icon=mdi:database, friendly_name=lwiphone Storage @ 2022-08-13T12:08:01.789482-05:00>>
2022-08-13 12:08:02.014 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:07:57.010428-05:00>, new_state=<state sensor.detection_fps=6; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:08:02.014307-05:00>>
2022-08-13 12:08:02.015 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.foyer_detection_fps, old_state=<state sensor.foyer_detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:07:57.011517-05:00>, new_state=<state sensor.foyer_detection_fps=6; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:08:02.015168-05:00>>
2022-08-13 12:08:02.717 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=stop_cover, service_data=entity_id=cover.smartwings_wm25_l_z_cover>
2022-08-13 12:08:02.718 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=30, command_id=2, *is_reply=False)
2022-08-13 12:08:02.719 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Sending request: stop()
2022-08-13 12:08:02.719 DEBUG (MainThread) [zigpy.device] [0x8279] Extending timeout for 0x1e request
2022-08-13 12:08:02.719 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 30 under 31 request id, data: b'011e02'
2022-08-13 12:08:02.719 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 31, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8279 endpoint=1>, 260, 258, 1, b'\x01\x1e\x02', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-13 12:08:02.723 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 31]
2022-08-13 12:08:02.732 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-13 12:08:02.733 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-13 12:08:02.735 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 31: 00
2022-08-13 12:08:02.736 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8279 endpoint=1>, status: 0x00
2022-08-13 12:08:03.417 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:08:03.417 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:08:03.420 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1e\x0b\x02\x00', 0, 175, 127, 250, 21, 253, 0, -78]
2022-08-13 12:08:03.420 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1e\x0b\x02\x00'
2022-08-13 12:08:03.421 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=30, command_id=11, *is_reply=True)
2022-08-13 12:08:03.421 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-13 12:08:03.422 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081e0b0200'
2022-08-13 12:08:03.422 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8279:1:0x0102]: executed 'stop' command with args: '()' kwargs: '{}' result: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-13 12:08:03.422 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.smartwings_wm25_l_z_cover, old_state=<state cover.smartwings_wm25_l_z_cover=opening; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:07:27.482935-05:00>, new_state=<state cover.smartwings_wm25_l_z_cover=closed; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:08:03.422898-05:00>>
2022-08-13 12:08:03.429 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:08:03.430 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:08:03.432 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x1e\x0b\x02\x81', 0, 175, 127, 250, 21, 253, 0, -78]
2022-08-13 12:08:03.432 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x1e\x0b\x02\x81'
2022-08-13 12:08:03.433 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=30, command_id=11, *is_reply=True)
2022-08-13 12:08:03.433 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: WindowCovering:Default_Response(command_id=2, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:08:03.434 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 30): Default_Response(command_id=2, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:08:03.434 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'081e0b0281'
2022-08-13 12:08:03.580 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8279](WM25/L-Z): Device seen - marking the device available and resetting counter
2022-08-13 12:08:03.580 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8279](WM25/L-Z): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:08:04.683 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x362F](TRADFRI bulb GU10 WW 400lm): Device seen - marking the device available and resetting counter
2022-08-13 12:08:04.683 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x362F](TRADFRI bulb GU10 WW 400lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:08:04.692 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF169](TRADFRI bulb GU10 WW 400lm): Device seen - marking the device available and resetting counter
2022-08-13 12:08:04.693 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF169](TRADFRI bulb GU10 WW 400lm): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:08:05.420 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:07:35.584428-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:08:05.419965-05:00>>
2022-08-13 12:08:05.584 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.masterbedroomsmartplug_value_electric_consumed_2, old_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:08:05.419965-05:00>, new_state=<state sensor.masterbedroomsmartplug_value_electric_consumed_2=0.0; state_class=measurement, meter_type=1, meter_type_name=ELECTRIC, unit_of_measurement=W, device_class=power, friendly_name=MasterBedroomSmartPlug: Value (Electric, Consumed) @ 2022-08-13T12:08:05.584262-05:00>>
2022-08-13 12:08:07.013 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=51; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:07:57.011082-05:00>, new_state=<state sensor.cpu1_inference_speed=50; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:07.013315-05:00>>
2022-08-13 12:08:10.274 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=weather.thermostat, old_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:07:40.273681+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:07:40.273681+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:07:40.273681+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:07:40.273681+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:07:40.273681+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>, new_state=<state weather.thermostat=partlycloudy; temperature=70, temperature_unit=°F, humidity=78, pressure=30.03, pressure_unit=inHg, wind_bearing=36, wind_speed=15.66, wind_speed_unit=mph, visibility=9.94, visibility_unit=mi, precipitation_unit=in, forecast=[{'condition': 'partlycloudy', 'wind_bearing': 36, 'datetime': '2022-08-13T17:08:10.273732+00:00', 'temperature': 78, 'templow': 58, 'wind_speed': 15.66}, {'condition': 'partlycloudy', 'wind_bearing': 152, 'datetime': '2022-08-14T17:08:10.273732+00:00', 'temperature': 82, 'templow': 55, 'wind_speed': 6.71}, {'condition': 'partlycloudy', 'wind_bearing': 157, 'datetime': '2022-08-15T17:08:10.273732+00:00', 'temperature': 82, 'templow': 57, 'wind_speed': 13.42}, {'condition': 'rainy', 'wind_bearing': 158, 'datetime': '2022-08-16T17:08:10.273732+00:00', 'temperature': 78, 'templow': 60, 'wind_speed': 13.42}, {'condition': 'partlycloudy', 'wind_bearing': 186, 'datetime': '2022-08-17T17:08:10.273732+00:00', 'temperature': 85, 'templow': 62, 'wind_speed': 15.66}], attribution=Ecobee weather provided by FI:727500 at 2022-08-13 17:02:28 UTC, friendly_name=Thermostat @ 2022-08-13T11:49:39.831653-05:00>>
2022-08-13 12:08:11.369 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:08:11.369 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:08:11.372 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x869b>, 1, 260, 6, b'\x18-\n\x00\x00\x10\x00', 0, 175, 63, 250, 21, 253, 0, -86]
2022-08-13 12:08:11.372 DEBUG (MainThread) [zigpy.zcl] [0x869B:1:0x0006] Received ZCL frame: b'\x18-\n\x00\x00\x10\x00'
2022-08-13 12:08:11.373 DEBUG (MainThread) [zigpy.zcl] [0x869B:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=45, command_id=10, *is_reply=True)
2022-08-13 12:08:11.373 DEBUG (MainThread) [zigpy.zcl] [0x869B:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-08-13 12:08:11.374 DEBUG (MainThread) [zigpy.zcl] [0x869B:1:0x0006] Received command 0x0A (TSN 45): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-08-13 12:08:11.374 DEBUG (MainThread) [zigpy.zcl] [0x869B:1:0x0006] Attribute report received: on_off=<Bool.false: 0>
2022-08-13 12:08:11.377 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x869b>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'182d0a00001000'
2022-08-13 12:08:12.014 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=6; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:08:02.014307-05:00>, new_state=<state sensor.detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:08:12.014698-05:00>>
2022-08-13 12:08:12.015 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=50; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:07.013315-05:00>, new_state=<state sensor.cpu1_inference_speed=53; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:12.015331-05:00>>
2022-08-13 12:08:12.015 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.foyer_detection_fps, old_state=<state sensor.foyer_detection_fps=6; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:08:02.015168-05:00>, new_state=<state sensor.foyer_detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:08:12.015775-05:00>>
2022-08-13 12:08:13.650 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB54B](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:08:13.650 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB54B](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:08:14.640 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1DFF](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:08:14.640 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1DFF](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:08:14.665 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8177](RT RGBW): Device seen - marking the device available and resetting counter
2022-08-13 12:08:14.665 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8177](RT RGBW): Update device availability - device available: True - new availability: True - changed: False
2022-08-13 12:08:17.012 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=53; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:12.015331-05:00>, new_state=<state sensor.cpu1_inference_speed=60; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:17.012499-05:00>>
2022-08-13 12:08:22.010 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.detection_fps, old_state=<state sensor.detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:08:12.014698-05:00>, new_state=<state sensor.detection_fps=3; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Detection Fps @ 2022-08-13T12:08:22.010847-05:00>>
2022-08-13 12:08:22.011 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.cpu1_inference_speed, old_state=<state sensor.cpu1_inference_speed=60; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:17.012499-05:00>, new_state=<state sensor.cpu1_inference_speed=53; unit_of_measurement=ms, icon=mdi:speedometer, friendly_name=Cpu1 Inference Speed @ 2022-08-13T12:08:22.011505-05:00>>
2022-08-13 12:08:22.012 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.foyer_detection_fps, old_state=<state sensor.foyer_detection_fps=5; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:08:12.015775-05:00>, new_state=<state sensor.foyer_detection_fps=3; unit_of_measurement=fps, icon=mdi:speedometer, friendly_name=Foyer Detection Fps @ 2022-08-13T12:08:22.012121-05:00>>
Not analyzed the logs yet but to be all the info clear:
- the quirk that you are testing, have the inverted code implemented?
- my guess is that the device must keep the
window_covering_mode
between HA restarts. Maybe get lost if you repair the device. Can you confirm? But you say something about the 'developer tab', could you explain this point? - without the quirk the open/close buttons aren't working at all, correct? Is not a matter that cover is open and controls inverted then the close button (that will trigger an open command) is not responding. You have tried both buttons and aren't responding
To future review (UNSUP_CLUSTER_COMMAND
¿11?):
2022-08-13 12:20:41.341 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x92\x0b\x00\x00'
2022-08-13 12:20:41.341 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=146, command_id=11, *is_reply=True)
2022-08-13 12:20:41.342 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: InvertedWindowCoveringCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:41.344 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08920b0000'
2022-08-13 12:20:41.345 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8279:1:0x0102]: executed 'up_open' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-13 12:20:41.345 DEBUG (MainThread) [homeassistant.components.zha.cover] state=opening
2022-08-13 12:20:41.345 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.smartwings_wm25_l_z_cover, old_state=<state cover.smartwings_wm25_l_z_cover=closed; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:15:35.370407-05:00>, new_state=<state cover.smartwings_wm25_l_z_cover=opening; current_position=0, friendly_name=Smartwings WM25/L-Z Cover, supported_features=15 @ 2022-08-13T12:20:41.345868-05:00>>
2022-08-13 12:20:41.352 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-13 12:20:41.353 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-13 12:20:41.355 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, 1, 260, 258, b'\x08\x92\x0b\x00\x81', 0, 175, 127, 214, 22, 253, 0, -78]
2022-08-13 12:20:41.356 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received ZCL frame: b'\x08\x92\x0b\x00\x81'
2022-08-13 12:20:41.356 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=146, command_id=11, *is_reply=True)
2022-08-13 12:20:41.357 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Decoded ZCL frame: InvertedWindowCoveringCluster:Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:20:41.357 DEBUG (MainThread) [zigpy.zcl] [0x8279:1:0x0102] Received command 0x0B (TSN 146): Default_Response(command_id=0, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-08-13 12:20:41.360 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8279>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08920b0081'
- Yes, the quirk has the inverted code implemented.
Quirk as implemented
"""Device handler for Smartwings WM25L-Z roller blinds."""
from zigpy.profiles import zha
from zigpy.quirks import CustomCluster, CustomDevice
from zigpy.zcl.clusters.closures import WindowCovering
from zigpy.zcl.clusters.general import (
Basic,
Groups,
Identify,
Ota,
PowerConfiguration,
Scenes,
)
from zhaquirks import DoublingPowerConfigurationCluster
from zhaquirks.const import (
DEVICE_TYPE,
ENDPOINTS,
INPUT_CLUSTERS,
MODELS_INFO,
OUTPUT_CLUSTERS,
PROFILE_ID,
)
class InvertedWindowCoveringCluster(CustomCluster, WindowCovering):
"""WindowCovering cluster implementation.
This implementation inverts the reported covering percent for non standard
devices that don't follow the reporting spec.
"""
cluster_id = WindowCovering.cluster_id
CURRENT_POSITION_LIFT_PERCENTAGE = 0x0008
def _update_attribute(self, attrid, value):
if attrid == self.CURRENT_POSITION_LIFT_PERCENTAGE:
value = 100 - value
super()._update_attribute(attrid, value)
class WM25LBlinds(CustomDevice):
"""Custom device representing Smartwings WM25/L-Z roller blinds."""
signature = {
# <SimpleDescriptor endpoint=1 profile=260 device_type=514
# device_version=1
# input_clusters=[0, 1, 3, 4, 5, 32, 258]
# output_clusters=[3, 25]>
MODELS_INFO: [
("Smartwings", "WM25/L-Z"),
],
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
PowerConfiguration.cluster_id,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
WindowCovering.cluster_id,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
},
}
replacement = {
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
DoublingPowerConfigurationCluster,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
# WindowCovering.cluster_id,
InvertedWindowCoveringCluster,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
}
}
-
Yes, the window covering mode persists through ha reboots. If I reconfigure using the reconfigure button on the device page in Home Assistant the
window_covering_mode
retains theWindowCoveringMode.Motor_direction_reversed
-
Without the quirk, if the blinds are physically open, the state in Home Assistant is Open and pressing either the UP or Down button has no effect.
-
Without the quirk enabled If the state of the blinds in Home Assistant is open and the physical state of the blind is open, calling the cover.open_cover service from the developer tools services page will physically close the blinds.
-
With the quirk enabled, if the blinds are physically OPEN the state in Home Assistant is closed and pressing the UP button physically closes the blinds.
Thought I'd chime in here, I just got a set of these today.
Physically open reads as 100% in home assistant. Open/close is reversed, opening physically closes them.
Without assumed_state I can't actually control them from the default more-info page since the close actually physically opens them and the close button is disabled.
I reset mine so the included remotes controls are backwards but the state and open and close buttons now work.
What do you mean by reset? Did you have to reset it in a special way to get it to reverse?
When I received my blinds, the included remote controls was working correctly, however the state in HomeAssistant was inverted. I factory reset the blinds, holding the button on the blinds for 14 seconds and the controls were inverted on the included remote however the state and controls are correct in HomeAssistant. In the included remote programming guide it talks about reversing the motor direction.
Sent from Proton Mail for iOS
On Wed, Aug 24, 2022 at 7:14 PM, D3v01dZA @.***> wrote:
What do you mean by reset? Did you have to reset it in a special way to get it to reverse?
— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you authored the thread.Message ID: @.***>
Thanks, this fixes it!
I just got these blinds. I used the quirk above and the remote to invert the motor and all is good. Though I have the battery on one plugged in and it's not showing any increase in charge after like 10 hours but thats not a quirk issue.
It it probably worth making a pr with this code.
update: charged up battery and got 50%. Found zhaquirks has an already coded power doubler and have update code below to use that. Seems to work. now getting 100%. This now gets my smartwings fully working. Yeah
ARRGGHHHH. I want to say ZHA is going to always be sub-par to zigbee2mqtt for HA users with the HA attitude towards documentation around quirks. Make it easy for community to fill in the gap until an official quirk is merged in. Thanks all here for starting me in the right direction. Documenting what it took to get SmartWings in ZHA so other can just cut and paste since search brings you here.
I do have one help request. I notice sometime the blinds stop responding and it takes a ZHA reload to make that one blind work again. Any suggestions would be helpful.
First to use custom quirks add to configuration.yaml
zha:
enable_quirks: true
custom_quirks_path: /config/zha_quirks #any folder you want
I have blinds with motor on the right and unspooling against the window. To keep the remote in the correct up/down orientation and make this integration work here is the quirk to use. Working for me at least. Seems the up/down commands are flipped when this way.
Then create a file in the dir. WHATEVERNAMEYOUWANT.py
"""Device handler for Smartwings WM25L-Z roller blinds."""
from zigpy.profiles import zha
from zigpy.quirks import CustomCluster, CustomDevice
from zigpy.zcl.clusters.closures import WindowCovering
from zigpy.zcl.clusters.general import (
Basic,
Groups,
Identify,
Ota,
PowerConfiguration,
Scenes,
)
from zhaquirks import DoublingPowerConfigurationCluster
from zhaquirks.const import (
DEVICE_TYPE,
ENDPOINTS,
INPUT_CLUSTERS,
MODELS_INFO,
OUTPUT_CLUSTERS,
PROFILE_ID,
)
class InvertedWindowCoveringCluster(CustomCluster, WindowCovering):
"""WindowCovering cluster implementation.
This implementation flips the up/down commands
"""
cluster_id = WindowCovering.cluster_id
CMD_GO_UP = 0x0001 #reported as down_close in ZHA commands
CMD_GO_DOWN = 0x0000 #reported as up_open in ZHA commands
async def command(
self, command_id, *args, manufacturer=None, expect_reply=True, tsn=None
):
"""Override default commands for up and down. they are backwards."""
if command_id == self.CMD_GO_UP:
return await super().command(self.CMD_GO_DOWN, *args)
if command_id == self.CMD_GO_DOWN:
return await super().command(self.CMD_GO_UP, *args)
return await super().command(
command_id,
*args,
manufacturer=manufacturer,
expect_reply=expect_reply,
tsn=tsn
)
class WM25LBlinds(CustomDevice):
"""Custom device representing Smartwings WM25/L-Z roller blinds."""
signature = {
# <SimpleDescriptor endpoint=1 profile=260 device_type=514
# device_version=1
# input_clusters=[0, 1, 3, 4, 5, 32, 258]
# output_clusters=[3, 25]>
MODELS_INFO: [
("Smartwings", "WM25/L-Z"),
],
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
DoublingPowerConfigurationCluster.cluster_id,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
WindowCovering.cluster_id,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
},
}
replacement = {
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
DoublingPowerConfigurationCluster,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
InvertedWindowCoveringCluster,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
}
}
At first I used mostly the previous quirk in this post after I had flipped the motor direction using a reset. See the smartwing manual on how to do this. However the slider "set" was backwards from the final "read" value. So here is a quirk likely if you have a left hand motor and unspooling against the window that fixes this. Once I got this working the lightbulb upstairs was um lets make the remote work and just flip the up/down commands.
"""Device handler for Smartwings WM25L-Z roller blinds."""
from zigpy.profiles import zha
from zigpy.quirks import CustomCluster, CustomDevice
from zigpy.zcl.clusters.closures import WindowCovering
from zigpy.zcl.clusters.general import (
Basic,
Groups,
Identify,
Ota,
PowerConfiguration,
Scenes,
)
from zhaquirks import DoublingPowerConfigurationCluster
from zhaquirks.const import (
DEVICE_TYPE,
ENDPOINTS,
INPUT_CLUSTERS,
MODELS_INFO,
OUTPUT_CLUSTERS,
PROFILE_ID,
)
class InvertedWindowCoveringCluster(CustomCluster, WindowCovering):
"""WindowCovering cluster implementation.
This implementation inverts the reported covering percent for non standard
devices that don't follow the reporting spec.
"""
cluster_id = WindowCovering.cluster_id
CURRENT_POSITION_LIFT_PERCENTAGE = 0x0008
CMD_GO_TO_LIFT_PERCENTAGE = 0x0005
def _update_attribute(self, attrid, value):
if attrid == self.CURRENT_POSITION_LIFT_PERCENTAGE:
value = 100 - value
super()._update_attribute(attrid, value)
async def command(
self, command_id, *args, manufacturer=None, expect_reply=True, tsn=None
):
"""Override default command to invert percent lift value."""
if command_id == self.CMD_GO_TO_LIFT_PERCENTAGE:
percent = args[0]
# Invert the percentage value
percent = 100 - percent
v = (percent,)
return await super().command(command_id, *v)
return await super().command(
command_id,
*args,
manufacturer=manufacturer,
expect_reply=expect_reply,
tsn=tsn
)
class WM25LBlinds(CustomDevice):
"""Custom device representing Smartwings WM25/L-Z roller blinds."""
signature = {
# <SimpleDescriptor endpoint=1 profile=260 device_type=514
# device_version=1
# input_clusters=[0, 1, 3, 4, 5, 32, 258]
# output_clusters=[3, 25]>
MODELS_INFO: [
("Smartwings", "WM25/L-Z"),
],
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
DoublingPowerConfigurationCluster.cluster_id,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
WindowCovering.cluster_id,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
},
}
replacement = {
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
DoublingPowerConfigurationCluster,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
InvertedWindowCoveringCluster,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
}
}
So use the one for the correct remote operation/blind orientation. Will look at if the battery is wrong in the next week and update this if so. What makes HA awesome the community support, aka one should figure out the rest just use.
@haywiremk this is working well for me, and got the slider to make more sense! thanks.
the battery should work with just one minor change to what you have above. The original endpoints shouldn't have the DoublingPowerConfigurationCluster.
Do you want to convert this into a PR? can likely make it into the next HA release. If not I can do i it, just let me know.
"""Device handler for Smartwings WM25L-Z roller blinds."""
from zigpy.profiles import zha
from zigpy.quirks import CustomCluster, CustomDevice
from zigpy.zcl.clusters.closures import WindowCovering
from zigpy.zcl.clusters.general import (
Basic,
Groups,
Identify,
Ota,
PowerConfiguration,
Scenes,
)
from zhaquirks import DoublingPowerConfigurationCluster
from zhaquirks.const import (
DEVICE_TYPE,
ENDPOINTS,
INPUT_CLUSTERS,
MODELS_INFO,
OUTPUT_CLUSTERS,
PROFILE_ID,
)
class InvertedWindowCoveringCluster(CustomCluster, WindowCovering):
"""WindowCovering cluster implementation.
This implementation inverts the reported covering percent for non standard
devices that don't follow the reporting spec.
"""
cluster_id = WindowCovering.cluster_id
CURRENT_POSITION_LIFT_PERCENTAGE = 0x0008
CMD_GO_TO_LIFT_PERCENTAGE = 0x0005
def _update_attribute(self, attrid, value):
if attrid == self.CURRENT_POSITION_LIFT_PERCENTAGE:
value = 100 - value
super()._update_attribute(attrid, value)
async def command(
self, command_id, *args, manufacturer=None, expect_reply=True, tsn=None
):
"""Override default command to invert percent lift value."""
if command_id == self.CMD_GO_TO_LIFT_PERCENTAGE:
percent = args[0]
# Invert the percentage value
percent = 100 - percent
v = (percent,)
return await super().command(command_id, *v)
return await super().command(
command_id,
*args,
manufacturer=manufacturer,
expect_reply=expect_reply,
tsn=tsn
)
class WM25LBlinds(CustomDevice):
"""Custom device representing Smartwings WM25/L-Z roller blinds."""
signature = {
# <SimpleDescriptor endpoint=1 profile=260 device_type=514
# device_version=1
# input_clusters=[0, 1, 3, 4, 5, 32, 258]
# output_clusters=[3, 25]>
MODELS_INFO: [
("Smartwings", "WM25/L-Z"),
],
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
PowerConfiguration.cluster_id,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
WindowCovering.cluster_id,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
},
}
replacement = {
ENDPOINTS: {
1: {
PROFILE_ID: zha.PROFILE_ID,
DEVICE_TYPE: zha.DeviceType.WINDOW_COVERING_DEVICE,
INPUT_CLUSTERS: [
Basic.cluster_id,
DoublingPowerConfigurationCluster,
Identify.cluster_id,
Groups.cluster_id,
Scenes.cluster_id,
InvertedWindowCoveringCluster,
],
OUTPUT_CLUSTERS: [Identify.cluster_id, Ota.cluster_id],
}
}
}
Go ahead and submit the PR. Good catch on the initial endpoints. I think we way it scraps just works but wrong. Not much docs around quirks and dug this out of another quirk. One final thing is there likely needs to be TWO versions. I have a right mount motor(best for solar) with roll backside and just use the command flip version with my remotes being proper up and down. I think if you have a left mount motor with rollbackside the quirk could be backwards. The other two frontsides will just be one of these. Not sure if the remote flip after a reset fixes that so one quirk will always work. Likely need someone with leftside-rollback to test or just leave a link to this ticket as a comment in the quirk for the lefties. Always those lefties!
I have the cellular shades and had to invert the motor with the remote to work correctly. I think this is better than nothing
Please see my first script that inverts the up/down commands vs flipping all the statuses and setting the remote backwards. I am using that one to keep the remote correct.
I'll reset my motor to default and test. I had it inverted for the previous quirk , switched to the last one you posted and all worked the same with added bonusthe slider was fixed to not be backwards.
I played around with my shades today I set the motor direction back to default with the remote and ended up removing the quirk completely for some testing, and clearing py_cache in custom_quirks folder. the buttons seem to be functioning albeit it backwards and the slider works. battery is halved as expected.
Setting the window_covering_mode (id: 0x0017) attribute to 1 doesn't seem to do anything, buttons still reversed. I'm interested in trying the config_status (id: 0x0007) attribute as it has a inverted control option. but I'm stuck trying to figure out what to write as the attribute to enable that.

Not sure what your config_status
currently is, but the 7 bits are ordered like this: 6543210
.
So just to enable bit 2, it's 0000100
and since the clusters UI (only?) does decimal, convert it: 100
in binary -> 4
in decimal. That would disable everything but bit 2. (You might want to enable more though)
Note: When reading, it might return it in a "nicer way", as zigpy does some type conversion.
(But read the attribute and save the output (here) before writing anything)