Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Device Support Request] Lonsonho 2CH Zigbee Dimmer Module (_TZ3210_wdexaypg) #1840

Closed
gregoryduckworth opened this issue Oct 18, 2022 · 19 comments
Labels
stale Issue is inactivate and might get closed soon Tuya Request/PR regarding a Tuya device

Comments

@gregoryduckworth
Copy link

gregoryduckworth commented Oct 18, 2022

Is your feature request related to a problem? Please describe.
Support new device as listed here - https://www.aliexpress.com/item/1005003012298844.html?spm=a2g0o.order_list.0.0.47e11802nmAbGl

Describe the solution you'd like
Support for this device, including the ability to change how it toggles on/off at the switch.

Device signature
{
  "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x0101",
      "in_clusters": [
        "0x0000",
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0xef00"
      ],
      "out_clusters": [
        "0x000a",
        "0x0019"
      ]
    },
    "2": {
      "profile_id": 260,
      "device_type": "0x0101",
      "in_clusters": [
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0xef00"
      ],
      "out_clusters": []
    },
    "242": {
      "profile_id": 41440,
      "device_type": "0x0061",
      "in_clusters": [],
      "out_clusters": [
        "0x0021"
      ]
    }
  },
  "manufacturer": "_TZ3210_wdexaypg",
  "model": "TS110E",
  "class": "zigpy.device.Device"
}
Diagnostic information
{
  "home_assistant": {
    "installation_type": "Home Assistant OS",
    "version": "2022.10.4",
    "dev": false,
    "hassio": true,
    "virtualenv": false,
    "python_version": "3.10.5",
    "docker": true,
    "arch": "x86_64",
    "timezone": "Europe/London",
    "os_name": "Linux",
    "os_version": "5.15.72",
    "supervisor": "2022.10.0",
    "host_os": "Home Assistant OS 9.2",
    "docker_version": "20.10.17",
    "chassis": "vm",
    "run_as_root": true
  },
  "integration_manifest": {
    "domain": "zha",
    "name": "Zigbee Home Automation",
    "config_flow": true,
    "documentation": "https://www.home-assistant.io/integrations/zha",
    "requirements": [
      "bellows==0.34.2",
      "pyserial==3.5",
      "pyserial-asyncio==0.6",
      "zha-quirks==0.0.82",
      "zigpy-deconz==0.19.0",
      "zigpy==0.51.3",
      "zigpy-xbee==0.16.2",
      "zigpy-zigate==0.10.2",
      "zigpy-znp==0.9.1"
    ],
    "usb": [
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*2652*",
        "known_devices": [
          "slae.sh cc2652rb stick"
        ]
      },
      {
        "vid": "1A86",
        "pid": "55D4",
        "description": "*sonoff*plus*",
        "known_devices": [
          "sonoff zigbee dongle plus v2"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*sonoff*plus*",
        "known_devices": [
          "sonoff zigbee dongle plus"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*tubeszb*",
        "known_devices": [
          "TubesZB Coordinator"
        ]
      },
      {
        "vid": "1A86",
        "pid": "7523",
        "description": "*tubeszb*",
        "known_devices": [
          "TubesZB Coordinator"
        ]
      },
      {
        "vid": "1A86",
        "pid": "7523",
        "description": "*zigstar*",
        "known_devices": [
          "ZigStar Coordinators"
        ]
      },
      {
        "vid": "1CF1",
        "pid": "0030",
        "description": "*conbee*",
        "known_devices": [
          "Conbee II"
        ]
      },
      {
        "vid": "10C4",
        "pid": "8A2A",
        "description": "*zigbee*",
        "known_devices": [
          "Nortek HUSBZB-1"
        ]
      },
      {
        "vid": "0403",
        "pid": "6015",
        "description": "*zigate*",
        "known_devices": [
          "ZiGate+"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*zigate*",
        "known_devices": [
          "ZiGate"
        ]
      },
      {
        "vid": "10C4",
        "pid": "8B34",
        "description": "*bv 2010/10*",
        "known_devices": [
          "Bitron Video AV2010/10"
        ]
      }
    ],
    "codeowners": [
      "@dmulcahey",
      "@adminiuga",
      "@puddly"
    ],
    "zeroconf": [
      {
        "type": "_esphomelib._tcp.local.",
        "name": "tube*"
      },
      {
        "type": "_zigate-zigbee-gateway._tcp.local.",
        "name": "*zigate*"
      },
      {
        "type": "_zigstar_gw._tcp.local.",
        "name": "*zigstar*"
      }
    ],
    "dependencies": [
      "file_upload"
    ],
    "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": 39807,
    "manufacturer": "_TZ3210_wdexaypg",
    "model": "TS110E",
    "name": "_TZ3210_wdexaypg TS110E",
    "quirk_applied": false,
    "quirk_class": "zigpy.device.Device",
    "manufacturer_code": 4417,
    "power_source": "Mains",
    "lqi": 27,
    "rssi": null,
    "last_seen": "2022-10-18T20:32:17",
    "available": true,
    "device_type": "Router",
    "signature": {
      "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
      "endpoints": {
        "1": {
          "profile_id": 260,
          "device_type": "0x0101",
          "in_clusters": [
            "0x0000",
            "0x0003",
            "0x0004",
            "0x0005",
            "0x0006",
            "0x0008",
            "0xef00"
          ],
          "out_clusters": [
            "0x000a",
            "0x0019"
          ]
        },
        "2": {
          "profile_id": 260,
          "device_type": "0x0101",
          "in_clusters": [
            "0x0003",
            "0x0004",
            "0x0005",
            "0x0006",
            "0x0008",
            "0xef00"
          ],
          "out_clusters": []
        },
        "242": {
          "profile_id": 41440,
          "device_type": "0x0061",
          "in_clusters": [],
          "out_clusters": [
            "0x0021"
          ]
        }
      }
    },
    "active_coordinator": false,
    "entities": [
      {
        "entity_id": "button.lounge_light_identifybutton",
        "name": "_TZ3210_wdexaypg TS110E"
      },
      {
        "entity_id": "light.lounge_light_side",
        "name": "_TZ3210_wdexaypg TS110E"
      },
      {
        "entity_id": "light.lounge_light_main",
        "name": "_TZ3210_wdexaypg TS110E"
      }
    ],
    "neighbors": [],
    "endpoint_names": [
      {
        "name": "DIMMABLE_LIGHT"
      },
      {
        "name": "DIMMABLE_LIGHT"
      },
      {
        "name": "unknown 97 device_type of 0xa1e0 profile id"
      }
    ],
    "user_given_name": "Lounge Light",
    "device_reg_id": "2bf6fe137b6d677b2bd78212179f02d5",
    "area_id": "lounge",
    "cluster_details": {
      "1": {
        "device_type": {
          "name": "DIMMABLE_LIGHT",
          "id": 257
        },
        "profile_id": 260,
        "in_clusters": {
          "0x0004": {
            "endpoint_attribute": "groups",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0005": {
            "endpoint_attribute": "scenes",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0003": {
            "endpoint_attribute": "identify",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0006": {
            "endpoint_attribute": "on_off",
            "attributes": {
              "0x0000": {
                "attribute_name": "on_off",
                "value": 1
              },
              "0x4001": {
                "attribute_name": "on_time",
                "value": 0
              }
            },
            "unsupported_attributes": {
              "0x4003": {
                "attribute_name": "start_up_on_off"
              }
            }
          },
          "0x0008": {
            "endpoint_attribute": "level",
            "attributes": {
              "0x0000": {
                "attribute_name": "current_level",
                "value": 255
              }
            },
            "unsupported_attributes": {
              "0x0000": {
                "attribute_name": "current_level"
              },
              "0x4000": {
                "attribute_name": "start_up_current_level"
              },
              "0x0013": {
                "attribute_name": "off_transition_time"
              },
              "0x0014": {
                "attribute_name": "default_move_rate"
              },
              "0x0011": {
                "attribute_name": "on_level"
              },
              "0x0012": {
                "attribute_name": "on_transition_time"
              },
              "0x0010": {
                "attribute_name": "on_off_transition_time"
              }
            }
          },
          "0xef00": {
            "endpoint_attribute": null,
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0000": {
            "endpoint_attribute": "basic",
            "attributes": {
              "0x0004": {
                "attribute_name": "manufacturer",
                "value": "_TZ3210_wdexaypg"
              },
              "0x0005": {
                "attribute_name": "model",
                "value": "TS110E"
              }
            },
            "unsupported_attributes": {}
          }
        },
        "out_clusters": {
          "0x0019": {
            "endpoint_attribute": "ota",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x000a": {
            "endpoint_attribute": "time",
            "attributes": {},
            "unsupported_attributes": {}
          }
        }
      },
      "2": {
        "device_type": {
          "name": "DIMMABLE_LIGHT",
          "id": 257
        },
        "profile_id": 260,
        "in_clusters": {
          "0x0004": {
            "endpoint_attribute": "groups",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0005": {
            "endpoint_attribute": "scenes",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0003": {
            "endpoint_attribute": "identify",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0006": {
            "endpoint_attribute": "on_off",
            "attributes": {
              "0x0000": {
                "attribute_name": "on_off",
                "value": 0
              },
              "0x4001": {
                "attribute_name": "on_time",
                "value": 0
              }
            },
            "unsupported_attributes": {
              "0x4003": {
                "attribute_name": "start_up_on_off"
              }
            }
          },
          "0x0008": {
            "endpoint_attribute": "level",
            "attributes": {
              "0x0000": {
                "attribute_name": "current_level",
                "value": 223
              }
            },
            "unsupported_attributes": {
              "0x0000": {
                "attribute_name": "current_level"
              },
              "0x4000": {
                "attribute_name": "start_up_current_level"
              },
              "0x0013": {
                "attribute_name": "off_transition_time"
              },
              "0x0014": {
                "attribute_name": "default_move_rate"
              },
              "0x0011": {
                "attribute_name": "on_level"
              },
              "0x0012": {
                "attribute_name": "on_transition_time"
              },
              "0x0010": {
                "attribute_name": "on_off_transition_time"
              }
            }
          },
          "0xef00": {
            "endpoint_attribute": null,
            "attributes": {},
            "unsupported_attributes": {}
          }
        },
        "out_clusters": {}
      },
      "242": {
        "device_type": {
          "name": "unknown",
          "id": 97
        },
        "profile_id": 41440,
        "in_clusters": {},
        "out_clusters": {
          "0x0021": {
            "endpoint_attribute": "green_power",
            "attributes": {},
            "unsupported_attributes": {}
          }
        }
      }
    }
  }
}
Additional logs
Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=257, device_version=1, input_clusters=[4, 5, 3, 6, 8, 61184, 0], output_clusters=[25, 10])
Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=2, profile=260, device_type=257, device_version=1, input_clusters=[4, 5, 3, 6, 8, 61184], output_clusters=[])
Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=242, profile=41440, device_type=97, device_version=0, input_clusters=[], output_clusters=[33])

Thanks in advance, I had a go at this but butchered it.

@javicalle
Copy link
Collaborator

javicalle commented Oct 20, 2022

It would be nice if you can include a link to the device manufacturer or to the site vendor to be able to identify the device.

A little more info about what is working and what no, or what do you expect from the device is necessary to try to 'quirk' it. It's just the on/off not working? Doesn't the dimming function? Or is another your issue?

@gregoryduckworth
Copy link
Author

Hi @javicalle,

I've updated the main issue to include a link to the product itself.

As for the device itself, it works through HA without any problems with typically dimming, on/off functionality, but when using it at the wall switch (which is a rocker), I cannot turn it on and end up resetting the device. I'm hoping that there is the ability to update this, similar to https://www.zigbee2mqtt.io/devices/TS110E_2gang.html#lonsonho-ts110e_2gang.

@javicalle
Copy link
Collaborator

javicalle commented Oct 20, 2022

Ok, the it seems that you need just to configure the external_switch_type for your device.

2 steps: configure local quirk and configure your device.

ts110e.py
"""Tuya Dimmer TS110E."""
from typing import Any, Optional, Union

from zigpy.profiles import zha
import zigpy.types as t
from zigpy.zcl import foundation
from zigpy.zcl.clusters.general import (
    Basic,
    GreenPowerProxy,
    Groups,
    Identify,
    LevelControl,
    OnOff,
    Ota,
    Scenes,
    Time,
)

from zhaquirks.const import (
    DEVICE_TYPE,
    ENDPOINTS,
    INPUT_CLUSTERS,
    MODELS_INFO,
    OUTPUT_CLUSTERS,
    PROFILE_ID,
)
from zhaquirks.tuya import (
    NoManufacturerCluster,
    TuyaDimmerSwitch,
    TuyaZBExternalSwitchTypeCluster,
)

TUYA_LEVEL_ATTRIBUTE = 0xF000
TUYA_BULB_TYPE_ATTRIBUTE = 0xFC02
TUYA_MIN_LEVEL_ATTRIBUTE = 0xFC03
TUYA_MAX_LEVEL_ATTRIBUTE = 0xFC04
TUYA_CUSTOM_LEVEL_COMMAND = 0x00F0


class TuyaLevelPayload(t.Struct):
    """Tuya Level payload."""

    level: t.uint16_t
    transtime: t.uint16_t


class TuyaBulbType(t.enum8):
    """Tuya bulb type."""

    LED = 0x00
    INCANDESCENT = 0x01
    HALOGEN = 0x02


class F000LevelControlCluster(NoManufacturerCluster, LevelControl):
    """LevelControlCluster that reports to attrid 0xF000."""

    server_commands = LevelControl.server_commands.copy()
    server_commands[TUYA_CUSTOM_LEVEL_COMMAND] = foundation.ZCLCommandDef(
        "moveToLevelTuya",
        (TuyaLevelPayload,),
        is_manufacturer_specific=False,
    )

    attributes = LevelControl.attributes.copy()
    attributes.update(
        {
            # 0xF000
            TUYA_LEVEL_ATTRIBUTE: ("manufacturer_current_level", t.uint16_t),
            # 0xFC02
            TUYA_BULB_TYPE_ATTRIBUTE: ("bulb_type", TuyaBulbType),
            # 0xFC03
            TUYA_MIN_LEVEL_ATTRIBUTE: ("manufacturer_min_level", t.uint16_t),
            # 0xFC04
            TUYA_MAX_LEVEL_ATTRIBUTE: ("manufacturer_max_level", t.uint16_t),
        }
    )

    # 0xF000 reported values are 10-1000, convert to 0-254
    def _update_attribute(self, attrid, value):
        if attrid == TUYA_LEVEL_ATTRIBUTE:
            self.debug(
                "Getting brightness %s",
                value,
            )
            value = (value + 4 - 10) * 254 // (1000 - 10)
            attrid = 0x0000

        super()._update_attribute(attrid, value)

    async def command(
        self,
        command_id: Union[foundation.GeneralCommand, int, t.uint8_t],
        *args,
        manufacturer: Optional[Union[int, t.uint16_t]] = None,
        expect_reply: bool = True,
        tsn: Optional[Union[int, t.uint8_t]] = None,
        **kwargs: Any,
    ):
        """Override the default Cluster command."""
        self.debug(
            "Sending Cluster Command. Cluster Command is %x, Arguments are %s",
            command_id,
            args,
        )
        # move_to_level, move, move_to_level_with_on_off
        if command_id in (0x0000, 0x0001, 0x0004):
            # getting the level value
            if kwargs and "level" in kwargs:
                level = kwargs["level"]
            elif args:
                level = args[0]
            else:
                level = 0
            # convert dim values to 10-1000
            brightness = level * (1000 - 10) // 254 + 10
            self.debug(
                "Setting brightness to %s",
                brightness,
            )
            return await super().command(
                TUYA_CUSTOM_LEVEL_COMMAND,
                TuyaLevelPayload(level=brightness, transtime=0),
                manufacturer=manufacturer,
                expect_reply=expect_reply,
                tsn=tsn,
            )

        return super().command(command_id, *args, manufacturer, expect_reply, tsn)


class DimmerSwitchWithNeutral1Gang(TuyaDimmerSwitch):
    """Tuya Dimmer Switch Module With Neutral 1 Gang."""

    signature = {
        MODELS_INFO: [("_TZ3210_ngqk6jia", "TS110E")],
        ENDPOINTS: {
            # <SimpleDescriptor endpoint=1 profile=260 device_type=257
            # input_clusters=[0, 4, 5, 6, 8, 57345]
            # output_clusters=[10, 25]>
            1: {
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.DIMMABLE_LIGHT,
                INPUT_CLUSTERS: [
                    Basic.cluster_id,
                    Groups.cluster_id,
                    Scenes.cluster_id,
                    OnOff.cluster_id,
                    LevelControl.cluster_id,
                    TuyaZBExternalSwitchTypeCluster.cluster_id,
                ],
                OUTPUT_CLUSTERS: [Time.cluster_id, Ota.cluster_id],
            },
            242: {
                # <SimpleDescriptor endpoint=242 profile=41440 device_type=97
                # input_clusters=[]
                # output_clusters=[33]
                PROFILE_ID: 41440,
                DEVICE_TYPE: 97,
                INPUT_CLUSTERS: [],
                OUTPUT_CLUSTERS: [GreenPowerProxy.cluster_id],
            },
        },
    }
    replacement = {
        ENDPOINTS: {
            1: {
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.DIMMABLE_LIGHT,
                INPUT_CLUSTERS: [
                    Basic.cluster_id,
                    Groups.cluster_id,
                    Scenes.cluster_id,
                    OnOff.cluster_id,
                    F000LevelControlCluster,
                    TuyaZBExternalSwitchTypeCluster,
                ],
                OUTPUT_CLUSTERS: [Time.cluster_id, Ota.cluster_id],
            },
            242: {
                PROFILE_ID: 41440,
                DEVICE_TYPE: 97,
                INPUT_CLUSTERS: [],
                OUTPUT_CLUSTERS: [GreenPowerProxy.cluster_id],
            },
        },
    }


class DimmerSwitchWithNeutral2Gang(TuyaDimmerSwitch):
    """Tuya Dimmer Switch Module With Neutral 2 Gang."""

    signature = {
        MODELS_INFO: [("_TZ3210_wdexaypg", "TS110E")],
        ENDPOINTS: {
            # NodeDescriptor(
            #     logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0,
            #     frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>,
            #     manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66,
            #     descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False,
            #     *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False
            # )
            1: {
                # input_clusters=["0x0000","0x0003","0x0004","0x0005","0x0006","0x0008","0xef00"]
                # output_clusters=["0x000a","0x0019"]
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.DIMMABLE_LIGHT,
                INPUT_CLUSTERS: [
                    Basic.cluster_id,
                    Identify.cluster_id,
                    Groups.cluster_id,
                    Scenes.cluster_id,
                    OnOff.cluster_id,
                    LevelControl.cluster_id,
                    TuyaZBExternalSwitchTypeCluster.cluster_id,
                ],
                OUTPUT_CLUSTERS: [Time.cluster_id, Ota.cluster_id],
            },
            2: {
                # input_clusters=["0x0003","0x0004","0x0005","0x0006","0x0008","0xef00"]
                # output_clusters=[]
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.DIMMABLE_LIGHT,
                INPUT_CLUSTERS: [
                    Identify.cluster_id,
                    Groups.cluster_id,
                    Scenes.cluster_id,
                    OnOff.cluster_id,
                    LevelControl.cluster_id,
                    TuyaZBExternalSwitchTypeCluster.cluster_id,
                ],
                OUTPUT_CLUSTERS: [],
            },
            242: {
                # input_clusters=[]
                # output_clusters=["0x0021"]
                PROFILE_ID: 41440,
                DEVICE_TYPE: 97,
                INPUT_CLUSTERS: [],
                OUTPUT_CLUSTERS: [GreenPowerProxy.cluster_id],
            },
        },
    }
    replacement = {
        ENDPOINTS: {
            1: {
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.DIMMABLE_LIGHT,
                INPUT_CLUSTERS: [
                    Basic.cluster_id,
                    Identify.cluster_id,
                    Groups.cluster_id,
                    Scenes.cluster_id,
                    OnOff.cluster_id,
                    F000LevelControlCluster,
                    TuyaZBExternalSwitchTypeCluster,
                ],
                OUTPUT_CLUSTERS: [Time.cluster_id, Ota.cluster_id],
            },
            2: {
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.DIMMABLE_LIGHT,
                INPUT_CLUSTERS: [
                    Identify.cluster_id,
                    Groups.cluster_id,
                    Scenes.cluster_id,
                    OnOff.cluster_id,
                    F000LevelControlCluster,
                    TuyaZBExternalSwitchTypeCluster,
                ],
                OUTPUT_CLUSTERS: [],
            },
            242: {
                PROFILE_ID: 41440,
                DEVICE_TYPE: 97,
                INPUT_CLUSTERS: [],
                OUTPUT_CLUSTERS: [GreenPowerProxy.cluster_id],
            },
        },
    }

There is a guide about enabling custom quirks:

Create a new ts110e.py file in your local quirks folder.
Put the proposed code in the new file.
Restart HA and pair you device again. Check that the device is loading the quirk and that the device signature has changed.

Once the device has loaded the quirk, you could change the external_switch_type from cluster TuyaZBExternalSwitchTypeCluster.
First get the current value and then change it to a new one. With a rocker a would say that could be 0, but you can try any value 0/1/2.

@gregoryduckworth
Copy link
Author

Thanks for your help with this @javicalle, I've loaded up a custom quirk and according to the diagnostics it is loading correctly, however I don't seem to have the TuyaZBExternalSwitchTypeCluster option.

{
  "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x0101",
      "in_clusters": [
        "0x0000",
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0xef00"
      ],
      "out_clusters": [
        "0x000a",
        "0x0019"
      ]
    },
    "2": {
      "profile_id": 260,
      "device_type": "0x0101",
      "in_clusters": [
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0xef00"
      ],
      "out_clusters": []
    },
    "242": {
      "profile_id": 41440,
      "device_type": "0x0061",
      "in_clusters": [],
      "out_clusters": [
        "0x0021"
      ]
    }
  },
  "manufacturer": "_TZ3210_wdexaypg",
  "model": "TS110E",
  "class": "zigpy.device.Device"
}

@gregoryduckworth
Copy link
Author

gregoryduckworth commented Nov 7, 2022

On further inspection as I've had a few goes now, it looks like the setting isn't coming through, the device diagnostics come back as:

"0xef00": {
  "endpoint_attribute": null,
  "attributes": {},
  "unsupported_attributes": {}
},

@javicalle
Copy link
Collaborator

javicalle commented Nov 7, 2022

Hi, your device is NOT loading the quirk.
If done, the device signature would be changed to something like ts110e.DimmerSwitchWithNeutral2Gang, but don't seems to be the case.

Check the HA startup log for any error related to the quirk file.

@gregoryduckworth
Copy link
Author

gregoryduckworth commented Nov 7, 2022

Thanks for your help with this, from the looks of it I've set something up wrong but can't see the wood for the trees.

I've got my zha config here:
image

I've created the file, like so:
image

then copied in the code you provided above but it still doesn't seem to be picking up the quirk (I've restarted HA and tried reconnecting it several times):
image

@gregoryduckworth
Copy link
Author

Ahh, I've found more information:

2022-11-07 20:35:29.882 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'ts110e.DimmerSwitchWithNeutral2Gang'>
2022-11-07 20:35:29.882 DEBUG (MainThread) [zigpy.quirks.registry] Fail because input cluster mismatch on at least one endpoint

Will investigate this a little more.

@javicalle
Copy link
Collaborator

My bad, I have mistaken the cluster 🤦🏻‍♂️
Give me a minute.

@javicalle
Copy link
Collaborator

The problem is with the TuyaZBExternalSwitchTypeCluster. It is not the correct one, but is what we would need.

If you replace the cluster with the TuyaLevelControlManufCluster, replace it also in the replacement part. That will not fix the problem, but maybe we can get some info from the logs.
You will need enable the debug logs a look at them when you interact with the phisycal device to identify the events that generate.

@gregoryduckworth
Copy link
Author

It's looking better! Just need to get it into the right state:
image

@javicalle
Copy link
Collaborator

@gregoryduckworth
Copy link
Author

Aww, that is a shame! My WAF is going through the floor 😉

Thanks for all your help with this. Looks like I will have to play a little more.

@javicalle
Copy link
Collaborator

Look at the F000LevelControlCluster of that device.
There must be a bulb_type attribute (ignore the name).
Get its value (probably 0).
Try to update to another value: 0/1/2/3

@gregoryduckworth
Copy link
Author

gregoryduckworth commented Nov 7, 2022

The bulb_type comes back as TuyaBulbType.LED on both. Tried 0/1/2/3 still but nothing happened when switching it at the wall.

@javicalle
Copy link
Collaborator

I'm pretty sure that this is the attribute but I don't know why you can't update its value.
I'll take another look, but would be tomorrow.

References:

@javicalle
Copy link
Collaborator

I can't find any problem in the code. Can you enable the debug log and attach them?

Please attach the logs from changing the value in HA and also the logs from interacting with the phisycal device (switching, dimming, etc)

@gregoryduckworth
Copy link
Author

gregoryduckworth commented Nov 12, 2022

Hopefully I've got everything in this log, there is a fair old amount of information coming through!

Debug Logs both through HA and Physical switch
2022-11-12 14:44:52.708 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.lounge_light_light>
2022-11-12 14:44:52.710 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: setting transitioning flag to True
2022-11-12 14:44:52.710 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=23, command_id=1, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:44:52.711 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending request: on()
2022-11-12 14:44:52.711 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=1, source_route=None, extended_timeout=False, tsn=23, profile_id=260, cluster_id=6, data=Serialized[b'\x01\x17\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:52.713 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=23, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x17\x01')
2022-11-12 14:44:52.732 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:52.737 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=23)
2022-11-12 14:44:52.829 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8871441, TSN=0, Data=b'\x18\x17\x0B\x01\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:52.830 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8871744, TSN=0, Data=b'\x08\xDA\x0A\x00\x00\x10\x01', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:52.830 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\x17\x0b\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:52.830 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received ZCL frame: b'\x18\x17\x0b\x01\x00'
2022-11-12 14:44:52.831 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=23, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:52.831 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame: OnOff:Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:52.831 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x08\xda\n\x00\x00\x10\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:52.832 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received ZCL frame: b'\x08\xda\n\x00\x00\x10\x01'
2022-11-12 14:44:52.832 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=218, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:52.832 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:52.833 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received command 0x0A (TSN 218): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:52.833 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Attribute report received: on_off=<Bool.true: 1>
2022-11-12 14:44:52.833 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: received onoff Bool.true while transitioning - skipping update
2022-11-12 14:44:52.834 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=218, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:52.834 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:52.834 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=1, source_route=None, extended_timeout=False, tsn=218, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xda\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:52.835 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8871970, TSN=0, Data=b'\x18\xDB\x0A\x00\x00\x10\x01', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:52.835 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF9DE:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:52.835 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: starting transitioning timer for 1.25
2022-11-12 14:44:52.836 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: turned on: {'on_off': Default_Response(command_id=1, status=<Status.SUCCESS: 0>)}
2022-11-12 14:44:52.836 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light, old_state=<state light.lounge_light_light=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=1, friendly_name=Lounge - Side Light, supported_features=40 @ 2022-11-12T14:44:11.883117+00:00>, new_state=<state light.lounge_light_light=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=1, off_with_transition=False, off_brightness=None, friendly_name=Lounge - Side Light, supported_features=40 @ 2022-11-12T14:44:52.836386+00:00>>
2022-11-12 14:44:52.836 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=218, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=0, Data=b'\x18\xDA\x0B\x0A\x00')
2022-11-12 14:44:52.837 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xdb\n\x00\x00\x10\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:52.837 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received ZCL frame: b'\x18\xdb\n\x00\x00\x10\x01'
2022-11-12 14:44:52.837 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=219, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:52.838 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:52.838 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received command 0x0A (TSN 219): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:52.838 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Attribute report received: on_off=<Bool.true: 1>
2022-11-12 14:44:52.838 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: received onoff Bool.true while transitioning - skipping update
2022-11-12 14:44:52.861 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:52.885 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=218)
2022-11-12 14:44:53.584 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_off, service_data=entity_id=light.lounge_light_light>
2022-11-12 14:44:53.586 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: setting transitioning flag to True
2022-11-12 14:44:53.586 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=24, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:44:53.587 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending request: off()
2022-11-12 14:44:53.587 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=1, source_route=None, extended_timeout=False, tsn=24, profile_id=260, cluster_id=6, data=Serialized[b'\x01\x18\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:53.588 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=24, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x18\x00')
2022-11-12 14:44:53.607 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:53.611 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=24)
2022-11-12 14:44:53.643 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=18, SecurityUse=<Bool.false: 0>, TimeStamp=8924679, TSN=0, Data=b'\x18\x18\x0B\x00\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:53.644 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\x18\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=18, rssi=None)
2022-11-12 14:44:53.644 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received ZCL frame: b'\x18\x18\x0b\x00\x00'
2022-11-12 14:44:53.644 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=24, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:53.645 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame: OnOff:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:53.645 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF9DE:1:0x0006]: executed 'off' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:53.645 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: starting transitioning timer for 1.25
2022-11-12 14:44:53.646 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: turned off: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:53.646 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light, old_state=<state light.lounge_light_light=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=1, off_with_transition=False, off_brightness=None, friendly_name=Lounge - Side Light, supported_features=40 @ 2022-11-12T14:44:52.836386+00:00>, new_state=<state light.lounge_light_light=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=1, friendly_name=Lounge - Side Light, supported_features=40 @ 2022-11-12T14:44:53.646261+00:00>>
2022-11-12 14:44:53.676 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8925923, TSN=0, Data=b'\x08\xDC\x0A\x00\x00\x10\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:53.677 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x08\xdc\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:53.677 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received ZCL frame: b'\x08\xdc\n\x00\x00\x10\x00'
2022-11-12 14:44:53.677 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=220, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:53.678 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:53.678 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received command 0x0A (TSN 220): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:53.678 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Attribute report received: on_off=<Bool.false: 0>
2022-11-12 14:44:53.684 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: received onoff Bool.false while transitioning - skipping update
2022-11-12 14:44:53.685 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=220, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:53.685 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:53.686 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=1, source_route=None, extended_timeout=False, tsn=220, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xdc\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:53.686 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=220, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=0, Data=b'\x18\xDC\x0B\x0A\x00')
2022-11-12 14:44:53.690 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8926129, TSN=0, Data=b'\x18\xDD\x0A\x00\x00\x10\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:53.691 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xdd\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:53.691 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received ZCL frame: b'\x18\xdd\n\x00\x00\x10\x00'
2022-11-12 14:44:53.691 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=221, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:53.692 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:53.692 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Received command 0x0A (TSN 221): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:53.692 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:1:0x0006] Attribute report received: on_off=<Bool.false: 0>
2022-11-12 14:44:53.692 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: received onoff Bool.false while transitioning - skipping update
2022-11-12 14:44:53.705 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:53.740 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=220)
2022-11-12 14:44:54.553 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.lounge_light_light_2>
2022-11-12 14:44:54.554 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: setting transitioning flag to True
2022-11-12 14:44:54.554 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=25, command_id=1, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:44:54.554 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending request: on()
2022-11-12 14:44:54.555 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=2, source_route=None, extended_timeout=False, tsn=25, profile_id=260, cluster_id=6, data=Serialized[b'\x01\x19\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:54.555 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=2, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=25, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x19\x01')
2022-11-12 14:44:54.574 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:54.578 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=25)
2022-11-12 14:44:54.649 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=2, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=18, SecurityUse=<Bool.false: 0>, TimeStamp=8986004, TSN=0, Data=b'\x18\x19\x0B\x01\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:54.649 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\x19\x0b\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=18, rssi=None)
2022-11-12 14:44:54.650 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received ZCL frame: b'\x18\x19\x0b\x01\x00'
2022-11-12 14:44:54.650 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=25, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:54.650 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame: OnOff:Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:54.651 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF9DE:2:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:54.651 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: starting transitioning timer for 1.25
2022-11-12 14:44:54.651 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: turned on: {'on_off': Default_Response(command_id=1, status=<Status.SUCCESS: 0>)}
2022-11-12 14:44:54.651 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light_2, old_state=<state light.lounge_light_light_2=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=255, friendly_name=Lounge - Main Light, supported_features=40 @ 2022-11-12T14:44:14.750261+00:00>, new_state=<state light.lounge_light_light_2=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=255, off_with_transition=False, off_brightness=None, friendly_name=Lounge - Main Light, supported_features=40 @ 2022-11-12T14:44:54.651858+00:00>>
2022-11-12 14:44:54.663 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=2, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8986265, TSN=0, Data=b'\x08\xDE\x0A\x00\x00\x10\x01', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:54.664 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=2, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=8986573, TSN=0, Data=b'\x18\xDF\x0A\x00\x00\x10\x01', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:54.664 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x08\xde\n\x00\x00\x10\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:54.664 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received ZCL frame: b'\x08\xde\n\x00\x00\x10\x01'
2022-11-12 14:44:54.665 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=222, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:54.665 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:54.665 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received command 0x0A (TSN 222): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:54.666 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Attribute report received: on_off=<Bool.true: 1>
2022-11-12 14:44:54.666 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: received onoff Bool.true while transitioning - skipping update
2022-11-12 14:44:54.666 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xdf\n\x00\x00\x10\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:54.666 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received ZCL frame: b'\x18\xdf\n\x00\x00\x10\x01'
2022-11-12 14:44:54.666 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=223, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:54.667 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:54.667 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received command 0x0A (TSN 223): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-11-12 14:44:54.667 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Attribute report received: on_off=<Bool.true: 1>
2022-11-12 14:44:54.667 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: received onoff Bool.true while transitioning - skipping update
2022-11-12 14:44:54.668 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=222, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:54.668 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:54.668 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=2, source_route=None, extended_timeout=False, tsn=222, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xde\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:54.669 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=2, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=222, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=0, Data=b'\x18\xDE\x0B\x0A\x00')
2022-11-12 14:44:54.688 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:54.714 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=222)
2022-11-12 14:44:54.896 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light: transition complete - future attribute reports will write HA state
2022-11-12 14:44:55.613 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1733](SA-003-Zigbee): Device seen - marking the device available and resetting counter
2022-11-12 14:44:55.615 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1733](SA-003-Zigbee): Update device availability - device available: True - new availability: True - changed: False
2022-11-12 14:44:55.744 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_off, service_data=entity_id=light.lounge_light_light_2>
2022-11-12 14:44:55.748 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: setting transitioning flag to True
2022-11-12 14:44:55.749 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=26, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:44:55.749 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending request: off()
2022-11-12 14:44:55.749 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=2, source_route=None, extended_timeout=False, tsn=26, profile_id=260, cluster_id=6, data=Serialized[b'\x01\x1a\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:55.750 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=2, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=26, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x1A\x00')
2022-11-12 14:44:55.769 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:55.773 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=26)
2022-11-12 14:44:55.861 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=2, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=9060921, TSN=0, Data=b'\x18\x1A\x0B\x00\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:55.861 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=2, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=9061244, TSN=0, Data=b'\x08\xE0\x0A\x00\x00\x10\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:55.862 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\x1a\x0b\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:55.862 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received ZCL frame: b'\x18\x1a\x0b\x00\x00'
2022-11-12 14:44:55.862 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=26, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:55.863 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame: OnOff:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:55.863 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x08\xe0\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:55.863 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received ZCL frame: b'\x08\xe0\n\x00\x00\x10\x00'
2022-11-12 14:44:55.864 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=224, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:55.864 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:55.864 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received command 0x0A (TSN 224): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:55.864 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Attribute report received: on_off=<Bool.false: 0>
2022-11-12 14:44:55.865 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: received onoff Bool.false while transitioning - skipping update
2022-11-12 14:44:55.866 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=224, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:55.866 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:55.867 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), dst_ep=2, source_route=None, extended_timeout=False, tsn=224, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xe0\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:44:55.870 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xF9DE, SrcEndpoint=2, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=9061571, TSN=0, Data=b'\x18\xE1\x0A\x00\x00\x10\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:44:55.870 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF9DE:2:0x0006]: executed 'off' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:55.871 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: starting transitioning timer for 1.25
2022-11-12 14:44:55.871 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: turned off: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-11-12 14:44:55.871 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light_2, old_state=<state light.lounge_light_light_2=on; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], color_mode=brightness, brightness=255, off_with_transition=False, off_brightness=None, friendly_name=Lounge - Main Light, supported_features=40 @ 2022-11-12T14:44:54.651858+00:00>, new_state=<state light.lounge_light_light_2=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=255, friendly_name=Lounge - Main Light, supported_features=40 @ 2022-11-12T14:44:55.871651+00:00>>
2022-11-12 14:44:55.872 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xF9DE), DstEndpoint=2, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=224, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=0, Data=b'\x18\xE0\x0B\x0A\x00')
2022-11-12 14:44:55.872 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF9DE), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xe1\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:44:55.872 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received ZCL frame: b'\x18\xe1\n\x00\x00\x10\x00'
2022-11-12 14:44:55.873 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=225, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:44:55.873 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:55.873 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Received command 0x0A (TSN 225): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-11-12 14:44:55.873 DEBUG (MainThread) [zigpy.zcl] [0xF9DE:2:0x0006] Attribute report received: on_off=<Bool.false: 0>
2022-11-12 14:44:55.874 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: received onoff Bool.false while transitioning - skipping update
2022-11-12 14:44:55.895 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:44:55.924 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=224)
2022-11-12 14:44:57.122 DEBUG (MainThread) [homeassistant.components.zha.entity] light.lounge_light_light_2: transition complete - future attribute reports will write HA state
2022-11-12 14:45:00.003 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.time, old_state=<state sensor.time=14:44; icon=mdi:clock, friendly_name=Time @ 2022-11-12T14:44:00.002776+00:00>, new_state=<state sensor.time=14:45; icon=mdi:clock, friendly_name=Time @ 2022-11-12T14:45:00.002947+00:00>>
2022-11-12 14:45:00.005 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.date_time, old_state=<state sensor.date_time=2022-11-12, 14:44; icon=mdi:calendar-clock, friendly_name=Date & Time @ 2022-11-12T14:44:00.002187+00:00>, new_state=<state sensor.date_time=2022-11-12, 14:45; icon=mdi:calendar-clock, friendly_name=Date & Time @ 2022-11-12T14:45:00.005598+00:00>>
2022-11-12 14:45:00.407 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.LeaveInd.Callback(NWK=0xF9DE, IEEE=a4:c1:38:2b:34:61:cd:aa, Request=<Bool.false: 0>, Remove=<Bool.false: 0>, Rejoin=<Bool.false: 0>)
2022-11-12 14:45:00.408 INFO (MainThread) [zigpy_znp.zigbee.application] ZDO device left: ZDO.LeaveInd.Callback(NWK=0xF9DE, IEEE=a4:c1:38:2b:34:61:cd:aa, Request=<Bool.false: 0>, Remove=<Bool.false: 0>, Rejoin=<Bool.false: 0>)
2022-11-12 14:45:00.408 INFO (MainThread) [zigpy.application] Device 0xf9de (a4:c1:38:2b:34:61:cd:aa) left the network
2022-11-12 14:45:00.408 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF9DE](TS110E): Update device availability - device available: True - new availability: False - changed: True
2022-11-12 14:45:00.409 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF9DE](TS110E): Device availability changed and device became unavailable
2022-11-12 14:45:00.409 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=a4:c1:38:2b:34:61:cd:aa, unique_id=a4:c1:38:2b:34:61:cd:aa, device_id=2bf6fe137b6d677b2bd78212179f02d5, device_event_type=device_offline>
2022-11-12 14:45:00.409 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=button.lounge_light_identify, old_state=<state button.lounge_light_identify=unknown; device_class=update, friendly_name=Lounge Light Identify @ 2022-11-12T14:42:35.722048+00:00>, new_state=<state button.lounge_light_identify=unavailable; device_class=update, friendly_name=Lounge Light Identify @ 2022-11-12T14:45:00.409457+00:00>>
2022-11-12 14:45:00.409 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light, old_state=<state light.lounge_light_light=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=1, friendly_name=Lounge - Side Light, supported_features=40 @ 2022-11-12T14:44:53.646261+00:00>, new_state=<state light.lounge_light_light=unavailable; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], friendly_name=Lounge - Side Light, supported_features=40 @ 2022-11-12T14:45:00.409832+00:00>>
2022-11-12 14:45:00.410 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light_2, old_state=<state light.lounge_light_light_2=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=255, friendly_name=Lounge - Main Light, supported_features=40 @ 2022-11-12T14:44:55.871651+00:00>, new_state=<state light.lounge_light_light_2=unavailable; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], friendly_name=Lounge - Main Light, supported_features=40 @ 2022-11-12T14:45:00.410614+00:00>>
2022-11-12 14:45:00.411 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x7587, Relays=[])
2022-11-12 14:45:00.411 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0xFB19, Relays=[])
2022-11-12 14:45:00.411 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x0120, Relays=[0xFB19])
2022-11-12 14:45:00.412 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0xC719, Relays=[0x7587])
2022-11-12 14:45:00.412 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x1733, Relays=[0xFB19])
2022-11-12 14:45:03.613 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0120](SA-003-Zigbee): Device seen - marking the device available and resetting counter
2022-11-12 14:45:03.613 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0120](SA-003-Zigbee): Update device availability - device available: True - new availability: True - changed: False
2022-11-12 14:45:04.225 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.frigate_shed_motion, old_state=<state binary_sensor.frigate_shed_motion=off; device_class=motion, friendly_name=Frigate Shed Motion @ 2022-11-12T14:42:27.845251+00:00>, new_state=<state binary_sensor.frigate_shed_motion=on; device_class=motion, friendly_name=Frigate Shed Motion @ 2022-11-12T14:45:04.225099+00:00>>
2022-11-12 14:45:04.399 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.frigate_front_door_motion, old_state=<state binary_sensor.frigate_front_door_motion=off; device_class=motion, friendly_name=Frigate Front Door Motion @ 2022-11-12T14:44:36.915905+00:00>, new_state=<state binary_sensor.frigate_front_door_motion=on; device_class=motion, friendly_name=Frigate Front Door Motion @ 2022-11-12T14:45:04.399267+00:00>>
2022-11-12 14:45:04.438 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0xFB19, Relays=[])
2022-11-12 14:45:04.439 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.TCDevInd.Callback(SrcNwk=0xB754, SrcIEEE=5c:02:72:ff:fe:e1:7b:1d, ParentNwk=0xFB19)
2022-11-12 14:45:04.440 INFO (MainThread) [zigpy_znp.zigbee.application] TC device join: ZDO.TCDevInd.Callback(SrcNwk=0xB754, SrcIEEE=5c:02:72:ff:fe:e1:7b:1d, ParentNwk=0xFB19)
2022-11-12 14:45:04.440 INFO (MainThread) [zigpy.application] Device 0xb754 (5c:02:72:ff:fe:e1:7b:1d) joined the network
2022-11-12 14:45:04.441 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=27, command_id=2, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:45:04.441 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Sending request: get_membership(groups=[])
2022-11-12 14:45:04.441 DEBUG (MainThread) [zigpy.device] [0xb754] Extending timeout for 0x1b request
2022-11-12 14:45:04.441 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), dst_ep=1, source_route=None, extended_timeout=True, tsn=27, profile_id=260, cluster_id=4, data=Serialized[b'\x01\x1b\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:45:04.442 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteDisc.Req(Dst=0xB754, Options=<RouteDiscoveryOptions.UNICAST: 0>, Radius=30)
2022-11-12 14:45:04.462 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.frigate_garden_motion, old_state=<state binary_sensor.frigate_garden_motion=off; device_class=motion, friendly_name=Frigate Garden Motion @ 2022-11-12T14:43:37.659089+00:00>, new_state=<state binary_sensor.frigate_garden_motion=on; device_class=motion, friendly_name=Frigate Garden Motion @ 2022-11-12T14:45:04.462511+00:00>>
2022-11-12 14:45:04.529 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteDisc.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:04.530 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.TCDevInd.Callback(SrcNwk=0xB754, SrcIEEE=5c:02:72:ff:fe:e1:7b:1d, ParentNwk=0xFB19)
2022-11-12 14:45:04.530 INFO (MainThread) [zigpy_znp.zigbee.application] TC device join: ZDO.TCDevInd.Callback(SrcNwk=0xB754, SrcIEEE=5c:02:72:ff:fe:e1:7b:1d, ParentNwk=0xFB19)
2022-11-12 14:45:04.530 INFO (MainThread) [zigpy.application] Device 0xb754 (5c:02:72:ff:fe:e1:7b:1d) joined the network
2022-11-12 14:45:04.531 DEBUG (MainThread) [zigpy.device] [0xb754] Cancelling old group rescan
2022-11-12 14:45:04.532 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=28, command_id=2, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:45:04.532 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Sending request: get_membership(groups=[])
2022-11-12 14:45:04.532 DEBUG (MainThread) [zigpy.device] [0xb754] Extending timeout for 0x1c request
2022-11-12 14:45:04.532 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), dst_ep=1, source_route=None, extended_timeout=True, tsn=28, profile_id=260, cluster_id=4, data=Serialized[b'\x01\x1c\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:45:04.533 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xB754), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=4, TSN=27, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x1B\x02\x00')
2022-11-12 14:45:04.552 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:04.552 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xB754), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=4, TSN=28, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x1C\x02\x00')
2022-11-12 14:45:04.595 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:04.681 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.EndDeviceAnnceInd.Callback(Src=0xB754, NWK=0xB754, IEEE=5c:02:72:ff:fe:e1:7b:1d, Capabilities=<MACCapabilities.AllocateShortAddrDuringAssocNeeded: 128>)
2022-11-12 14:45:04.682 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-11-12 14:45:04.682 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0xB754, IsBroadcast=<Bool.true: 1>, ClusterId=19, SecurityUse=0, TSN=130, MacDst=0xFFFF, Data=b'\x54\xB7\x1D\x7B\xE1\xFE\xFF\x72\x02\x5C\x80')
2022-11-12 14:45:04.683 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=130, profile_id=0, cluster_id=19, data=Serialized[b'\x82T\xb7\x1d{\xe1\xfe\xffr\x02\\\x80'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:45:04.683 INFO (MainThread) [zigpy.application] Device 0xb754 (5c:02:72:ff:fe:e1:7b:1d) joined the network
2022-11-12 14:45:04.684 DEBUG (MainThread) [zigpy.device] [0xb754] Cancelling old group rescan
2022-11-12 14:45:04.684 DEBUG (MainThread) [zigpy.zdo] [0xb754:zdo] ZDO request ZDOCmd.Device_annce: [0xB754, 5c:02:72:ff:fe:e1:7b:1d, 128]
2022-11-12 14:45:04.684 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=29, command_id=2, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:45:04.685 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Sending request: get_membership(groups=[])
2022-11-12 14:45:04.685 DEBUG (MainThread) [zigpy.device] [0xb754] Extending timeout for 0x1d request
2022-11-12 14:45:04.685 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), dst_ep=1, source_route=None, extended_timeout=True, tsn=29, profile_id=260, cluster_id=4, data=Serialized[b'\x01\x1d\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:45:04.686 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xB754), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=4, TSN=29, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x1D\x02\x00')
2022-11-12 14:45:04.707 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:05.442 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.NWK_NO_ROUTE: 205>, Endpoint=1, TSN=28)
2022-11-12 14:45:05.492 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2022-11-12 14:45:05.498 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2022-11-12 14:45:05.690 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.NWK_NO_ROUTE: 205>, Endpoint=1, TSN=29)
2022-11-12 14:45:05.690 DEBUG (MainThread) [zigpy_znp.zigbee.application] Request failed (Unsuccessful request status code: <Status.NWK_NO_ROUTE: 205>), retry attempt 1 of 5
2022-11-12 14:45:05.773 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.NWK_NO_ROUTE: 205>, Endpoint=1, TSN=27)
2022-11-12 14:45:07.193 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xB754), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=4, TSN=29, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x1D\x02\x00')
2022-11-12 14:45:07.201 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:08.092 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.NWK_NO_ROUTE: 205>, Endpoint=1, TSN=29)
2022-11-12 14:45:08.092 DEBUG (MainThread) [zigpy_znp.zigbee.application] Request failed (Unsuccessful request status code: <Status.NWK_NO_ROUTE: 205>), retry attempt 2 of 5
2022-11-12 14:45:08.594 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestSrcRtg.Req(DstAddr=0xB754, DstEndpoint=1, SrcEndpoint=1, ClusterId=4, TSN=29, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, SourceRoute=[0x0120, 0xFB19], Data=b'\x01\x1D\x02\x00')
2022-11-12 14:45:08.613 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestSrcRtg.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:08.616 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=29)
2022-11-12 14:45:08.680 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=10, SrcAddr=0xB754, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=9864410, TSN=0, Data=b'\x00\x00\x00\x07\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:45:08.680 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=10, data=Serialized[b'\x00\x00\x00\x07\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:45:08.681 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x000a] Received ZCL frame: b'\x00\x00\x00\x07\x00'
2022-11-12 14:45:08.681 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=0, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:45:08.681 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[7])
2022-11-12 14:45:08.682 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x000a] Received command 0x00 (TSN 0): Read_Attributes(attribute_ids=[7])
2022-11-12 14:45:08.684 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=0, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:45:08.684 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=LocalTime, value=721579508))])
2022-11-12 14:45:08.685 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=10, data=Serialized[b'\x18\x00\x01\x07\x00\x00#\xf4m\x02+'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:45:08.685 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0xB754), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=10, TSN=0, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=0, Data=b'\x18\x00\x01\x07\x00\x00\x23\xF4\x6D\x02\x2B')
2022-11-12 14:45:08.705 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:45:08.895 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=4, SrcAddr=0xB754, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=9877145, TSN=0, Data=b'\x19\x1D\x02\xFF\x00', MacSrcAddr=0xFB19, MsgResultRadius=28)
2022-11-12 14:45:08.896 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xB754), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=4, data=Serialized[b'\x19\x1d\x02\xff\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=28, non_member_radius=0, lqi=21, rssi=None)
2022-11-12 14:45:08.896 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Received ZCL frame: b'\x19\x1d\x02\xff\x00'
2022-11-12 14:45:08.896 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=29, command_id=2, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-11-12 14:45:08.897 DEBUG (MainThread) [zigpy.zcl] [0xB754:1:0x0004] Decoded ZCL frame: Groups:get_membership_response(capacity=255, groups=[])
Physical switching logs
2022-11-12 14:53:35.637 DEBUG (MainThread) [zigpy_znp.api] Sending request: SYS.Ping.Req()
2022-11-12 14:53:35.642 DEBUG (MainThread) [zigpy_znp.api] Received command: SYS.Ping.Rsp(Capabilities=<MTCapabilities.APP_CNF|GP|UTIL|ZDO|AF|SYS: 1625>)
2022-11-12 14:53:41.627 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0120](SA-003-Zigbee): Device seen - marking the device available and resetting counter
2022-11-12 14:53:41.627 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0120](SA-003-Zigbee): Update device availability - device available: True - new availability: True - changed: False
2022-11-12 14:53:42.174 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.LeaveInd.Callback(NWK=0xCCF1, IEEE=a4:c1:38:2b:34:61:cd:aa, Request=<Bool.false: 0>, Remove=<Bool.false: 0>, Rejoin=<Bool.false: 0>)
2022-11-12 14:53:42.174 INFO (MainThread) [zigpy_znp.zigbee.application] ZDO device left: ZDO.LeaveInd.Callback(NWK=0xCCF1, IEEE=a4:c1:38:2b:34:61:cd:aa, Request=<Bool.false: 0>, Remove=<Bool.false: 0>, Rejoin=<Bool.false: 0>)
2022-11-12 14:53:42.174 INFO (MainThread) [zigpy.application] Device 0xccf1 (a4:c1:38:2b:34:61:cd:aa) left the network
2022-11-12 14:53:42.174 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xCCF1](TS110E): Update device availability - device available: True - new availability: False - changed: True
2022-11-12 14:53:42.174 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xCCF1](TS110E): Device availability changed and device became unavailable
2022-11-12 14:53:42.175 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=a4:c1:38:2b:34:61:cd:aa, unique_id=a4:c1:38:2b:34:61:cd:aa, device_id=2bf6fe137b6d677b2bd78212179f02d5, device_event_type=device_offline>
2022-11-12 14:53:42.175 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=button.lounge_light_identify, old_state=<state button.lounge_light_identify=unknown; device_class=update, friendly_name=Lounge Light Identify @ 2022-11-12T14:53:18.397223+00:00>, new_state=<state button.lounge_light_identify=unavailable; device_class=update, friendly_name=Lounge Light Identify @ 2022-11-12T14:53:42.175280+00:00>>
2022-11-12 14:53:42.175 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light, old_state=<state light.lounge_light_light=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=181, friendly_name=Lounge Light Light, supported_features=40 @ 2022-11-12T14:53:18.397931+00:00>, new_state=<state light.lounge_light_light=unavailable; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], friendly_name=Lounge Light Light, supported_features=40 @ 2022-11-12T14:53:42.175648+00:00>>
2022-11-12 14:53:42.175 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lounge_light_light_2, old_state=<state light.lounge_light_light_2=off; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], off_with_transition=False, off_brightness=254, friendly_name=Lounge Light Light, supported_features=40 @ 2022-11-12T14:53:18.398613+00:00>, new_state=<state light.lounge_light_light_2=unavailable; supported_color_modes=[<ColorMode.BRIGHTNESS: 'brightness'>], friendly_name=Lounge Light Light, supported_features=40 @ 2022-11-12T14:53:42.175881+00:00>>
2022-11-12 14:53:42.176 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0x7587, Relays=[])
2022-11-12 14:53:42.176 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0xFB19, Relays=[])
2022-11-12 14:53:43.282 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=climate.ours, old_state=<state climate.ours=auto; hvac_modes=[<HVACMode.AUTO: 'auto'>, <HVACMode.HEAT: 'heat'>, <HVACMode.OFF: 'off'>], min_temp=5, max_temp=32, preset_modes=['boost', 'none'], current_temperature=17.4, temperature=7.0, hvac_action=idle, preset_mode=none, friendly_name=Ours, supported_features=17 @ 2022-11-12T14:42:28.219717+00:00>, new_state=<state climate.ours=auto; hvac_modes=[<HVACMode.AUTO: 'auto'>, <HVACMode.HEAT: 'heat'>, <HVACMode.OFF: 'off'>], min_temp=5, max_temp=32, preset_modes=['boost', 'none'], current_temperature=17.3, temperature=7.0, hvac_action=idle, preset_mode=none, friendly_name=Ours, supported_features=17 @ 2022-11-12T14:42:28.219717+00:00>>
2022-11-12 14:53:45.775 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.TCDevInd.Callback(SrcNwk=0x6832, SrcIEEE=a4:c1:38:2b:34:61:cd:aa, ParentNwk=0xFB19)
2022-11-12 14:53:45.775 INFO (MainThread) [zigpy_znp.zigbee.application] TC device join: ZDO.TCDevInd.Callback(SrcNwk=0x6832, SrcIEEE=a4:c1:38:2b:34:61:cd:aa, ParentNwk=0xFB19)
2022-11-12 14:53:45.776 INFO (MainThread) [zigpy.application] Device 0x6832 (a4:c1:38:2b:34:61:cd:aa) joined the network
2022-11-12 14:53:45.776 DEBUG (MainThread) [zigpy.application] Device a4:c1:38:2b:34:61:cd:aa changed id (0xccf1 => 0x6832)
2022-11-12 14:53:45.776 DEBUG (MainThread) [zigpy.device] [0x6832] Skipping initialization, device is fully initialized
2022-11-12 14:53:45.776 DEBUG (MainThread) [zigpy.application] Device is initialized <DimmerSwitchWithNeutral2Gang model='TS110E' manuf='_TZ3210_wdexaypg' nwk=0x6832 ieee=a4:c1:38:2b:34:61:cd:aa is_initialized=True>
2022-11-12 14:53:45.777 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.TCDevInd.Callback(SrcNwk=0x6832, SrcIEEE=a4:c1:38:2b:34:61:cd:aa, ParentNwk=0xFB19)
2022-11-12 14:53:45.777 INFO (MainThread) [zigpy_znp.zigbee.application] TC device join: ZDO.TCDevInd.Callback(SrcNwk=0x6832, SrcIEEE=a4:c1:38:2b:34:61:cd:aa, ParentNwk=0xFB19)
2022-11-12 14:53:45.777 INFO (MainThread) [zigpy.application] Device 0x6832 (a4:c1:38:2b:34:61:cd:aa) joined the network
2022-11-12 14:53:45.778 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x6832:a4:c1:38:2b:34:61:cd:aa entering async_device_initialized - is_new_join: False
2022-11-12 14:53:45.778 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x6832:a4:c1:38:2b:34:61:cd:aa has been reset and re-added or its nwk address changed
2022-11-12 14:53:45.778 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] skipping discovery for previously discovered device - 0x6832:a4:c1:38:2b:34:61:cd:aa
2022-11-12 14:53:45.778 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x6832](TS110E): started configuration
2022-11-12 14:53:45.778 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:ZDO](TS110E): 'async_configure' stage succeeded
2022-11-12 14:53:45.779 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=156, command_id=2, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:53:45.779 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0004] Sending request: get_membership(groups=[])
2022-11-12 14:53:45.781 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=1, source_route=None, extended_timeout=False, tsn=156, profile_id=260, cluster_id=4, data=Serialized[b'\x01\x9c\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:45.781 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteDisc.Req(Dst=0x6832, Options=<RouteDiscoveryOptions.UNICAST: 0>, Radius=30)
2022-11-12 14:53:45.782 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0006]: Performing cluster binding
2022-11-12 14:53:45.782 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=0, source_route=None, extended_timeout=False, tsn=157, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x9d\xaa\xcda4+8\xc1\xa4\x01\x06\x00\x03\xef\xae\xc2$\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:45.783 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: Performing cluster binding
2022-11-12 14:53:45.783 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=0, source_route=None, extended_timeout=False, tsn=158, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x9e\xaa\xcda4+8\xc1\xa4\x01\x08\x00\x03\xef\xae\xc2$\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:45.783 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0003]: Configuring cluster attribute reporting
2022-11-12 14:53:45.783 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0003]: finished channel configuration
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0000]: Configuring cluster attribute reporting
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0000]: finished channel configuration
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0xef00]: Performing cluster binding
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0xef00]: bound 'tuya_manufacturer' cluster: Status.SUCCESS
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0xef00]: Configuring cluster attribute reporting
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0xef00]: finished channel configuration
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0019]: finished channel configuration
2022-11-12 14:53:45.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0006]: Performing cluster binding
2022-11-12 14:53:45.784 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=0, source_route=None, extended_timeout=False, tsn=159, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\x9f\xaa\xcda4+8\xc1\xa4\x02\x06\x00\x03\xef\xae\xc2$\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:45.785 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: Performing cluster binding
2022-11-12 14:53:45.785 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=0, source_route=None, extended_timeout=False, tsn=160, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'\xa0\xaa\xcda4+8\xc1\xa4\x02\x08\x00\x03\xef\xae\xc2$\x00K\x12\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:45.785 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0003]: Configuring cluster attribute reporting
2022-11-12 14:53:45.785 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0003]: finished channel configuration
2022-11-12 14:53:45.786 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0xef00]: Performing cluster binding
2022-11-12 14:53:45.786 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0xef00]: bound 'tuya_manufacturer' cluster: Status.SUCCESS
2022-11-12 14:53:45.786 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0xef00]: Configuring cluster attribute reporting
2022-11-12 14:53:45.786 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0xef00]: finished channel configuration
2022-11-12 14:53:45.796 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteDisc.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:53:45.796 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x6832), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=4, TSN=156, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\x9C\x02\x00')
2022-11-12 14:53:45.803 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:53:45.804 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x6832, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-11-12 14:53:45.809 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.FAIL: 1>)
2022-11-12 14:53:45.809 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x6832, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-11-12 14:53:45.814 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.FAIL: 1>)
2022-11-12 14:53:45.814 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x6832, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-11-12 14:53:45.819 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.FAIL: 1>)
2022-11-12 14:53:45.820 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x6832, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-11-12 14:53:45.825 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.FAIL: 1>)
2022-11-12 14:53:45.845 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=system_log, service=write, service_data=logger=frontend.js.latest.202211080, message=https://XXX.duckdns.org/frontend_latest/1696351a.js:256:519 Uncaught TypeError: Cannot read properties of undefined (reading 'get')>
2022-11-12 14:53:45.845 ERROR (MainThread) [frontend.js.latest.202211080] https://XXX.duckdns.org/frontend_latest/1696351a.js:256:519 Uncaught TypeError: Cannot read properties of undefined (reading 'get')
2022-11-12 14:53:46.050 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.EndDeviceAnnceInd.Callback(Src=0x6832, NWK=0x6832, IEEE=a4:c1:38:2b:34:61:cd:aa, Capabilities=<MACCapabilities.AllocateShortAddrDuringAssocNeeded|RXWhenIdle|MainsPowered|Router: 142>)
2022-11-12 14:53:46.051 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-11-12 14:53:46.056 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x6832, IsBroadcast=<Bool.true: 1>, ClusterId=19, SecurityUse=0, TSN=11, MacDst=0xFFFF, Data=b'\x32\x68\xAA\xCD\x61\x34\x2B\x38\xC1\xA4\x8E')
2022-11-12 14:53:46.057 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=11, profile_id=0, cluster_id=19, data=Serialized[b'\x0b2h\xaa\xcda4+8\xc1\xa4\x8e'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:46.057 INFO (MainThread) [zigpy.application] Device 0x6832 (a4:c1:38:2b:34:61:cd:aa) joined the network
2022-11-12 14:53:46.057 DEBUG (MainThread) [zigpy.device] [0x6832] Cancelling old group rescan
2022-11-12 14:53:46.057 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x6832](TS110E): Update device availability - device available: False - new availability: True - changed: True
2022-11-12 14:53:46.057 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x6832](TS110E): Device availability changed and device became available, reinitializing channels
2022-11-12 14:53:46.057 DEBUG (MainThread) [zigpy.zdo] [0x6832:zdo] ZDO request ZDOCmd.Device_annce: [0x6832, a4:c1:38:2b:34:61:cd:aa, 142]
2022-11-12 14:53:46.058 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0004] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=False), tsn=161, command_id=2, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:53:46.059 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0004] Sending request: get_membership(groups=[])
2022-11-12 14:53:46.059 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=1, source_route=None, extended_timeout=False, tsn=161, profile_id=260, cluster_id=4, data=Serialized[b'\x01\xa1\x02\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:46.059 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x6832](TS110E): started initialization
2022-11-12 14:53:46.059 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:ZDO](TS110E): 'async_initialize' stage succeeded
2022-11-12 14:53:46.060 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x6832), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=4, TSN=161, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x01\xA1\x02\x00')
2022-11-12 14:53:46.060 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0006]: initializing channel: from_cache: False
2022-11-12 14:53:46.060 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0006]: initializing cached channel attributes: ['start_up_on_off']
2022-11-12 14:53:46.061 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0006]: Reading attributes in chunks: ['start_up_on_off']
2022-11-12 14:53:46.061 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2022-11-12 14:53:46.061 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0006]: Reading attributes in chunks: ['on_off']
2022-11-12 14:53:46.061 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=162, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:53:46.061 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0006] Sending request: Read_Attributes(attribute_ids=[0])
2022-11-12 14:53:46.062 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=1, source_route=None, extended_timeout=False, tsn=162, profile_id=260, cluster_id=6, data=Serialized[b'\x00\xa2\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:46.062 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: initializing channel: from_cache: False
2022-11-12 14:53:46.062 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: initializing cached channel attributes: ['on_off_transition_time', 'on_level', 'on_transition_time', 'off_transition_time', 'default_move_rate', 'start_up_current_level']
2022-11-12 14:53:46.062 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: Reading attributes in chunks: ['on_off_transition_time', 'on_level', 'on_transition_time', 'off_transition_time', 'default_move_rate']
2022-11-12 14:53:46.062 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: Reading attributes in chunks: ['start_up_current_level']
2022-11-12 14:53:46.062 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: initializing uncached channel attributes: ['current_level'] - from cache[False]
2022-11-12 14:53:46.062 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0008]: Reading attributes in chunks: ['current_level']
2022-11-12 14:53:46.063 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0008] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=163, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:53:46.063 DEBUG (MainThread) [zigpy.zcl] [0x6832:1:0x0008] Sending request: Read_Attributes(attribute_ids=[0])
2022-11-12 14:53:46.063 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=1, source_route=None, extended_timeout=False, tsn=163, profile_id=260, cluster_id=8, data=Serialized[b'\x00\xa3\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:46.063 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0003]: initializing channel: from_cache: False
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0003]: finished channel initialization
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0000]: initializing channel: from_cache: False
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0000]: finished channel initialization
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0xef00]: initializing channel: from_cache: False
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0xef00]: finished channel initialization
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0019]: initializing channel: from_cache: False
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:1:0x0019]: finished channel initialization
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0006]: initializing channel: from_cache: False
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0006]: initializing cached channel attributes: ['start_up_on_off']
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0006]: Reading attributes in chunks: ['start_up_on_off']
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2022-11-12 14:53:46.064 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0006]: Reading attributes in chunks: ['on_off']
2022-11-12 14:53:46.065 DEBUG (MainThread) [zigpy.zcl] [0x6832:2:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=164, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:53:46.065 DEBUG (MainThread) [zigpy.zcl] [0x6832:2:0x0006] Sending request: Read_Attributes(attribute_ids=[0])
2022-11-12 14:53:46.066 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=2, source_route=None, extended_timeout=False, tsn=164, profile_id=260, cluster_id=6, data=Serialized[b'\x00\xa4\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:46.066 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: initializing channel: from_cache: False
2022-11-12 14:53:46.066 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: initializing cached channel attributes: ['on_off_transition_time', 'on_level', 'on_transition_time', 'off_transition_time', 'default_move_rate', 'start_up_current_level']
2022-11-12 14:53:46.066 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: Reading attributes in chunks: ['on_off_transition_time', 'on_level', 'on_transition_time', 'off_transition_time', 'default_move_rate']
2022-11-12 14:53:46.066 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: Reading attributes in chunks: ['start_up_current_level']
2022-11-12 14:53:46.067 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: initializing uncached channel attributes: ['current_level'] - from cache[False]
2022-11-12 14:53:46.067 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0008]: Reading attributes in chunks: ['current_level']
2022-11-12 14:53:46.067 DEBUG (MainThread) [zigpy.zcl] [0x6832:2:0x0008] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True, *is_reply=False), tsn=165, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-11-12 14:53:46.067 DEBUG (MainThread) [zigpy.zcl] [0x6832:2:0x0008] Sending request: Read_Attributes(attribute_ids=[0])
2022-11-12 14:53:46.067 DEBUG (MainThread) [zigpy_znp.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=2, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x6832), dst_ep=2, source_route=None, extended_timeout=False, tsn=165, profile_id=260, cluster_id=8, data=Serialized[b'\x00\xa5\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
2022-11-12 14:53:46.068 DEBUG (MainThread) [zigpy.application] Max concurrency (8) reached, delaying request (0 enqueued)
2022-11-12 14:53:46.068 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0003]: initializing channel: from_cache: False
2022-11-12 14:53:46.068 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0x0003]: finished channel initialization
2022-11-12 14:53:46.068 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0xef00]: initializing channel: from_cache: False
2022-11-12 14:53:46.068 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6832:2:0xef00]: finished channel initialization
2022-11-12 14:53:46.080 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:53:46.080 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x6832), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=162, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x00\xA2\x00\x00\x00')
2022-11-12 14:53:46.088 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:53:46.089 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x6832), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=8, TSN=163, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x00\xA3\x00\x00\x00')
2022-11-12 14:53:46.096 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-11-12 14:53:46.096 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x6832), DstEndpoint=2, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=164, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=0, Data=b'\x00\xA4\x00\x00\x00')
2022-11-12 14:53:46.104 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)

it looks like even after setting the bulb_type, moving the physical switch puts it in a reconnect state. (From my understanding)

@github-actions
Copy link

github-actions bot commented Jul 3, 2023

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.

@github-actions github-actions bot added the stale Issue is inactivate and might get closed soon label Jul 3, 2023
@github-actions github-actions bot closed this as not planned Won't fix, can't repro, duplicate, stale Jul 10, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
stale Issue is inactivate and might get closed soon Tuya Request/PR regarding a Tuya device
Projects
None yet
Development

No branches or pull requests

3 participants