Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

ZHA only working for a few hours #43962

Closed
benjR opened this issue Dec 5, 2020 · 26 comments
Closed

ZHA only working for a few hours #43962

benjR opened this issue Dec 5, 2020 · 26 comments

Comments

@benjR
Copy link

benjR commented Dec 5, 2020

The problem

I recently switched from the zigate custom component (thanks @doudz for the work) to the ZHA integration for zigate. It seems to be okay for a while (a few hours each time) but then stops working, to make it work again, I have to restart home assistant.
I have also noticed that OTA update with IKEA device are not working but will open another issue for this once this is one is closed.

Environment

  • Home Assistant Core release with the issue: Home assistant 0.118.4
  • Last working Home Assistant Core release (if known): was working okay before with zigate plugin
  • Operating environment (OS/Container/Supervised/Core): Docker
  • Integration causing this issue: ZHA
  • Link to integration documentation on our website: https://www.home-assistant.io/integrations/zha/

Zigbee signature

{
  "node_descriptor": "NodeDescriptor()",
  "endpoints": {},
  "manufacturer": "ZiGate",
  "model": "ZiGate WiFi 3.1d",
  "class": "zigpy_zigate.zigbee.application.ZiGateDevice"
}

I have 2 TRADFRI bulbs, 2 TRADFRI control outlets, 7 Aqara LUMI weather sensors, and 3 Aqara LUMI motion sensors connected

Problem-relevant configuration.yaml

Configured via GUI but I disabled updates for IKEA devices as it's not working

zha:
  zigpy_config:
    ota:
      ikea_provider: false

Traceback/Error logs

2020-12-05 03:42:42 DEBUG (MainThread) [zigpy.device] [0x0167] Delivery error for seq # 0x49, on endpoint id 1 cluster 0x0300: ZiGate doesn't answer to command
2020-12-05 03:42:42 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:42:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x1d\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x13\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10I\x02\x10\x02\x17\x02\x10'
2020-12-05 03:42:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\r\x02\x01g\x01\x01\x03\x00\x01\x04\x00\x00\x05\x00I\x00\x07\x00'
2020-12-05 03:42:40 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101030001040000050049000700'
2020-12-05 03:42:40 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 03:42:40 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:42:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x1d\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x13\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10I\x02\x10\x02\x17\x02\x10'
2020-12-05 03:42:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\r\x02\x01g\x01\x01\x03\x00\x01\x04\x00\x00\x05\x00I\x00\x07\x00'
2020-12-05 03:42:39 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101030001040000050049000700'
2020-12-05 03:42:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x0167, 260, 768, 1, 1, 73, b'\x00I\x00\x07\x00', True, False)
2020-12-05 03:42:39 DEBUG (MainThread) [zigpy.device] [0x0167] Delivery error for seq # 0x48, on endpoint id 1 cluster 0x0008: ZiGate doesn't answer to command
2020-12-05 03:42:39 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:42:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x10\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x18\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10H\x02\x10\x02\x10\x02\x10'
2020-12-05 03:42:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x00\x02\x01g\x01\x01\x00\x08\x01\x04\x00\x00\x05\x00H\x00\x00\x00'
2020-12-05 03:42:37 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000801040000050048000000'
2020-12-05 03:42:37 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 03:42:37 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:42:36 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x10\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x18\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10H\x02\x10\x02\x10\x02\x10'
2020-12-05 03:42:36 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x00\x02\x01g\x01\x01\x00\x08\x01\x04\x00\x00\x05\x00H\x00\x00\x00'
2020-12-05 03:42:36 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000801040000050048000000'
2020-12-05 03:42:36 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x0167, 260, 8, 1, 1, 72, b'\x00H\x00\x00\x00', True, False)
2020-12-05 03:42:36 DEBUG (MainThread) [zigpy.device] [0x0167] Delivery error for seq # 0x47, on endpoint id 1 cluster 0x0006: ZiGate doesn't answer to command
2020-12-05 03:42:36 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:42:34 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x11\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10G\x02\x10\x02\x10\x02\x10'
2020-12-05 03:42:34 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x01\x02\x01g\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00G\x00\x00\x00'
2020-12-05 03:42:34 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000601040000050047000000'
2020-12-05 03:42:34 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 03:42:34 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:42:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x11\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10G\x02\x10\x02\x10\x02\x10'
2020-12-05 03:42:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x01\x02\x01g\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00G\x00\x00\x00'
2020-12-05 03:42:33 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000601040000050047000000'
2020-12-05 03:42:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x0167, 260, 6, 1, 1, 71, b'\x00G\x00\x00\x00', True, False)
2020-12-05 03:18:14 DEBUG (MainThread) [zigpy.device] [0x3e9b] Delivery error for seq # 0x46, on endpoint id 1 cluster 0x0300: ZiGate doesn't answer to command
2020-12-05 03:18:14 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:18:12 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x13\xc1\x02\x12>\x9b\x02\x11\x02\x11\x02\x13\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10F\x02\x10\x02\x13\x02\x10\x02\x14\x02\x10'
2020-12-05 03:18:12 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x13\xc1\x02>\x9b\x01\x01\x03\x00\x01\x04\x00\x00\x07\x00F\x00\x03\x00\x04\x00'
2020-12-05 03:18:12 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'023e9b01010300010400000700460003000400'
2020-12-05 03:18:12 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 03:18:12 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:18:11 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x13\xc1\x02\x12>\x9b\x02\x11\x02\x11\x02\x13\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10F\x02\x10\x02\x13\x02\x10\x02\x14\x02\x10'
2020-12-05 03:18:11 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x13\xc1\x02>\x9b\x01\x01\x03\x00\x01\x04\x00\x00\x07\x00F\x00\x03\x00\x04\x00'
2020-12-05 03:18:11 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'023e9b01010300010400000700460003000400'
2020-12-05 03:18:11 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x3E9B, 260, 768, 1, 1, 70, b'\x00F\x00\x03\x00\x04\x00', True, False)
2020-12-05 03:18:11 DEBUG (MainThread) [zigpy.device] [0x3e9b] Delivery error for seq # 0x45, on endpoint id 1 cluster 0x0008: ZiGate doesn't answer to command
2020-12-05 03:18:11 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:18:09 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xce\x02\x12>\x9b\x02\x11\x02\x11\x02\x10\x02\x18\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10E\x02\x10\x02\x10\x02\x10'
2020-12-05 03:18:09 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xce\x02>\x9b\x01\x01\x00\x08\x01\x04\x00\x00\x05\x00E\x00\x00\x00'
2020-12-05 03:18:09 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'023e9b0101000801040000050045000000'
2020-12-05 03:18:09 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 03:18:09 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:18:08 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xce\x02\x12>\x9b\x02\x11\x02\x11\x02\x10\x02\x18\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10E\x02\x10\x02\x10\x02\x10'
2020-12-05 03:18:08 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xce\x02>\x9b\x01\x01\x00\x08\x01\x04\x00\x00\x05\x00E\x00\x00\x00'
2020-12-05 03:18:08 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'023e9b0101000801040000050045000000'
2020-12-05 03:18:08 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x3E9B, 260, 8, 1, 1, 69, b'\x00E\x00\x00\x00', True, False)
2020-12-05 03:18:08 DEBUG (MainThread) [zigpy.device] [0x3e9b] Delivery error for seq # 0x44, on endpoint id 1 cluster 0x0006: ZiGate doesn't answer to command
2020-12-05 03:18:08 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:18:06 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xc1\x02\x12>\x9b\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10D\x02\x10\x02\x10\x02\x10'
2020-12-05 03:18:06 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xc1\x02>\x9b\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00D\x00\x00\x00'
2020-12-05 03:18:06 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'023e9b0101000601040000050044000000'
2020-12-05 03:18:06 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 03:18:06 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 03:18:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xc1\x02\x12>\x9b\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10D\x02\x10\x02\x10\x02\x10'
2020-12-05 03:18:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xc1\x02>\x9b\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00D\x00\x00\x00'
2020-12-05 03:18:04 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'023e9b0101000601040000050044000000'
2020-12-05 03:18:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x3E9B, 260, 6, 1, 1, 68, b'\x00D\x00\x00\x00', True, False)
2020-12-05 02:55:31 DEBUG (MainThread) [zigpy.device] [0x0167] Delivery error for seq # 0x43, on endpoint id 1 cluster 0x0300: ZiGate doesn't answer to command
2020-12-05 02:55:31 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 02:55:29 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x17\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x13\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10C\x02\x10\x02\x17\x02\x10'
2020-12-05 02:55:29 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x07\x02\x01g\x01\x01\x03\x00\x01\x04\x00\x00\x05\x00C\x00\x07\x00'
2020-12-05 02:55:29 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101030001040000050043000700'
2020-12-05 02:55:29 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 02:55:29 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 02:55:28 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x17\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x13\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10C\x02\x10\x02\x17\x02\x10'
2020-12-05 02:55:28 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x07\x02\x01g\x01\x01\x03\x00\x01\x04\x00\x00\x05\x00C\x00\x07\x00'
2020-12-05 02:55:28 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101030001040000050043000700'
2020-12-05 02:55:28 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x0167, 260, 768, 1, 1, 67, b'\x00C\x00\x07\x00', True, False)
2020-12-05 02:55:28 DEBUG (MainThread) [zigpy.device] [0x0167] Delivery error for seq # 0x42, on endpoint id 1 cluster 0x0008: ZiGate doesn't answer to command
2020-12-05 02:55:28 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 02:55:26 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x1a\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x18\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10B\x02\x10\x02\x10\x02\x10'
2020-12-05 02:55:26 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\n\x02\x01g\x01\x01\x00\x08\x01\x04\x00\x00\x05\x00B\x00\x00\x00'
2020-12-05 02:55:26 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000801040000050042000000'
2020-12-05 02:55:26 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 02:55:26 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 02:55:25 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x1a\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x18\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10B\x02\x10\x02\x10\x02\x10'
2020-12-05 02:55:25 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\n\x02\x01g\x01\x01\x00\x08\x01\x04\x00\x00\x05\x00B\x00\x00\x00'
2020-12-05 02:55:25 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000801040000050042000000'
2020-12-05 02:55:25 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x0167, 260, 8, 1, 1, 66, b'\x00B\x00\x00\x00', True, False)
2020-12-05 02:55:25 DEBUG (MainThread) [zigpy.device] [0x0167] Delivery error for seq # 0x41, on endpoint id 1 cluster 0x0006: ZiGate doesn't answer to command
2020-12-05 02:55:25 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 02:55:23 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x17\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10A\x02\x10\x02\x10\x02\x10'
2020-12-05 02:55:23 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x07\x02\x01g\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00A\x00\x00\x00'
2020-12-05 02:55:23 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000601040000050041000000'
2020-12-05 02:55:23 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
2020-12-05 02:55:23 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
2020-12-05 02:55:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x17\x02\x12\x02\x11g\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10A\x02\x10\x02\x10\x02\x10'
2020-12-05 02:55:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x07\x02\x01g\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00A\x00\x00\x00'
2020-12-05 02:55:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'0201670101000601040000050041000000'
2020-12-05 02:55:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x0167, 260, 6, 1, 1, 65, b'\x00A\x00\x00\x00', True, False)
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0000] Attribute report received: 65281=�!���!���!+t�$�
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=�!���!���!+t�$�>)]]
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=72 command_id=Command.Report_Attributes>
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 0, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x55ee>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18H\n\x01\xffB%\x01!\xbd\x0b\x04!\xa8\x13\x05!+t\x06$\x01\x00\x00\x00\x00d)\x18\x07e!\xb9\x16f+v\x80\x01\x00\n!\x00\x00']
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'000104000001010255ee02000018480a01ff42250121bd0b0421a81305212b7406240100000000642918076521b916662b768001000a210000' LQI:90
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002003ae7000104000001010255ee02000018480a01ff42250121bd0b0421a81305212b7406240100000000642918076521b916662b768001000a2100005a
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0000] Attribute report received: 65281=�!���!���!+t�$�
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=�!���!���!+t�$�>)]]
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=72 command_id=Command.Report_Attributes>
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 0, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x55ee>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18H\n\x01\xffB%\x01!\xbd\x0b\x04!\xa8\x13\x05!+t\x06$\x01\x00\x00\x00\x00d)\x18\x07e!\xb9\x16f+v\x80\x01\x00\n!\x00\x00']
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'000104000001010255ee02000018480a01ff42250121bd0b0421a81305212b7406240100000000642918076521b916662b768001000a210000' LQI:90
2020-12-05 02:48:35 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002003ae7000104000001010255ee02000018480a01ff42250121bd0b0421a81305212b7406240100000000642918076521b916662b768001000a2100005a
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy.zcl] [0x58e5:1:0x0000] Attribute report received: 65281=�!���(��!���!�
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy.zcl] [0x58e5:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=�!���(��!���!�>)]]
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy.zcl] [0x58e5:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=False disable_default_response=True> manufacturer=4447 tsn=173 command_id=Command.Report_Attributes>
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 0, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x58e5>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x1c_\x11\xad\n\x01\xffB!\x01!\xb3\x0b\x03(\x19\x04!\xa8\x13\x05!\x0f\x00\x06$\x02\x00\x00\x00\x00\n!\x00\x00d\x10\x00\x0b!\x00\x00']
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'000104000001010258e50200001c5f11ad0a01ff42210121b30b0328190421a81305210f00062402000000000a2100006410000b210000' LQI:117
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002003855000104000001010258e50200001c5f11ad0a01ff42210121b30b0328190421a81305210f00062402000000000a2100006410000b21000075
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy.zcl] [0x58e5:1:0x0000] Attribute report received: 65281=�!���(��!���!�
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy.zcl] [0x58e5:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=�!���(��!���!�>)]]
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy.zcl] [0x58e5:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=False disable_default_response=True> manufacturer=4447 tsn=173 command_id=Command.Report_Attributes>
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 0, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x58e5>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x1c_\x11\xad\n\x01\xffB!\x01!\xb3\x0b\x03(\x19\x04!\xa8\x13\x05!\x0f\x00\x06$\x02\x00\x00\x00\x00\n!\x00\x00d\x10\x00\x0b!\x00\x00']
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'000104000001010258e50200001c5f11ad0a01ff42210121b30b0328190421a81305210f00062402000000000a2100006410000b210000' LQI:117
2020-12-05 02:47:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002003855000104000001010258e50200001c5f11ad0a01ff42210121b30b0328190421a81305210f00062402000000000a2100006410000b21000075
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.zigbee.application] ACK Data received 93 0
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 0xf932, 1, 6, 93]
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8011 b'00f9320100065d' LQI:0
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 801100080800f9320100065d00
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 94, 1328, b'\x00\x00']
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'005e05300000' LQI:0
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80000007ec005e0530000000
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.util] Duplicate 4 TSN
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.zcl] [0xf932:1:0x0006] Attribute report received: on_off=1
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.zcl] [0xf932:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.zcl] [0xf932:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=4 command_id=Command.Report_Attributes>
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xf932>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x08\x04\n\x00\x00\x10\x01']
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040006010102f93202000008040a00001001' LQI:75
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015030001040006010102f93202000008040a000010014b
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xf6\x02\x12\xf92\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x18\x02\x14\x02\x1b\x02\x1a\x02\x10'
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xf6\x02\xf92\x01\x01\x00\x06\x01\x04\x00\x00\x05\x18\x04\x0b\n\x00'
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02f93201010006010400000518040b0a00'
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xF932, 260, 6, 1, 1, 4, b'\x18\x04\x0b\n\x00', False, False)
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.zcl] [0xf932:1:0x0006] Attribute report received: on_off=1
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.zcl] [0xf932:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy.zcl] [0xf932:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=4 command_id=Command.Report_Attributes>
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xf932>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x08\x04\n\x00\x00\x10\x01']
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040006010102f93202000008040a00001001' LQI:75
2020-12-05 02:46:14 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015030001040006010102f93202000008040a000010014b
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9832
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9832>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=242 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf2\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)h&']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102086d02000018f20a000029d703140028ff1000296826' LQI:123
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f280001040403010102086d02000018f20a000029d703140028ff10002968267b
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9832
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9832>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=242 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf2\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)h&']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102086d02000018f20a000029d703140028ff1000296826' LQI:123
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f280001040403010102086d02000018f20a000029d703140028ff10002968267b
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9832
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9832>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=242 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf2\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)h&']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102086d02000018f20a000029d703140028ff1000296826' LQI:123
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f280001040403010102086d02000018f20a000029d703140028ff10002968267b
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9832
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9832>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=242 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf2\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)h&']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102086d02000018f20a000029d703140028ff1000296826' LQI:123
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f280001040403010102086d02000018f20a000029d703140028ff10002968267b
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0405] Attribute report received: measured_value=8644
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=8644>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=241 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1029, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf1\n\x00\x00!\xc4!']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040405010102086d02000018f10a000021c421' LQI:120
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016aa0001040405010102086d02000018f10a000021c42178
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0405] Attribute report received: measured_value=8644
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=8644>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=241 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1029, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf1\n\x00\x00!\xc4!']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040405010102086d02000018f10a000021c421' LQI:120
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016aa0001040405010102086d02000018f10a000021c42178
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0402] Attribute report received: measured_value=454
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=454>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=240 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1026, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf0\n\x00\x00)\xc6\x01']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040402010102086d02000018f00a000029c601' LQI:123
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016850001040402010102086d02000018f00a000029c6017b
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0402] Attribute report received: measured_value=454
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=454>)]]
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy.zcl] [0x086d:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=240 command_id=Command.Report_Attributes>
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1026, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x086d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\xf0\n\x00\x00)\xc6\x01']
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040402010102086d02000018f00a000029c601' LQI:123
2020-12-05 02:46:04 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016850001040402010102086d02000018f00a000029c6017b
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9834
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9834>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=92 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\\\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)j&']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102cfd6020000185c0a000029d703140028ff1000296a26' LQI:105
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001fea0001040403010102cfd6020000185c0a000029d703140028ff1000296a2669
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9834
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9834>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=92 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\\\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)j&']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102cfd6020000185c0a000029d703140028ff1000296a26' LQI:105
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001fea0001040403010102cfd6020000185c0a000029d703140028ff1000296a2669
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9834
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9834>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=92 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\\\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)j&']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102cfd6020000185c0a000029d703140028ff1000296a26' LQI:105
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001fea0001040403010102cfd6020000185c0a000029d703140028ff1000296a2669
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] Attribute report received: measured_value=983, 20=-1, 16=9834
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=983>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9834>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=92 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\\\n\x00\x00)\xd7\x03\x14\x00(\xff\x10\x00)j&']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040403010102cfd6020000185c0a000029d703140028ff1000296a26' LQI:105
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001fea0001040403010102cfd6020000185c0a000029d703140028ff1000296a2669
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0405] Attribute report received: measured_value=5417
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=5417>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=91 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1029, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18[\n\x00\x00!)\x15']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040405010102cfd6020000185b0a0000212915' LQI:108
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016b10001040405010102cfd6020000185b0a00002129156c
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0405] Attribute report received: measured_value=5417
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=5417>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=91 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1029, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18[\n\x00\x00!)\x15']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040405010102cfd6020000185b0a0000212915' LQI:108
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016b10001040405010102cfd6020000185b0a00002129156c
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0402] Attribute report received: measured_value=1760
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=1760>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=90 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1026, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18Z\n\x00\x00)\xe0\x06']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040402010102cfd6020000185a0a000029e006' LQI:111
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016660001040402010102cfd6020000185a0a000029e0066f
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0402] Attribute report received: measured_value=1760
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=1760>)]]
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy.zcl] [0xcfd6:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=90 command_id=Command.Report_Attributes>
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1026, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xcfd6>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18Z\n\x00\x00)\xe0\x06']
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040402010102cfd6020000185a0a000029e006' LQI:111
2020-12-05 02:45:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016660001040402010102cfd6020000185a0a000029e0066f
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] Attribute report received: measured_value=982, 20=-1, 16=9824
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=982>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9824>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=30 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1e\n\x00\x00)\xd6\x03\x14\x00(\xff\x10\x00)`&']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404030101021339020000181e0a000029d603140028ff1000296026' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f7d00010404030101021339020000181e0a000029d603140028ff100029602684
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] Attribute report received: measured_value=982, 20=-1, 16=9824
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=982>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9824>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=30 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1e\n\x00\x00)\xd6\x03\x14\x00(\xff\x10\x00)`&']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404030101021339020000181e0a000029d603140028ff1000296026' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f7d00010404030101021339020000181e0a000029d603140028ff100029602684
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] Attribute report received: measured_value=982, 20=-1, 16=9824
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=982>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9824>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=30 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1e\n\x00\x00)\xd6\x03\x14\x00(\xff\x10\x00)`&']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404030101021339020000181e0a000029d603140028ff1000296026' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f7d00010404030101021339020000181e0a000029d603140028ff100029602684
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] Attribute report received: measured_value=982, 20=-1, 16=9824
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=982>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9824>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=30 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1e\n\x00\x00)\xd6\x03\x14\x00(\xff\x10\x00)`&']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404030101021339020000181e0a000029d603140028ff1000296026' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f7d00010404030101021339020000181e0a000029d603140028ff100029602684
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0405] Attribute report received: measured_value=4922
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=4922>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=29 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1029, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1d\n\x00\x00!:\x13']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404050101021339020000181d0a0000213a13' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200163900010404050101021339020000181d0a0000213a1384
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0405] Attribute report received: measured_value=4922
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=4922>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=29 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1029, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1d\n\x00\x00!:\x13']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404050101021339020000181d0a0000213a13' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200163900010404050101021339020000181d0a0000213a1384
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0402] Attribute report received: measured_value=2108
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=2108>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=28 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1026, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1c\n\x00\x00)<\x08']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404020101021339020000181c0a0000293c08' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200162a00010404020101021339020000181c0a0000293c0884
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0402] Attribute report received: measured_value=2108
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=2108>)]]
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy.zcl] [0x1339:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=28 command_id=Command.Report_Attributes>
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1026, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x1339>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1c\n\x00\x00)<\x08']
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'00010404020101021339020000181c0a0000293c08' LQI:132
2020-12-05 02:42:48 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200162a00010404020101021339020000181c0a0000293c0884
2020-12-05 02:40:30 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0403] Attribute report received: measured_value=984, 20=-1, 16=9841
2020-12-05 02:40:30 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=984>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=9841>)]]
2020-12-05 02:40:30 DEBUG (MainThread) [zigpy.zcl] [0x55ee:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=71 command_id=Command.Report_Attributes>
2020-12-05 02:40:30 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1027, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x55ee>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18G\n\x00\x00)\xd8\x03\x14\x00(\xff\x10\x00)q&']
2020-12-05 02:40:30 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'000104040301010255ee02000018470a000029d803140028ff1000297126' LQI:90
2020-12-05 02:40:30 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8002001f74000104040301010255ee02000018470a000029d803140028ff10002971265a

Additional information

Here on the logs, it seems to stop working at 2020-12-05 02:55:22, last sensor update seems to have been received at 02:45:58.

Thanks for your help!

@doudz
Copy link
Contributor

doudz commented Dec 5, 2020

Since you use a ZiGate WiFi, it could be a network problem.
Currently there's no auto-reconnection, previous integration has it

@doudz
Copy link
Contributor

doudz commented Dec 5, 2020

zigpy/zigpy-zigate#70

@benjR
Copy link
Author

benjR commented Dec 5, 2020

thanks @doudz
That could be a possibility, in the meantime trying to use dirty workaround to make it work longer (for those who have the same issue), by calling the integration reload url every Xmin using a rest_command and calling this url http://192.168.1.100:8123/api/config/config_entries/entry/integrationID/reload

This will probably do the trick, will report here

@bokub
Copy link
Contributor

bokub commented Dec 6, 2020

I also switched from the zigate custom component (again, thanks @doudz ) to ZHA a few days ago, and I have a similar problem: after a few hours, all my zigbee devices stop working (mostly aqara devices).

Restarting home assistant solves the problem 100% of the time.

However, unlike @benjR:

  • I have a PiZigate
  • There are absolutely no log about it

Any idea about what's happening?

@doudz
Copy link
Contributor

doudz commented Dec 6, 2020

🤔
I'm using pizigate for weeks now with zha and I have no problem.
I have many aqara devices and also few Ikea and osram bulbs.

@bokub are you using firmware 3.1d ?

@bokub
Copy link
Contributor

bokub commented Dec 6, 2020

I just upgraded this morning, I'll tell you in a few days if the problem is solved 👍

@doudz
Copy link
Contributor

doudz commented Dec 6, 2020

ok, previous firmwares are known to have memory leak using zha

@dmulcahey
Copy link
Contributor

@doudz is this stated in the setup docs for ZHA with Zigate? I have seen several tickets about this over the last few days.

@doudz
Copy link
Contributor

doudz commented Dec 6, 2020

yes, it's in the doc https://www.home-assistant.io/integrations/zha/

ZiGate based radios (via the zigpy-zigate library for zigpy and require firmware 3.1d or later)

@dmulcahey
Copy link
Contributor

Awesome and smh... 😀

@doudz
Copy link
Contributor

doudz commented Dec 6, 2020

Probably I should add a warning when old firmware is detected

@bokub
Copy link
Contributor

bokub commented Dec 6, 2020

There is one in the logs actually, but maybe it could be even more explicit

@bokub
Copy link
Contributor

bokub commented Dec 9, 2020

I confirm that upgrading my PiZigate to firmware 3.1d solved the problem

Thanks and sorry for the disturbance 👍

@benjR
Copy link
Author

benjR commented Dec 13, 2020

Ah issue closed? Well a note should be added to the docs, people using Zigate Wifi should not migrate to ZHA before the fix from @doudz is available, it's not stable as is.

@dmulcahey
Copy link
Contributor

There already is a note about minimum supported firmware according to @doudz

@doudz
Copy link
Contributor

doudz commented Dec 13, 2020

well, the issue should be re-open because the initial problem is not related to firmware 3.1d, it's a problem of network disconnection of zigate wifi
current zigpy-zigate doesn't have auto reconnect feature

@dmulcahey dmulcahey reopened this Dec 13, 2020
@dmulcahey
Copy link
Contributor

Done

@benjR
Copy link
Author

benjR commented Jan 19, 2021

Hi! question for @doudz more, do you think it's possible to have the old component running just to maintain the connection ?

@baimard
Copy link

baimard commented Jan 29, 2021

Dear, thank you for this issue, I have the exact same problem.

Do you have a hot fix for waiting the new release ?

@baimard
Copy link

baimard commented Jan 30, 2021

I have do this cron job, looks like to work. But I'm not sur for the moment :

*/5 * * * * <user> /bin/bash <path>fixuphass.sh

fixuphass.sh

#!/bin/bash
curl -s -X POST https://<IP>/api/config/config_entries/entry/<IDOFZIGATE>/reload   -H 'authorization: Bearer <yourowntoken>'   -H 'cache-control: no-cache'   -H 'content-type: application/json'   > /dev/null

replace all by your.

regards

@ZeGuigui
Copy link
Contributor

ZeGuigui commented Feb 1, 2021

I tryed a fix from doudz (implement reconnect in zigpy) but this does not work. I am not quite sure if it's doudz implementation that has problems (but looks very similar to what other drivers provide) or if it's a zigpy issue where reconnect is not called when the device is seen as disconnected. Using a similar script as @baimard on an hourly schedule for now.

@baimard
Copy link

baimard commented Feb 1, 2021

I tryed a fix from doudz (implement reconnect in zigpy) but this does not work. I am not quite sure if it's doudz implementation that has problems (but looks very similar to what other drivers provide) or if it's a zigpy issue where reconnect is not called when the device is seen as disconnected. Using a similar script as @baimard on an hourly schedule for now.

I change my schedule by 45 minutes, because 5 min was very short and the monitoring of the light looks bad

@github-actions
Copy link

github-actions bot commented May 2, 2021

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

@github-actions github-actions bot added the stale label May 2, 2021
@github-actions github-actions bot closed this as completed May 9, 2021
@baimard
Copy link

baimard commented May 18, 2021

Hello,

After longtime deal with the path, I just can say. that not work ! After a random delay, all the device connected to Zigate And integrated on home Assistant with ZHA not works.

DEBUG :

home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:57 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_off, service_data=entity_id=light.beta_level_on_off>
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:57 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x5231, 260, 6, 1, 1, 18, b'\x01\x12\x00', True, False)
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:57 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'025231010100060104000003011200'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:57 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x0fH\x02R1\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x12\x00'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:57 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x02\x1fH\x02\x12R1\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x12\x02\x10'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:58 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:58 WARNING (MainThread) [zigpy_zigate.api] Retry command 0x0530
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:58 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'025231010100060104000003011200'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x0fH\x02R1\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x12\x00'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:25:58 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x02\x1fH\x02\x12R1\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x12\x02\x10'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:26:00 WARNING (MainThread) [zigpy_zigate.api] No response to command 0x0530
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:26:00 DEBUG (MainThread) [zigpy.device] [0x5231] Delivery error for seq # 0x12, on endpoint id 1 cluster 0x0006: ZiGate doesn't answer to command
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:26:00 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x5231:1:0x0006]: command failed: 'off' args: '()' kwargs '{}' exception: '[0x5231:1:0x0006]: Message send failure'
home_assistant.1.k28tajqju8bv@x-kaki    | 2021-05-18 23:26:00 DEBUG (MainThread) [homeassistant.components.zha.entity] light.beta_level_on_off: turned off: [0x5231:1:0x0006]: Message send failure

Issue open

zigpy/zigpy-zigate#89 (comment)

I have also update the firmware of my zigate WIFI.

There is a really big problem, I can't identify what happend. I can do every debug you want, to try to solve this issue !

Could you please take this problem in consideration ?

@tygerlord
Copy link

I use firmware 3.1e
Yesterday all is working. This morning: devices Offlines

@ZeGuigui
Copy link
Contributor

ZeGuigui commented Jun 14, 2021

Same firmware, same issue. Zigate in wifi.
Doudz has not yet released his 0.8.0 version of zigpy-zigate which tries to implement reconnect. Without this feature we cannot expect stability from this platform. I wish I had better python skills and some time to help him debug and release this.

@github-actions github-actions bot locked and limited conversation to collaborators Jul 14, 2021
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Development

No branches or pull requests

8 participants