zha-device-handlers icon indicating copy to clipboard operation
zha-device-handlers copied to clipboard

[BUG] TS0601 changing brightness turns off device

Open JoryHogeveen opened this issue 1 year ago • 1 comments

Describe the bug

  1. When changing the brightness of this device it shows as "on" within HA but the device itself doesn't turn on.
  2. When changing the brightness of this device when it is already on it will turn off the device while HA still reports it as "on".

To Reproduce Steps to reproduce the behavior:

  1. Turn on light
  2. Try change brightbess
  3. Light turns off (not in HA but the switch turns off)

Expected behavior A clear and concise description of what you expected to happen. This used to work, I honestly don't know what version. I'm 100% sure this is broken in all 2022.8.x versions.

Device signature
{
  "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x0100",
      "in_clusters": [
        "0x0000",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0xef00"
      ],
      "out_clusters": [
        "0x000a",
        "0x0019"
      ]
    }
  },
  "manufacturer": "_TZE200_dfxkcots",
  "model": "TS0601",
  "class": "zhaquirks.tuya.ts0601_dimmer.TuyaSingleSwitchDimmer"
}
Diagnostic information
{
  "home_assistant": {
    "installation_type": "Home Assistant Container",
    "version": "2022.8.4",
    "dev": false,
    "hassio": false,
    "virtualenv": false,
    "python_version": "3.10.5",
    "docker": true,
    "arch": "aarch64",
    "timezone": "Europe/Amsterdam",
    "os_name": "Linux",
    "os_version": "5.4.0-1068-raspi",
    "run_as_root": true
  },
  "custom_components": {
    "afvalbeheer": {
      "version": "4.9.6",
      "requirements": [
        "rsa",
        "pycryptodome"
      ]
    },
    "luchtmeetnet": {
      "version": "0.0.3",
      "requirements": [
        "luchtmeetnet==0.0.2"
      ]
    },
    "dwd_pollenflug": {
      "version": "1.0.1",
      "requirements": []
    },
    "adaptive_lighting": {
      "version": "1.0.14",
      "requirements": []
    },
    "hacs": {
      "version": "1.26.2",
      "requirements": [
        "aiogithubapi>=22.2.4"
      ]
    },
    "worlds_air_quality_index": {
      "version": "0.3.3",
      "requirements": []
    },
    "powercalc": {
      "version": "v0.25.0-beta.8",
      "requirements": [
        "numpy>=1.21.1"
      ]
    },
    "zha_toolkit": {
      "version": "v0.8.12",
      "requirements": []
    },
    "toon_climate": {
      "version": "1.0.12",
      "requirements": []
    }
  },
  "integration_manifest": {
    "domain": "zha",
    "name": "Zigbee Home Automation",
    "config_flow": true,
    "documentation": "https://www.home-assistant.io/integrations/zha",
    "requirements": [
      "bellows==0.32.0",
      "pyserial==3.5",
      "pyserial-asyncio==0.6",
      "zha-quirks==0.0.78",
      "zigpy-deconz==0.18.0",
      "zigpy==0.49.1",
      "zigpy-xbee==0.15.0",
      "zigpy-zigate==0.9.1",
      "zigpy-znp==0.8.1"
    ],
    "usb": [
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*2652*",
        "known_devices": [
          "slae.sh cc2652rb stick"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*sonoff*plus*",
        "known_devices": [
          "sonoff zigbee dongle plus"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*tubeszb*",
        "known_devices": [
          "TubesZB Coordinator"
        ]
      },
      {
        "vid": "1A86",
        "pid": "7523",
        "description": "*tubeszb*",
        "known_devices": [
          "TubesZB Coordinator"
        ]
      },
      {
        "vid": "1A86",
        "pid": "7523",
        "description": "*zigstar*",
        "known_devices": [
          "ZigStar Coordinators"
        ]
      },
      {
        "vid": "1CF1",
        "pid": "0030",
        "description": "*conbee*",
        "known_devices": [
          "Conbee II"
        ]
      },
      {
        "vid": "10C4",
        "pid": "8A2A",
        "description": "*zigbee*",
        "known_devices": [
          "Nortek HUSBZB-1"
        ]
      },
      {
        "vid": "0403",
        "pid": "6015",
        "description": "*zigate*",
        "known_devices": [
          "ZiGate+"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*zigate*",
        "known_devices": [
          "ZiGate"
        ]
      },
      {
        "vid": "10C4",
        "pid": "8B34",
        "description": "*bv 2010/10*",
        "known_devices": [
          "Bitron Video AV2010/10"
        ]
      }
    ],
    "codeowners": [
      "@dmulcahey",
      "@adminiuga",
      "@puddly"
    ],
    "zeroconf": [
      {
        "type": "_esphomelib._tcp.local.",
        "name": "tube*"
      },
      {
        "type": "_zigate-zigbee-gateway._tcp.local.",
        "name": "*zigate*"
      }
    ],
    "after_dependencies": [
      "onboarding",
      "usb",
      "zeroconf"
    ],
    "iot_class": "local_polling",
    "loggers": [
      "aiosqlite",
      "bellows",
      "crccheck",
      "pure_pcapy3",
      "zhaquirks",
      "zigpy",
      "zigpy_deconz",
      "zigpy_xbee",
      "zigpy_zigate",
      "zigpy_znp"
    ],
    "is_built_in": true
  },
  "data": {
    "ieee": "**REDACTED**",
    "nwk": 22991,
    "manufacturer": "_TZE200_dfxkcots",
    "model": "TS0601",
    "name": "_TZE200_dfxkcots TS0601",
    "quirk_applied": true,
    "quirk_class": "zhaquirks.tuya.ts0601_dimmer.TuyaSingleSwitchDimmer",
    "manufacturer_code": 4098,
    "power_source": "Mains",
    "lqi": 255,
    "rssi": -55,
    "last_seen": "2022-08-16T00:01:11",
    "available": true,
    "device_type": "Router",
    "signature": {
      "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4098, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
      "endpoints": {
        "1": {
          "profile_id": 260,
          "device_type": "0x0100",
          "in_clusters": [
            "0x0000",
            "0x0004",
            "0x0005",
            "0x0006",
            "0x0008",
            "0xef00"
          ],
          "out_clusters": [
            "0x000a",
            "0x0019"
          ]
        }
      }
    },
    "active_coordinator": false,
    "entities": [
      {
        "entity_id": "light.light_livingroom_table_level_on_off",
        "name": "_TZE200_dfxkcots TS0601"
      }
    ],
    "neighbors": [
      {
        "device_type": "Coordinator",
        "rx_on_when_idle": "On",
        "relationship": "Parent",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x0000",
        "permit_joining": "Unknown",
        "depth": "0",
        "lqi": "194"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x106A",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "147"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x11DD",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "78"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x13F4",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "87"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x3B8E",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "73"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x5133",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "36"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x553C",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "110"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x5EED",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "55"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x686D",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "126"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x6BC1",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "62"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x6E6F",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "57"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x7949",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "69"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x8347",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "136"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x8E84",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "63"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0x9FDB",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "74"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xAD50",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "64"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xB96B",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "28"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xBDDE",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "102"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xC2B7",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "98"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xC52E",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "44"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xCC93",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "49"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xD3D4",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "50"
      },
      {
        "device_type": "Router",
        "rx_on_when_idle": "On",
        "relationship": "Sibling",
        "extended_pan_id": "**REDACTED**",
        "ieee": "**REDACTED**",
        "nwk": "0xD910",
        "permit_joining": "Unknown",
        "depth": "15",
        "lqi": "82"
      }
    ],
    "endpoint_names": [
      {
        "name": "ON_OFF_LIGHT"
      }
    ],
    "user_given_name": "Light - Livingroom Table",
    "device_reg_id": "e2372bba3fb7b1b656c1f1944c40bd4d",
    "area_id": "livingroom",
    "cluster_details": {
      "1": {
        "device_type": {
          "name": "ON_OFF_LIGHT",
          "id": 256
        },
        "profile_id": 260,
        "in_clusters": {
          "0x0000": {
            "endpoint_attribute": "basic",
            "attributes": {
              "0x0004": {
                "attribute_name": "manufacturer",
                "value": "_TZE200_dfxkcots"
              },
              "0x0005": {
                "attribute_name": "model",
                "value": "TS0601"
              }
            },
            "unsupported_attributes": {}
          },
          "0x0004": {
            "endpoint_attribute": "groups",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0005": {
            "endpoint_attribute": "scenes",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0xef00": {
            "endpoint_attribute": "tuya_manufacturer",
            "attributes": {
              "0xef00": {
                "attribute_name": "mcu_version",
                "value": "1.0.1"
              }
            },
            "unsupported_attributes": {}
          },
          "0x0006": {
            "endpoint_attribute": "on_off",
            "attributes": {
              "0x0000": {
                "attribute_name": "on_off",
                "value": 0
              }
            },
            "unsupported_attributes": {
              "0x4003": {
                "attribute_name": "start_up_on_off"
              }
            }
          },
          "0x0008": {
            "endpoint_attribute": "level",
            "attributes": {
              "0x0000": {
                "attribute_name": "current_level",
                "value": 124
              },
              "0xef01": {
                "attribute_name": "minimum_level",
                "value": 2
              }
            },
            "unsupported_attributes": {}
          }
        },
        "out_clusters": {
          "0x000a": {
            "endpoint_attribute": "time",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0019": {
            "endpoint_attribute": "ota",
            "attributes": {},
            "unsupported_attributes": {}
          }
        }
      }
    }
  }
}
Additional logs
Paste any additional debug logs here.
Don't remove the extra line breaks outside the ``` marks.

Additional context

Related issues:

  • https://github.com/zigpy/zha-device-handlers/issues/1554

Possible causes:

  • https://github.com/zigpy/zha-device-handlers/pull/1572
  • https://github.com/zigpy/zha-device-handlers/pull/1489
    • Similar report: https://github.com/zigpy/zha-device-handlers/pull/1489#issuecomment-1118113670

JoryHogeveen avatar Aug 15 '22 21:08 JoryHogeveen

@javicalle

Thanks for the info at #1554 You mention that the device should turn on when I change the brightness. This does not happen with my device. It turns on in HA but the device itself stays off. I will add this to the bug report above.

JoryHogeveen avatar Aug 15 '22 22:08 JoryHogeveen

I recently changed from Deconz + Conbee II to ZHA + Conbee II. I have the exact same problem and read all the topics I could find, but did not find a solution yet. With Deconz, I had no issues. I even bought a Tuya Gateway, just to be sure I have the latest firmware. When connected to the Tuya gateway (Tuya cloud integration), I could change the brightness without any problem.

I have no experience with ZHA before HA 2022.8. I also didn’t find other people with this problem until now. I would expect there has something changed what is causing this issue.

I have 3 different Tuya Dimmers (all from brand Moes), and all have the same behavior with turning off the actual light (not the status of the light) when brightness is changed (slider in UI or change in HomeKit for instance).

types: _TZE200_dfxkcots (I have a Moes Rotary knob dimmer and the Moes touch slider dimmer) _TZE200_e3oitdyu (Moes 2 gang dimmer / 105B)

I hope there is a solution for this behavior. If I can help with some logs, I need some guidance what to do.

Joepie007v2 avatar Aug 22 '22 19:08 Joepie007v2

Confirming the issue as well with my dimmer (TZE200_3p5ydos3)

lotr avatar Aug 23 '22 16:08 lotr

I have a similar issue with dimmer TS0601 by _TZE200_dfxkcots and one month before it was OK. I can't say that updates cause this problem. But now then i try to change brightness, lamp is always turn off. On entity brightness number is changed, lamp brightness stay the same. When I change brightness with dimmer knob, it changed and brightness number on entity changed. I remove and reinstall the device with no effect.

Eddio56 avatar Aug 28 '22 12:08 Eddio56

Can someone with this issue turn on full debug logs and attach an unedited/unfiltered log of the interaction?

dmulcahey avatar Aug 30 '22 11:08 dmulcahey


2022-08-30 17:48:20.802 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 1.25

2022-08-30 17:48:20.802 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned on: {'on_off': Default_Response(command_id=1, status=<Status.SUCCESS: 0>)}

2022-08-30 17:48:20.858 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.true while transitioning - skipping update

2022-08-30 17:48:22.054 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state

2022-08-30 17:49:02.895 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: setting transitioning flag to True

2022-08-30 17:49:02.914 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 0.25

2022-08-30 17:49:02.915 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}

2022-08-30 17:49:03.001 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.false while transitioning - skipping update

2022-08-30 17:49:03.079 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received level 27 while transitioning - skipping update

2022-08-30 17:49:03.166 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state

2022-08-30 17:49:25.712 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: setting transitioning flag to True

2022-08-30 17:49:25.722 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 1.25

2022-08-30 17:49:25.722 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned off: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)

2022-08-30 17:49:25.778 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.false while transitioning - skipping update

2022-08-30 17:49:26.973 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state```


turned on, waited 4 a minute or something, changed the level, waited 4 another 20 seconds and turned off (the physical light was already off)

lotr avatar Aug 30 '22 15:08 lotr

Where are the logs from the radio lib?

dmulcahey avatar Aug 30 '22 15:08 dmulcahey

@TheJulianJES 2022-08-30 17:49:25.778 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.false while transitioning - skipping update we may need to tweak how this works…

dmulcahey avatar Aug 30 '22 15:08 dmulcahey

2022-08-30 17:48:20.590 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: initializing channel: from_cache: False
2022-08-30 17:48:20.591 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: initializing cached channel attributes: ['start_up_on_off']
2022-08-30 17:48:20.591 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: Reading attributes in chunks: ['start_up_on_off']
2022-08-30 17:48:20.591 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2022-08-30 17:48:20.592 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: Reading attributes in chunks: ['on_off']
2022-08-30 17:48:20.593 DEBUG (MainThread) [zigpy.zcl] [0x01C0: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=100, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:48:20.594 DEBUG (MainThread) [zigpy.zcl] [0x01C0:1:0x0006] Sending request: Read_Attributes(attribute_ids=[0])
2022-08-30 17:48:20.595 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 100 under 101 request id, data: b'0064000000'
2022-08-30 17:48:20.595 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 101, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x01C0 endpoint=1>, 260, 6, 1, b'\x00d\x00\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:48:20.596 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12c3001b001400650002c001010401060001050000640000000200
2022-08-30 17:48:20.598 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12c300090002002265
2022-08-30 17:48:20.599 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 101]
2022-08-30 17:48:20.794 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: setting transitioning flag to True
2022-08-30 17:48:20.794 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0006] Setting the NO manufacturer id in command: 1
2022-08-30 17:48:20.794 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0006] Sending Tuya Cluster Command... Cluster Command is 1, Arguments are ()
2022-08-30 17:48:20.795 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=1, expect_reply=True, manufacturer=-1)
2022-08-30 17:48:20.795 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] get_dp_mapping --> found DP: 1
2022-08-30 17:48:20.795 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-08-30 17:48:20.796 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] ztype: Bool.true
2022-08-30 17:48:20.796 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_value: [1, 1]
2022-08-30 17:48:20.797 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] raw: b'\x01'
2022-08-30 17:48:20.797 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=102, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>))
2022-08-30 17:48:20.798 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=103, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:48:20.800 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=102, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-08-30 17:48:20.801 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB00B:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: Default_Response(command_id=1, status=<Status.SUCCESS: 0>)
2022-08-30 17:48:20.802 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 1.25
2022-08-30 17:48:20.802 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned on: {'on_off': Default_Response(command_id=1, status=<Status.SUCCESS: 0>)}
2022-08-30 17:48:20.804 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 103 under 104 request id, data: b'01670000660101000101'
2022-08-30 17:48:20.804 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (25, 104, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x01g\x00\x00f\x01\x01\x00\x01\x01', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:48:20.805 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12c400200019006800020bb001040100ef010a00016700006601010001010200
2022-08-30 17:48:20.816 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12c400090002002268
2022-08-30 17:48:20.817 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 104]
2022-08-30 17:48:20.818 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ec5000700a600
2022-08-30 17:48:20.819 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:48:20.820 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:48:20.820 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04c50007000000
2022-08-30 17:48:20.823 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04c50013000c002268020bb001010000000000
2022-08-30 17:48:20.823 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 104: 00
2022-08-30 17:48:20.824 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x68 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:48:20.826 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ec6000700aa00
2022-08-30 17:48:20.826 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:48:20.827 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:48:20.827 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17c6000800010001
2022-08-30 17:48:20.830 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17c60023001c002202000001020bb001040100ef050018670b000000afffe46d1b00d4
2022-08-30 17:48:20.831 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\x18g\x0b\x00\x00', 0, 175, 255, 228, 109, 27, 0, -44]
2022-08-30 17:48:20.832 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\x18g\x0b\x00\x00'
2022-08-30 17:48:20.833 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=103, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:48:20.835 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-30 17:48:20.838 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'18670b0000'
2022-08-30 17:48:20.850 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ec7000700aa00
2022-08-30 17:48:20.850 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:48:20.851 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:48:20.851 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17c7000800010001
2022-08-30 17:48:20.854 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17c700280021002202000001020bb001040100ef0a000967020066010100010100afffe46d1b00d5
2022-08-30 17:48:20.854 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [33, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\tg\x02\x00f\x01\x01\x00\x01\x01', 0, 175, 255, 228, 109, 27, 0, -43]
2022-08-30 17:48:20.855 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\tg\x02\x00f\x01\x01\x00\x01\x01'
2022-08-30 17:48:20.856 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=103, command_id=2, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:48:20.857 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=102, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-08-30 17:48:20.857 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received command 0x02 (TSN 103): set_data_response(data=TuyaCommand(status=0, tsn=102, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x01', *payload=<Bool.true: 1>)))
2022-08-30 17:48:20.858 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.true while transitioning - skipping update
2022-08-30 17:48:20.859 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=103, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:48:20.860 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-30 17:48:20.861 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 103 under 105 request id, data: b'18670b0200'
2022-08-30 17:48:20.861 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'09670200660101000101'
2022-08-30 17:48:20.862 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 105, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x18g\x0b\x02\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:48:20.862 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12c8001b0014006900020bb001040100ef01050018670b02000200
2022-08-30 17:48:20.865 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12c800090002002269
2022-08-30 17:48:20.865 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 105]
2022-08-30 17:48:20.874 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ec9000700a600
2022-08-30 17:48:20.874 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:48:20.875 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:48:20.875 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04c90007000000
2022-08-30 17:48:20.877 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04c90013000c002269020bb001010000000000
2022-08-30 17:48:20.878 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 105: 00
2022-08-30 17:48:20.878 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x69 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:48:20.906 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: initializing channel: from_cache: False
2022-08-30 17:48:20.907 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: initializing cached channel attributes: ['start_up_on_off']
2022-08-30 17:48:20.907 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: Reading attributes in chunks: ['start_up_on_off']
2022-08-30 17:48:20.908 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2022-08-30 17:48:20.908 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: Reading attributes in chunks: ['on_off']
2022-08-30 17:48:20.909 DEBUG (MainThread) [zigpy.zcl] [0x8191: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=106, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:48:20.911 DEBUG (MainThread) [zigpy.zcl] [0x8191:1:0x0006] Sending request: Read_Attributes(attribute_ids=[0])
2022-08-30 17:48:20.912 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 106 under 107 request id, data: b'006a000000'
2022-08-30 17:48:20.912 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 107, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8191 endpoint=1>, 260, 6, 1, b'\x00j\x00\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:48:20.913 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12ca001b0014006b000291810104010600010500006a0000000200
2022-08-30 17:48:20.916 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12ca0009000200226b
2022-08-30 17:48:20.917 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 107]
2022-08-30 17:48:22.054 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state
2022-08-30 17:48:30.727 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecb000700a600
2022-08-30 17:48:30.727 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:48:30.728 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:48:30.729 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04cb0007000000
2022-08-30 17:48:30.732 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04cb0013000c00226502c0010101d000000000
2022-08-30 17:48:30.733 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 101: d0
2022-08-30 17:48:30.734 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x65 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x01c0 endpoint=1>, status: 0xd0
2022-08-30 17:48:30.734 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 101 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED
2022-08-30 17:48:30.734 DEBUG (MainThread) [zigpy.device] [0x01c0] Delivery error for seq # 0x64, on endpoint id 1 cluster 0x0006: message send failure
2022-08-30 17:48:30.735 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0x01c0:1:0x0006]: Message send failure
2022-08-30 17:48:30.735 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('[0x01c0:1:0x0006]: Message send failure'), DeliveryError('[0x01c0:1:0x0006]: Message send failure'), DeliveryError('[0x01c0:1:0x0006]: Message send failure'), DeliveryError('[0x01c0:1:0x0006]: Message send failure')]
2022-08-30 17:48:30.738 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0006]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.739 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0002]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.739 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0b04]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.739 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0702]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.739 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0003]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.739 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0000]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.740 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x01C0:1:0x0019]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.740 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01C0](lumi.switch.n1aeu1): power source: Mains
2022-08-30 17:48:30.740 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01C0](lumi.switch.n1aeu1): completed initialization
2022-08-30 17:48:30.987 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecc000700a600
2022-08-30 17:48:30.987 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:48:30.988 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:48:30.988 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04cc0007000000
2022-08-30 17:48:30.991 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04cc0013000c00226b0291810101d000000000
2022-08-30 17:48:30.992 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 107: d0
2022-08-30 17:48:30.993 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x6b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8191 endpoint=1>, status: 0xd0
2022-08-30 17:48:30.993 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 107 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED
2022-08-30 17:48:30.993 DEBUG (MainThread) [zigpy.device] [0x8191] Delivery error for seq # 0x6a, on endpoint id 1 cluster 0x0006: message send failure
2022-08-30 17:48:30.993 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0x8191:1:0x0006]: Message send failure
2022-08-30 17:48:30.993 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('[0x8191:1:0x0006]: Message send failure'), DeliveryError('[0x8191:1:0x0006]: Message send failure'), DeliveryError('[0x8191:1:0x0006]: Message send failure'), DeliveryError('[0x8191:1:0x0006]: Message send failure')]
2022-08-30 17:48:30.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0006]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0702]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0000]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0003]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0b04]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.998 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8191:1:0x0019]: 'async_initialize' stage succeeded
2022-08-30 17:48:30.998 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8191](TS011F): power source: Mains
2022-08-30 17:48:30.998 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8191](TS011F): completed initialization
2022-08-30 17:48:37.355 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecd000700aa00
2022-08-30 17:48:37.356 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:48:37.357 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:48:37.357 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17cd000800010001
2022-08-30 17:48:37.360 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17cd002d0026002202000001020bb001040100000f0008680a01002040e2ff2020e4ff200000affff06d1b00d5
2022-08-30 17:48:37.361 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [38, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 0, b'\x08h\n\x01\x00 @\xe2\xff \xe4\xff \x00', 0, 175, 255, 240, 109, 27, 0, -43]
2022-08-30 17:48:37.362 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Received ZCL frame: b'\x08h\n\x01\x00 @\xe2\xff \xe4\xff \x00'
2022-08-30 17:48:37.363 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] 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=104, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:48:37.365 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=64)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=32)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2022-08-30 17:48:37.367 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Received command 0x0A (TSN 104): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=64)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=32)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2022-08-30 17:48:37.368 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Attribute report received: app_version=64, 0xFFE2=32, 0xFFE4=0
2022-08-30 17:48:37.369 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=104, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:48:37.371 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-30 17:48:37.373 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 104 under 108 request id, data: b'18680b0a00'
2022-08-30 17:48:37.373 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08680a01002040e2ff2020e4ff2000'
2022-08-30 17:48:37.374 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 108, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 0, 1, b'\x18h\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:48:37.374 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12ce001b0014006c00020bb0010401000001050018680b0a000200
2022-08-30 17:48:37.377 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12ce0009000200226c
2022-08-30 17:48:37.377 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 108]
2022-08-30 17:48:37.386 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecf000700a600
2022-08-30 17:48:37.386 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:48:37.387 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:48:37.387 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04cf0007000000
2022-08-30 17:48:37.390 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04cf0013000c00226c020bb001010000000000
2022-08-30 17:48:37.390 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 108: 00
2022-08-30 17:48:37.391 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x6c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:48:44.991 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x873D](TS0203): Device seen - marking the device available and resetting counter
2022-08-30 17:48:44.992 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x873D](TS0203): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:48:47.892 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB8D7](TS0001): Device seen - marking the device available and resetting counter
2022-08-30 17:48:47.893 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB8D7](TS0001): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:48:49.055 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB00B](TS0601): Device seen - marking the device available and resetting counter
2022-08-30 17:48:49.056 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB00B](TS0601): Update device availability - device available: True - new availability: True - changed: False

Sry for not pasting all the logs, just briefly checking, if I'm not compromising any secrets... I'm not familiar with the full debug logs

lotr avatar Aug 30 '22 16:08 lotr

@dmulcahey if you need anything else, feel free to contact me even via my email, or just write here, I'll do my best to help with this :)

lotr avatar Aug 30 '22 16:08 lotr

2022-08-30 17:48:51.972 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xCF7E](TS0001): Device seen - marking the device available and resetting counter
2022-08-30 17:48:51.973 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xCF7E](TS0001): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:48:53.953 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x961A](TS0002): Device seen - marking the device available and resetting counter
2022-08-30 17:48:53.953 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x961A](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:48:55.014 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8C48](lumi.switch.l1aeu1): last_seen is 2921071.5650992393 seconds ago and ping attempts have been exhausted, marking the device unavailable
2022-08-30 17:48:55.015 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8C48](lumi.switch.l1aeu1): Update device availability - device available: False - new availability: False - changed: False
2022-08-30 17:48:55.043 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9E8F](TS0002): Device seen - marking the device available and resetting counter
2022-08-30 17:48:55.043 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9E8F](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:01.094 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed0000700aa00
2022-08-30 17:49:01.094 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:49:01.095 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:01.095 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17d0000800010001
2022-08-30 17:49:01.098 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17d0002d0026002202000001028f9e01040100000f0008e00a01002050e2ff2030e4ff200000af5ff36d1b00ae
2022-08-30 17:49:01.099 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [38, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9e8f>, 1, 260, 0, b'\x08\xe0\n\x01\x00 P\xe2\xff 0\xe4\xff \x00', 0, 175, 95, 243, 109, 27, 0, -82]
2022-08-30 17:49:01.099 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] Received ZCL frame: b'\x08\xe0\n\x01\x00 P\xe2\xff 0\xe4\xff \x00'
2022-08-30 17:49:01.101 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] 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-08-30 17:49:01.103 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=48)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2022-08-30 17:49:01.105 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] Received command 0x0A (TSN 224): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=80)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=48)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2022-08-30 17:49:01.105 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] Attribute report received: app_version=80, 0xFFE2=48, 0xFFE4=0
2022-08-30 17:49:01.107 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] 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-08-30 17:49:01.107 DEBUG (MainThread) [zigpy.zcl] [0x9E8F:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:01.108 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 224 under 109 request id, data: b'18e00b0a00'
2022-08-30 17:49:01.109 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9e8f>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08e00a01002050e2ff2030e4ff2000'
2022-08-30 17:49:01.109 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 109, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x9E8F endpoint=1>, 260, 0, 1, b'\x18\xe0\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:01.109 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d1001b0014006d00028f9e010401000001050018e00b0a000200
2022-08-30 17:49:01.112 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d10009000200226d
2022-08-30 17:49:01.113 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 109]
2022-08-30 17:49:01.121 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed2000700a600
2022-08-30 17:49:01.121 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:01.122 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:01.122 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d20007000000
2022-08-30 17:49:01.123 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d20013000c00226d028f9e01010000000000
2022-08-30 17:49:01.124 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 109: 00
2022-08-30 17:49:01.124 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x6d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9e8f endpoint=1>, status: 0x00
2022-08-30 17:49:01.914 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8365](TS0002): Device seen - marking the device available and resetting counter
2022-08-30 17:49:01.914 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8365](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:01.937 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF9D5](01MINIZB): Device seen - marking the device available and resetting counter
2022-08-30 17:49:01.937 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF9D5](01MINIZB): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:02.895 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: setting transitioning flag to True
2022-08-30 17:49:02.895 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0008] Setting the NO manufacturer id in command: 4
2022-08-30 17:49:02.896 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0008] Sending Tuya Cluster Command. Cluster Command is 4, Arguments are (28, 0)
2022-08-30 17:49:02.896 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=0, expect_reply=True, manufacturer=-1)
2022-08-30 17:49:02.896 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] get_dp_mapping --> found DP: 1
2022-08-30 17:49:02.897 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-08-30 17:49:02.897 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] ztype: Bool.false
2022-08-30 17:49:02.897 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_value: [1, 0]
2022-08-30 17:49:02.898 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] raw: b'\x00'
2022-08-30 17:49:02.898 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=110, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))
2022-08-30 17:49:02.899 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=111, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:49:02.904 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=110, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-08-30 17:49:02.906 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='current_level', attr_value=28, expect_reply=True, manufacturer=-1)
2022-08-30 17:49:02.906 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] get_dp_mapping --> found DP: 2
2022-08-30 17:49:02.906 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_cluster_data: 2, DPToAttributeMapping(ep_attribute='level', attribute_name='current_level', dp_type=<TuyaDPType.VALUE: 2>, converter=<function TuyaLevelControlManufCluster.<lambda> at 0xffff7d9c9360>, dp_converter=<function TuyaLevelControlManufCluster.<lambda> at 0xffff7d9c93f0>, endpoint_id=None)
2022-08-30 17:49:02.907 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] converted: 109
2022-08-30 17:49:02.907 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] ztype: 109
2022-08-30 17:49:02.907 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_value: [4, 0, 0, 0, 109]
2022-08-30 17:49:02.908 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] raw: b'\x00\x00\x00m'
2022-08-30 17:49:02.908 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=112, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00m', *payload=1828716544))
2022-08-30 17:49:02.912 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=113, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:49:02.913 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=112, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x00m', *payload=1828716544)))
2022-08-30 17:49:02.914 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB00B:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(28, 0)' kwargs: '{}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:02.914 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 0.25
2022-08-30 17:49:02.915 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-08-30 17:49:02.916 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 111 under 114 request id, data: b'016f00006e0101000100'
2022-08-30 17:49:02.916 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (25, 114, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x01o\x00\x00n\x01\x01\x00\x01\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:02.917 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d300200019007200020bb001040100ef010a00016f00006e01010001000200
2022-08-30 17:49:02.920 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 113 under 115 request id, data: b'0171000070020200040000006d'
2022-08-30 17:49:02.924 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d300090002002272
2022-08-30 17:49:02.925 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 114]
2022-08-30 17:49:02.927 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (28, 115, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x01q\x00\x00p\x02\x02\x00\x04\x00\x00\x00m', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:02.927 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d40023001c007300020bb001040100ef010d000171000070020200040000006d0200
2022-08-30 17:49:02.932 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d400090002002273
2022-08-30 17:49:02.933 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 115]
2022-08-30 17:49:02.973 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed5000700ae00
2022-08-30 17:49:02.973 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
2022-08-30 17:49:02.974 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:02.974 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17d5000800010001
2022-08-30 17:49:02.977 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed6000700ae00
2022-08-30 17:49:02.978 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
2022-08-30 17:49:02.979 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17d50023001c002e02000001020bb001040100ef0500186f0b000000affff36d1b00d5
2022-08-30 17:49:02.979 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 46>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\x18o\x0b\x00\x00', 0, 175, 255, 243, 109, 27, 0, -43]
2022-08-30 17:49:02.981 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\x18o\x0b\x00\x00'
2022-08-30 17:49:02.982 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=111, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:02.984 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:02.985 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'186f0b0000'
2022-08-30 17:49:02.985 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:02.986 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d60007000000
2022-08-30 17:49:02.989 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d60013000c002e72020bb00101e900000000
2022-08-30 17:49:02.990 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 114: e9
2022-08-30 17:49:02.991 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x72 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0xe9
2022-08-30 17:49:02.992 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 114 req id frame: TXStatus.MAC_NO_ACK
2022-08-30 17:49:02.992 DEBUG (MainThread) [zigpy.device] [0xb00b] Delivery error for seq # 0x6f, on endpoint id 1 cluster 0xef00: message send failure
2022-08-30 17:49:02.992 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:02.992 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17d7000800010001
2022-08-30 17:49:02.995 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17d700280021002e02000001020bb001040100ef0a00096902006e010100010000afffe46d1b00d5
2022-08-30 17:49:02.996 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [33, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 46>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\ti\x02\x00n\x01\x01\x00\x01\x00', 0, 175, 255, 228, 109, 27, 0, -43]
2022-08-30 17:49:02.996 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\ti\x02\x00n\x01\x01\x00\x01\x00'
2022-08-30 17:49:02.997 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=105, command_id=2, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:02.999 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=110, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-08-30 17:49:03.000 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received command 0x02 (TSN 105): set_data_response(data=TuyaCommand(status=0, tsn=110, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-08-30 17:49:03.001 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.false while transitioning - skipping update
2022-08-30 17:49:03.003 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=105, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:03.004 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:03.009 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 105 under 116 request id, data: b'18690b0200'
2022-08-30 17:49:03.010 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'096902006e0101000100'
2022-08-30 17:49:03.010 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:03.011 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17d8000800010001
2022-08-30 17:49:03.013 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17d80023001c002602000001020bb001040100ef050018710b000000affff36d1b00d5
2022-08-30 17:49:03.013 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\x18q\x0b\x00\x00', 0, 175, 255, 243, 109, 27, 0, -43]
2022-08-30 17:49:03.014 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\x18q\x0b\x00\x00'
2022-08-30 17:49:03.015 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=113, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:03.016 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:03.017 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'18710b0000'
2022-08-30 17:49:03.017 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:03.017 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d90007000000
2022-08-30 17:49:03.025 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d90013000c002273020bb001010000000000
2022-08-30 17:49:03.026 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 115: 00
2022-08-30 17:49:03.027 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x73 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:49:03.029 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:03.029 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17da000800010001
2022-08-30 17:49:03.032 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17da050800010022
2022-08-30 17:49:03.033 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:03.033 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04db0007000000
2022-08-30 17:49:03.035 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04db050800010022
2022-08-30 17:49:03.036 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 116, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x18i\x0b\x02\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:03.036 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12dc001b0014007400020bb001040100ef01050018690b02000200
2022-08-30 17:49:03.040 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12dc00090002002274
2022-08-30 17:49:03.040 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 116]
2022-08-30 17:49:03.047 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0edd000700a600
2022-08-30 17:49:03.047 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:03.048 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:03.049 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04dd0007000000
2022-08-30 17:49:03.053 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04dd0013000c002274020bb001010000000000
2022-08-30 17:49:03.053 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 116: 00
2022-08-30 17:49:03.053 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x74 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:49:03.068 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ede000700aa00
2022-08-30 17:49:03.068 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:49:03.069 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:03.069 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17de000800010001
2022-08-30 17:49:03.072 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17de002b0024002202000001020bb001040100ef0d00096a020070020200040000006d00affff36d1b00d5
2022-08-30 17:49:03.073 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\tj\x02\x00p\x02\x02\x00\x04\x00\x00\x00m', 0, 175, 255, 243, 109, 27, 0, -43]
2022-08-30 17:49:03.074 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\tj\x02\x00p\x02\x02\x00\x04\x00\x00\x00m'
2022-08-30 17:49:03.075 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=106, command_id=2, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:03.076 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=112, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'm\x00\x00\x00', *payload=109)))
2022-08-30 17:49:03.077 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received command 0x02 (TSN 106): set_data_response(data=TuyaCommand(status=0, tsn=112, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'm\x00\x00\x00', *payload=109)))
2022-08-30 17:49:03.079 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB00B:1:0x0008]: received attribute: 0 update with value: 27
2022-08-30 17:49:03.079 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received level 27 while transitioning - skipping update
2022-08-30 17:49:03.080 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=106, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:03.081 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:03.082 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 106 under 117 request id, data: b'186a0b0200'
2022-08-30 17:49:03.083 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'096a020070020200040000006d'
2022-08-30 17:49:03.084 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 117, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x18j\x0b\x02\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:03.084 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12df001b0014007500020bb001040100ef010500186a0b02000200
2022-08-30 17:49:03.089 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12df00090002002275
2022-08-30 17:49:03.090 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 117]
2022-08-30 17:49:03.094 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee0000700a600
2022-08-30 17:49:03.094 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:03.095 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:03.095 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e00007000000
2022-08-30 17:49:03.098 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e00013000c002275020bb001010000000000
2022-08-30 17:49:03.098 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 117: 00
2022-08-30 17:49:03.098 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x75 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:49:03.166 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state
2022-08-30 17:49:04.984 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x689F](lumi.sensor_magnet.aq2): Device seen - marking the device available and resetting counter
2022-08-30 17:49:04.985 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x689F](lumi.sensor_magnet.aq2): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:05.997 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1EF8](TS0203): Device seen - marking the device available and resetting counter
2022-08-30 17:49:05.998 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1EF8](TS0203): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:07.025 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01C0](lumi.switch.n1aeu1): last_seen is 2758274.6389126778 seconds ago and ping attempts have been exhausted, marking the device unavailable
2022-08-30 17:49:07.026 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01C0](lumi.switch.n1aeu1): Update device availability - device available: False - new availability: False - changed: False
2022-08-30 17:49:07.904 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4B07](TS0002): Device seen - marking the device available and resetting counter
2022-08-30 17:49:07.904 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4B07](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:10.003 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4707](lumi.remote.b28ac1): last_seen is 3024464.710575342 seconds ago and ping attempts have been exhausted, marking the device unavailable
2022-08-30 17:49:10.004 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4707](lumi.remote.b28ac1): Update device availability - device available: False - new availability: False - changed: False
2022-08-30 17:49:10.405 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee1000700aa00
2022-08-30 17:49:10.406 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:49:10.407 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:10.407 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17e1000800010001
2022-08-30 17:49:10.413 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17e10025001e00220200000102af8e0104010101070008790a0000300100affff96d1b00c6
2022-08-30 17:49:10.413 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8eaf>, 1, 260, 257, b'\x08y\n\x00\x000\x01', 0, 175, 255, 249, 109, 27, 0, -58]
2022-08-30 17:49:10.414 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] Received ZCL frame: b'\x08y\n\x00\x000\x01'
2022-08-30 17:49:10.415 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] 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=121, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:10.416 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] Decoded ZCL frame: DoorLock:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=enum8, value=<enum8.undefined_0x01: 1>))])
2022-08-30 17:49:10.417 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] Received command 0x0A (TSN 121): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=enum8, value=<enum8.undefined_0x01: 1>))])
2022-08-30 17:49:10.417 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] Attribute report received: lock_state=<enum8.undefined_0x01: 1>
2022-08-30 17:49:10.418 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x8EAF:1:0x0101]: Attribute report 'Door Lock'[lock_state] = LockState.Locked
2022-08-30 17:49:10.419 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] 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=121, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:10.420 DEBUG (MainThread) [zigpy.zcl] [0x8EAF:1:0x0101] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:10.420 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 121 under 118 request id, data: b'18790b0a00'
2022-08-30 17:49:10.421 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8eaf>, ep: 1, profile: 0x0104, cluster_id: 0x0101, data: b'08790a00003001'
2022-08-30 17:49:10.421 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 118, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8EAF endpoint=1>, 260, 257, 1, b'\x18y\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:10.421 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e2001b001400760002af8e010401010101050018790b0a000200
2022-08-30 17:49:10.428 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e200090002002276
2022-08-30 17:49:10.428 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 118]
2022-08-30 17:49:10.434 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee3000700a600
2022-08-30 17:49:10.434 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:10.435 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:10.435 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e30007000000
2022-08-30 17:49:10.438 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e30013000c00227602af8e01010000000000
2022-08-30 17:49:10.439 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 118: 00
2022-08-30 17:49:10.439 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x76 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8eaf endpoint=1>, status: 0x00
2022-08-30 17:49:10.962 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE13A](TS0002): Device seen - marking the device available and resetting counter
2022-08-30 17:49:10.963 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE13A](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:10.978 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8EAF](V3-BTZBE): Device seen - marking the device available and resetting counter
2022-08-30 17:49:10.978 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8EAF](V3-BTZBE): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:11.925 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB0A7](TS0003): Device seen - marking the device available and resetting counter
2022-08-30 17:49:11.925 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB0A7](TS0003): Update device availability - device available: True - new availability: True - changed: False
2022-08-30 17:49:12.038 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8191](TS011F): Attempting to checkin with device - missed checkins: 1
2022-08-30 17:49:12.039 DEBUG (MainThread) [zigpy.zcl] [0x8191:1:0x0000] 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=119, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:49:12.040 DEBUG (MainThread) [zigpy.zcl] [0x8191:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4])
2022-08-30 17:49:12.041 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 119 under 120 request id, data: b'0077000400'
2022-08-30 17:49:12.041 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 120, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8191 endpoint=1>, 260, 0, 1, b'\x00w\x00\x04\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:12.041 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e4001b0014007800029181010401000001050000770004000200
2022-08-30 17:49:12.056 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e400090002002278
2022-08-30 17:49:12.057 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 120]
2022-08-30 17:49:21.052 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee5000700aa00
2022-08-30 17:49:21.053 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:49:21.055 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:21.055 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17e5000800010001
2022-08-30 17:49:21.058 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17e50029002200220200000102658301040100000b0018750a01002042e2ff201b00afffff6d1b00cb
2022-08-30 17:49:21.059 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [34, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8365>, 1, 260, 0, b'\x18u\n\x01\x00 B\xe2\xff \x1b', 0, 175, 255, 255, 109, 27, 0, -53]
2022-08-30 17:49:21.060 DEBUG (MainThread) [zigpy.zcl] [0x8365:1:0x0000] Received ZCL frame: b'\x18u\n\x01\x00 B\xe2\xff \x1b'
2022-08-30 17:49:21.062 DEBUG (MainThread) [zigpy.zcl] [0x8365:1:0x0000] 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=117, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:21.068 DEBUG (MainThread) [zigpy.zcl] [0x8365:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=66)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=27))])
2022-08-30 17:49:21.069 DEBUG (MainThread) [zigpy.zcl] [0x8365:1:0x0000] Received command 0x0A (TSN 117): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=66)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=27))])
2022-08-30 17:49:21.070 DEBUG (MainThread) [zigpy.zcl] [0x8365:1:0x0000] Attribute report received: app_version=66, 0xFFE2=27
2022-08-30 17:49:21.070 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x8365>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'18750a01002042e2ff201b'
2022-08-30 17:49:22.167 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee6000700a600
2022-08-30 17:49:22.168 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:22.169 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:22.169 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e60007000000
2022-08-30 17:49:22.173 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e60013000c0022780291810101d000000000
2022-08-30 17:49:22.173 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 120: d0
2022-08-30 17:49:22.174 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x78 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x8191 endpoint=1>, status: 0xd0
2022-08-30 17:49:22.175 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 120 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED
2022-08-30 17:49:22.175 DEBUG (MainThread) [zigpy.device] [0x8191] Delivery error for seq # 0x77, on endpoint id 1 cluster 0x0000: message send failure
2022-08-30 17:49:25.712 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: setting transitioning flag to True
2022-08-30 17:49:25.713 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0006] Setting the NO manufacturer id in command: 0
2022-08-30 17:49:25.713 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0006] Sending Tuya Cluster Command... Cluster Command is 0, Arguments are ()
2022-08-30 17:49:25.714 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='on_off', attr_value=0, expect_reply=True, manufacturer=-1)
2022-08-30 17:49:25.714 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] get_dp_mapping --> found DP: 1
2022-08-30 17:49:25.714 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_cluster_data: 1, DPToAttributeMapping(ep_attribute='on_off', attribute_name='on_off', dp_type=<TuyaDPType.BOOL: 1>, converter=None, dp_converter=None, endpoint_id=None)
2022-08-30 17:49:25.715 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] ztype: Bool.false
2022-08-30 17:49:25.715 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_value: [1, 0]
2022-08-30 17:49:25.715 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] raw: b'\x00'
2022-08-30 17:49:25.715 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=121, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>))
2022-08-30 17:49:25.716 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=122, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-08-30 17:49:25.717 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=121, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-08-30 17:49:25.721 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB00B:1:0x0006]: executed 'off' command with args: '()' kwargs: '{}' result: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:25.722 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 1.25
2022-08-30 17:49:25.722 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned off: Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:25.724 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 122 under 123 request id, data: b'017a0000790101000100'
2022-08-30 17:49:25.724 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (25, 123, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x01z\x00\x00y\x01\x01\x00\x01\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:25.724 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e700200019007b00020bb001040100ef010a00017a00007901010001000200
2022-08-30 17:49:25.733 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e70009000200227b
2022-08-30 17:49:25.734 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 123]
2022-08-30 17:49:25.744 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee8000700a600
2022-08-30 17:49:25.744 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:25.745 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:25.745 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e80007000000
2022-08-30 17:49:25.747 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e80013000c00227b020bb001010000000000
2022-08-30 17:49:25.747 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 123: 00
2022-08-30 17:49:25.748 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x7b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:49:25.752 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee9000700aa00
2022-08-30 17:49:25.752 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:49:25.753 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:25.753 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17e9000800010001
2022-08-30 17:49:25.756 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17e90023001c002202000001020bb001040100ef0500187a0b000000afff006e1b00d5
2022-08-30 17:49:25.757 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\x18z\x0b\x00\x00', 0, 175, 255, 0, 110, 27, 0, -43]
2022-08-30 17:49:25.758 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\x18z\x0b\x00\x00'
2022-08-30 17:49:25.758 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=122, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:25.759 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:25.761 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'187a0b0000'
2022-08-30 17:49:25.767 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0eea000700aa00
2022-08-30 17:49:25.767 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-08-30 17:49:25.768 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-08-30 17:49:25.768 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17ea000800010001
2022-08-30 17:49:25.771 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17ea00280021002202000001020bb001040100ef0a00096b020079010100010000afff006e1b00d5
2022-08-30 17:49:25.772 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [33, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\tk\x02\x00y\x01\x01\x00\x01\x00', 0, 175, 255, 0, 110, 27, 0, -43]
2022-08-30 17:49:25.772 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\tk\x02\x00y\x01\x01\x00\x01\x00'
2022-08-30 17:49:25.773 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=107, command_id=2, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:25.775 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=121, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-08-30 17:49:25.777 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received command 0x02 (TSN 107): set_data_response(data=TuyaCommand(status=0, tsn=121, dp=1, data=TuyaData(dp_type=<TuyaDPType.BOOL: 1>, function=0, raw=b'\x00', *payload=<Bool.false: 0>)))
2022-08-30 17:49:25.778 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received onoff Bool.false while transitioning - skipping update
2022-08-30 17:49:25.779 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=107, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-08-30 17:49:25.780 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-08-30 17:49:25.781 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 107 under 124 request id, data: b'186b0b0200'
2022-08-30 17:49:25.781 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'096b0200790101000100'
2022-08-30 17:49:25.782 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 124, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x18k\x0b\x02\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-08-30 17:49:25.782 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12eb001b0014007c00020bb001040100ef010500186b0b02000200
2022-08-30 17:49:25.786 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12eb0009000200227c
2022-08-30 17:49:25.787 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 124]
2022-08-30 17:49:25.794 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0eec000700a600
2022-08-30 17:49:25.794 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-08-30 17:49:25.795 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-08-30 17:49:25.795 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04ec0007000000
2022-08-30 17:49:25.797 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04ec0013000c00227c020bb001010000000000
2022-08-30 17:49:25.797 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 124: 00
2022-08-30 17:49:25.798 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x7c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-08-30 17:49:26.973 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state

@dmulcahey rest of the logs 4 the timewindow, since somehow I didn't paste it all

lotr avatar Aug 30 '22 16:08 lotr

Hello everyone. I recently updated from HA 2022.04 to .08 and had the same issue with my dimmers when setting the level. I could fix it by reverting one change from pull request #1489. The pull request changed zhaquirks/tuya/mcu/init.py and introduced a supposed handler (lines 400-413) for the move_to_level_with_on_off command. This handler expects a boolean parameter at args[1] containing whether the light should be on or off. I believe this is a misinterpretation of the actual function of the move_to_level_with_on_off command. According to the zcl specs, as I understand them, the command takes the same arguments as the non "with_on_off" command. It should then check whether the level is below the "min_level" of the dimmer and set on_off accordingly. It will never receive a boolean parameter if called according to the specs. Since I'm fairly new to HA/zigpy and don't know the process to submit a pull request, I was wondering if someone could submit the revert for those lines. Thanks.

gonzalezjj avatar Sep 03 '22 16:09 gonzalezjj

move_to_level_with_on_off

Is this the section you mean? I'm not sure how to change it as this issue is driving me crazy and no-one is fixing it...

       # (move_to_level_with_on_off --> send the on_off command first)
        if command_id == 0x0004:
            cluster_data = TuyaClusterData(
                endpoint_id=self.endpoint.endpoint_id,
                cluster_attr="on_off",
                attr_value=args[1],
                expect_reply=expect_reply,
                manufacturer=manufacturer,
            )
            self.endpoint.device.command_bus.listener_event(
                TUYA_MCU_COMMAND,
                cluster_data,
            )

shanelord01 avatar Sep 12 '22 09:09 shanelord01

Is this the section you mean? I'm not sure how to change it as this issue is driving me crazy and no-one is fixing it...

       # (move_to_level_with_on_off --> send the on_off command first)
        if command_id == 0x0004:
            cluster_data = TuyaClusterData(
                endpoint_id=self.endpoint.endpoint_id,
                cluster_attr="on_off",
                attr_value=args[1],
                expect_reply=expect_reply,
                manufacturer=manufacturer,
            )
            self.endpoint.device.command_bus.listener_event(
                TUYA_MCU_COMMAND,
                cluster_data,
            )

Yes, that's the section. After posting my reply, I realized that HA actually expects the on_off command to be sent and behaves weirdly if not. It assumes the light turns on and off based on the level, and does not send explicit on_off commands when using scenes, for example. So I ended up leaving the code in and changing a single line: from:

            attr_value=args[1],

to:

            attr_value=args[0] > 0,

Basically, this fully implements the move_to_level_with_on_off assuming the device's min_level is 0. From my understanding, for a correct implementation, the min_level would need to be fetched from the device, but this is probably good enough for most use cases and fixes the current issue.

gonzalezjj avatar Sep 12 '22 09:09 gonzalezjj

Ummm, I believe that this is a misunderstanding of the command ARGS meaning. It seems that as @gonzalezjj points, the on_off command is detected from the brightness value.

args[1] it's the transition time and nothing to do with the on_off value

I will check it later at home, but I believe that the proposed code can do the job.

javicalle avatar Sep 12 '22 10:09 javicalle

And here is the PR #1748

It will restore the 'normal' behavior, but... The quirk that was intended to invoke the on/off command on non-compliant devices (most Tuya devices, I'd bet) should be implemented in HA by adding the device manufacturer value to the list: https://github.com/home-assistant/core/blob/dev/homeassistant/components/zha/light.py#L889-L897

javicalle avatar Sep 12 '22 19:09 javicalle

In case someone wants to test the new version before next release, you can enable the local quirks and copy the file from here:

  • https://github.com/zigpy/zha-device-handlers/blob/dev/zhaquirks/tuya/mcu/init.py

javicalle avatar Sep 12 '22 20:09 javicalle

just a tiny bug remains - might be unrelated to this one and moved to a separate one - then the dimmer is turned off and I move the knob, the GUI shows, that the light turned on, but the physical light stays off

lotr avatar Sep 13 '22 05:09 lotr

just a tiny bug remains - might be unrelated to this one and moved to a separate one - then the dimmer is turned off and I move the knob, the GUI shows, that the light turned on, but the physical light stays off

Just to be sure, when you says "I move the knob" are you refering to the knob of the physical device (and not from the GUI) right? So the device has some separate mechanism to turn it on, maybe by pressing the knob?

Can you attach the HA logs from 'moving the knob' from the physical device?

javicalle avatar Sep 13 '22 06:09 javicalle

I'm pretty sure @lotr is describing the same behavior that I described as "HA behaves weirdly" (which, admittedly, is not very descriptive...). HA expects the move_to_level_with_on_off to be correctly implemented, so when the light is off and you increase the level using the slider in HA, HA automatically reports the light as on, since it has sent the move_to_level_with_on_off command. With the new code change, the light does not turn on, so there is a discrepancy between the actual state and what HA reports.

gonzalezjj avatar Sep 13 '22 06:09 gonzalezjj

@javicalle I mean the GUI knob, I'll attach the logs later today :)

lotr avatar Sep 13 '22 06:09 lotr

In case someone wants to test the new version before next release, you can enable the local quirks and copy the file from here:

  • https://github.com/zigpy/zha-device-handlers/blob/dev/zhaquirks/tuya/mcu/init.py

How do I do that? Do I just add that file to the /config/zha_quirks folder and ensure this is in HA configuration.yaml?

zha:
  enable_quirks: true
  custom_quirks_path: /config/zha_quirks

that doesn't seem to work...

shanelord01 avatar Sep 13 '22 07:09 shanelord01

@shanelord01 what I've done, I've checked the pull request which contained only a single file, so I changed the file in the python venv lib folder

lotr avatar Sep 13 '22 07:09 lotr

@shanelord01 what I've done, I've checked the pull request which contained only a single file, so I changed the file in the python venv lib folder

How do I access that?

shanelord01 avatar Sep 13 '22 07:09 shanelord01

@shanelord01 depends on your installation - you have to find the exact file in the HA installation folder, try to grep it for example :)

lotr avatar Sep 13 '22 07:09 lotr

@shanelord01 depends on your installation - you have to find the exact file in the HA installation folder, try to grep it for example :)

So I guess I'm screwed as I'm using Home Assistant OS... :(

shanelord01 avatar Sep 13 '22 07:09 shanelord01

@javicalle Here's the log. The physical device was off, the device in the GUI was off. I've moved the GUI knob from the most left position (min position) to some value. The physical device did not turn on, but the GUI was showing the device as it was turned on.

2022-09-13 16:58:01.414 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: setting transitioning flag to True
2022-09-13 16:58:01.415 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0008] Setting the NO manufacturer id in command: 4
2022-09-13 16:58:01.415 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0008] Sending Tuya Cluster Command. Cluster Command is 4, Arguments are (150, 0)
2022-09-13 16:58:01.416 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_mcu_command: cluster_data=TuyaClusterData(endpoint_id=1, cluster_attr='current_level', attr_value=150, expect_reply=True, manufacturer=-1)
2022-09-13 16:58:01.417 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] get_dp_mapping --> found DP: 2
2022-09-13 16:58:01.417 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_cluster_data: 2, DPToAttributeMapping(ep_attribute='level', attribute_name='current_level', dp_type=<TuyaDPType.VALUE: 2>, converter=<function TuyaLevelControlManufCluster.<lambda> at 0xffff48732440>, dp_converter=<function TuyaLevelControlManufCluster.<lambda> at 0xffff487324d0>, endpoint_id=None)
2022-09-13 16:58:01.418 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] converted: 588
2022-09-13 16:58:01.418 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] ztype: 588
2022-09-13 16:58:01.418 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] from_value: [4, 0, 0, 2, 76]
2022-09-13 16:58:01.419 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] raw: b'\x00\x00\x02L'
2022-09-13 16:58:01.419 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] tuya_command: TuyaCommand(status=0, tsn=107, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02L', *payload=1275199488))
2022-09-13 16:58:01.424 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=108, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-09-13 16:58:01.425 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending request: set_data(data=TuyaCommand(status=0, tsn=107, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'\x00\x00\x02L', *payload=1275199488)))
2022-09-13 16:58:01.426 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB00B:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(150, 0)' kwargs: '{}' result: Default_Response(command_id=4, status=<Status.SUCCESS: 0>)
2022-09-13 16:58:01.426 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: starting transitioning timer for 0.25
2022-09-13 16:58:01.427 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: turned on: {'move_to_level_with_on_off': Default_Response(command_id=4, status=<Status.SUCCESS: 0>)}
2022-09-13 16:58:01.428 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 108 under 109 request id, data: b'016c00006b020200040000024c'
2022-09-13 16:58:01.428 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (28, 109, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x01l\x00\x00k\x02\x02\x00\x04\x00\x00\x02L', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-09-13 16:58:01.429 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12c80023001c006d00020bb001040100ef010d00016c00006b020200040000024c0200
2022-09-13 16:58:01.439 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12c80009000200226d
2022-09-13 16:58:01.439 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 109]
2022-09-13 16:58:01.448 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ec9000700a600
2022-09-13 16:58:01.449 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-09-13 16:58:01.449 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-09-13 16:58:01.449 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04c90007000000
2022-09-13 16:58:01.453 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04c90013000c00226d020bb001010000000000
2022-09-13 16:58:01.453 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 109: 00
2022-09-13 16:58:01.454 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x6d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-09-13 16:58:01.457 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0eca000700aa00
2022-09-13 16:58:01.458 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-09-13 16:58:01.459 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-09-13 16:58:01.459 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17ca000800010001
2022-09-13 16:58:01.462 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17ca0023001c002202000001020bb001040100ef0500186c0b000000afffbc711d00d5
2022-09-13 16:58:01.463 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\x18l\x0b\x00\x00', 0, 175, 255, 188, 113, 29, 0, -43]
2022-09-13 16:58:01.464 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\x18l\x0b\x00\x00'
2022-09-13 16:58:01.465 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] 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=108, command_id=11, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-09-13 16:58:01.466 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:Default_Response(command_id=0, status=<Status.SUCCESS: 0>)
2022-09-13 16:58:01.467 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'186c0b0000'
2022-09-13 16:58:01.475 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecb000700aa00
2022-09-13 16:58:01.476 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-09-13 16:58:01.477 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-09-13 16:58:01.477 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17cb000800010001
2022-09-13 16:58:01.479 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17cb002b0024002202000001020bb001040100ef0d00094302006b020200040000024c00afffbc711d00d5
2022-09-13 16:58:01.479 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [36, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 61184, b'\tC\x02\x00k\x02\x02\x00\x04\x00\x00\x02L', 0, 175, 255, 188, 113, 29, 0, -43]
2022-09-13 16:58:01.480 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received ZCL frame: b'\tC\x02\x00k\x02\x02\x00\x04\x00\x00\x02L'
2022-09-13 16:58:01.481 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False, *is_reply=True), tsn=67, command_id=2, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-09-13 16:58:01.482 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Decoded ZCL frame: TuyaLevelControlManufCluster:set_data_response(data=TuyaCommand(status=0, tsn=107, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'L\x02\x00\x00', *payload=588)))
2022-09-13 16:58:01.483 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Received command 0x02 (TSN 67): set_data_response(data=TuyaCommand(status=0, tsn=107, dp=2, data=TuyaData(dp_type=<TuyaDPType.VALUE: 2>, function=0, raw=b'L\x02\x00\x00', *payload=588)))
2022-09-13 16:58:01.484 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xB00B:1:0x0008]: received attribute: 0 update with value: 149
2022-09-13 16:58:01.484 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: received level 149 while transitioning - skipping update
2022-09-13 16:58:01.485 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=67, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-09-13 16:58:01.487 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0xef00] Sending reply: Default_Response(command_id=2, status=<Status.SUCCESS: 0>)
2022-09-13 16:58:01.488 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 67 under 110 request id, data: b'18430b0200'
2022-09-13 16:58:01.489 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0xef00, data: b'094302006b020200040000024c'
2022-09-13 16:58:01.489 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 110, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 61184, 1, b'\x18C\x0b\x02\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-09-13 16:58:01.490 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12cc001b0014006e00020bb001040100ef01050018430b02000200
2022-09-13 16:58:01.493 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12cc0009000200226e
2022-09-13 16:58:01.493 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 110]
2022-09-13 16:58:01.502 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecd000700a600
2022-09-13 16:58:01.502 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-09-13 16:58:01.503 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-09-13 16:58:01.503 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04cd0007000000
2022-09-13 16:58:01.507 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04cd0013000c00226e020bb001010000000000
2022-09-13 16:58:01.507 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 110: 00
2022-09-13 16:58:01.508 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x6e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-09-13 16:58:01.678 DEBUG (MainThread) [homeassistant.components.zha.entity] light.livingroom_switch_light: transition complete - future attribute reports will write HA state
2022-09-13 16:58:07.839 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ece000700aa00
2022-09-13 16:58:07.840 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-09-13 16:58:07.841 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-09-13 16:58:07.841 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17ce000800010001
2022-09-13 16:58:07.845 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17ce0023001c002202000001027ecf0104010a000500007200070000afffbc711d00c6
2022-09-13 16:58:07.846 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xcf7e>, 1, 260, 10, b'\x00r\x00\x07\x00', 0, 175, 255, 188, 113, 29, 0, -58]
2022-09-13 16:58:07.847 DEBUG (MainThread) [zigpy.zcl] [0xCF7E:1:0x000a] Received ZCL frame: b'\x00r\x00\x07\x00'
2022-09-13 16:58:07.848 DEBUG (MainThread) [zigpy.zcl] [0xCF7E: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=114, command_id=0, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-09-13 16:58:07.850 DEBUG (MainThread) [zigpy.zcl] [0xCF7E:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[7])
2022-09-13 16:58:07.850 DEBUG (MainThread) [zigpy.zcl] [0xCF7E:1:0x000a] Received command 0x00 (TSN 114): Read_Attributes(attribute_ids=[7])
2022-09-13 16:58:07.852 DEBUG (MainThread) [zigpy.zcl] [0xCF7E: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=114, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-09-13 16:58:07.853 DEBUG (MainThread) [zigpy.zcl] [0xCF7E:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=LocalTime, value=716403487))])
2022-09-13 16:58:07.854 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 114 under 111 request id, data: b'187201070000231f73b32a'
2022-09-13 16:58:07.855 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xcf7e>, ep: 1, profile: 0x0104, cluster_id: 0x000a, data: b'0072000700'
2022-09-13 16:58:07.855 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (26, 111, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xCF7E endpoint=1>, 260, 10, 1, b'\x18r\x01\x07\x00\x00#\x1fs\xb3*', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-09-13 16:58:07.856 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12cf0021001a006f00027ecf0104010a00010b00187201070000231f73b32a0200
2022-09-13 16:58:07.860 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12cf0009000200226f
2022-09-13 16:58:07.860 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 111]
2022-09-13 16:58:07.868 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed0000700a600
2022-09-13 16:58:07.869 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-09-13 16:58:07.870 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-09-13 16:58:07.870 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d00007000000
2022-09-13 16:58:07.872 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d00013000c00226f027ecf01010000000000
2022-09-13 16:58:07.872 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 111: 00
2022-09-13 16:58:07.873 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x6f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xcf7e endpoint=1>, status: 0x00
2022-09-13 16:58:08.092 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed1000700aa00
2022-09-13 16:58:08.093 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2022-09-13 16:58:08.094 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2022-09-13 16:58:08.094 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x17d1000800010001
2022-09-13 16:58:08.097 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x17d1002d0026002202000001020bb001040100000f0008440a01002040e2ff2020e4ff200000afffbc711d00d5
2022-09-13 16:58:08.097 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [38, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, 1, 260, 0, b'\x08D\n\x01\x00 @\xe2\xff \xe4\xff \x00', 0, 175, 255, 188, 113, 29, 0, -43]
2022-09-13 16:58:08.099 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Received ZCL frame: b'\x08D\n\x01\x00 @\xe2\xff \xe4\xff \x00'
2022-09-13 16:58:08.101 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] 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=68, command_id=10, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-09-13 16:58:08.103 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=64)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=32)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2022-09-13 16:58:08.104 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Received command 0x0A (TSN 68): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=64)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=32)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=0))])
2022-09-13 16:58:08.105 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Attribute report received: app_version=64, 0xFFE2=32, 0xFFE4=0
2022-09-13 16:58:08.106 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True, *is_reply=True), tsn=68, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>, *is_reply=True)
2022-09-13 16:58:08.106 DEBUG (MainThread) [zigpy.zcl] [0xB00B:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
2022-09-13 16:58:08.107 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 68 under 112 request id, data: b'18440b0a00'
2022-09-13 16:58:08.108 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xb00b>, ep: 1, profile: 0x0104, cluster_id: 0x0000, data: b'08440a01002040e2ff2020e4ff2000'
2022-09-13 16:58:08.108 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 112, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0xB00B endpoint=1>, 260, 0, 1, b'\x18D\x0b\n\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-09-13 16:58:08.108 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d2001b0014007000020bb0010401000001050018440b0a000200
2022-09-13 16:58:08.113 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d200090002002270
2022-09-13 16:58:08.113 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 112]
2022-09-13 16:58:08.121 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed3000700a600
2022-09-13 16:58:08.121 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2022-09-13 16:58:08.122 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2022-09-13 16:58:08.122 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d30007000000
2022-09-13 16:58:08.126 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d30013000c002270020bb001010000000000
2022-09-13 16:58:08.126 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 112: 00
2022-09-13 16:58:08.127 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x70 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xb00b endpoint=1>, status: 0x00
2022-09-13 16:58:09.219 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4707](lumi.remote.b28ac1): last_seen is 4231003.926374674 seconds ago and ping attempts have been exhausted, marking the device unavailable
2022-09-13 16:58:09.219 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x4707](lumi.remote.b28ac1): Update device availability - device available: False - new availability: False - changed: False
2022-09-13 16:58:11.214 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1EF8](TS0203): Device seen - marking the device available and resetting counter
2022-09-13 16:58:11.214 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1EF8](TS0203): Update device availability - device available: True - new availability: True - changed: False
2022-09-13 16:58:12.207 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x873D](TS0203): Device seen - marking the device available and resetting counter
2022-09-13 16:58:12.208 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x873D](TS0203): Update device availability - device available: True - new availability: True - changed: False
2022-09-13 16:58:15.177 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE13A](TS0002): Device seen - marking the device available and resetting counter
2022-09-13 16:58:15.177 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE13A](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-09-13 16:58:15.242 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01C0](lumi.switch.n1aeu1): last_seen is 3964822.855497122 seconds ago and ping attempts have been exhausted, marking the device unavailable
2022-09-13 16:58:15.242 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x01C0](lumi.switch.n1aeu1): Update device availability - device available: False - new availability: False - changed: False
2022-09-13 16:58:18.274 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB00B](TS0601): Device seen - marking the device available and resetting counter
2022-09-13 16:58:18.274 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xB00B](TS0601): Update device availability - device available: True - new availability: True - changed: False
2022-09-13 16:58:19.201 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x689F](lumi.sensor_magnet.aq2): Device seen - marking the device available and resetting counter
2022-09-13 16:58:19.202 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x689F](lumi.sensor_magnet.aq2): Update device availability - device available: True - new availability: True - changed: False
2022-09-13 16:58:19.260 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9E8F](TS0002): Device seen - marking the device available and resetting counter
2022-09-13 16:58:19.260 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x9E8F](TS0002): Update device availability - device available: True - new availability: True - changed: False
2022-09-13 16:58:22.254 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8191](TS011F): Attempting to checkin with device - missed checkins: 1
2022-09-13 16:58:22.258 DEBUG (MainThread) [zigpy.zcl] [0x8191:1:0x0000] 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=113, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>, *is_reply=False)
2022-09-13 16:58:22.259 DEBUG (MainThread) [zigpy.zcl] [0x8191:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4])
2022-09-13 16:58:22.261 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 113 under 114 request id, data: b'0071000400'
2022-09-13 16:58:22.261 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 114, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x8191 endpoint=1>, 260, 0, 1, b'\x00q\x00\x04\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
2022-09-13 16:58:22.263 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d4001b0014007200029181010401000001050000710004000200
2022-09-13 16:58:22.266 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d400090002002272
2022-09-13 16:58:22.267 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 114]

lotr avatar Sep 13 '22 15:09 lotr

How do I do that? Do I just add that file to the /config/zha_quirks folder and ensure this is in HA configuration.yaml?

zha:
  enable_quirks: true
  custom_quirks_path: /config/zha_quirks

that doesn't seem to work...

Ummmm, I believe that would be a little more complex...

You will need to copy also the ts0601_dimmer.py file (in the same folder):

  • https://github.com/zigpy/zha-device-handlers/blob/dev/zhaquirks/tuya/ts0601_dimmer.py

And adapt (in the ts0601_dimmer.py file) all the tuya/mcu/* imports like this:

from zhaquirks.tuya import NoManufacturerCluster, TuyaDimmerSwitch
from custom_zha_quirks import (
    TuyaInWallLevelControl,
    TuyaLevelControlManufCluster,
    TuyaOnOff,
    TuyaOnOffNM,
)

javicalle avatar Sep 13 '22 17:09 javicalle

The fixed command probably will be included in the next HA release. Remember to delete the local quirks once updated.

javicalle avatar Sep 25 '22 18:09 javicalle

I confirm my dimmers work correctly after upgrading to HA Core 2022.10.4

anttix avatar Oct 17 '22 19:10 anttix