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.

Sign up for GitHub

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

Jump to bottom

ZHA: Visonic MCT-340 isn't reporting state correctly #28410

Closed
edif30 opened this issue Nov 1, 2019 · 8 comments
Closed

ZHA: Visonic MCT-340 isn't reporting state correctly #28410

edif30 opened this issue Nov 1, 2019 · 8 comments
Assignees
@dmulcahey @Adminiuga
Labels
integration: zha

Comments

@edif30
Copy link
Contributor

edif30 commented Nov 1, 2019 •
edited

Home Assistant release with the issue: 0.100+

Last working Home Assistant release (if known): N/A

Operating environment (Hass.io/Docker/Windows/etc.): Docker

Integration: ZHA

Description of problem:
Sensors seemingly pair ok and report state changes for the most part. I have 14 of these same type of sensors. A few (mostly ones we use a lot) seem to not catch the state of the sensor if the sensor changes state fast. Meaning if the door is opened and shut fast, the state will remain open and open. If I open and close the sensor again it usually updates. A few times I had to open and close 3 or 4x just to get it to update. I bought extra mains powered devices in hopes to get better coverage. You can see in the logs that the alarm_state stays the same.

I noticed a Centralite sensor I have did the same thing. It just doesn't happen as frequently. I have decent coverage as all my RSSI is between -58 and -68. LQI is almost always 255.

Problem-relevant configuration.yaml entries and (fill out even if it seems unimportant):
There is no config. This is just standard zha config.

  usb_path: !secret zha_device_path
  database_path: !secret zha_db_path

Traceback (if applicable): No tracebacks

Additional information:

Using a HUSBZB-1

2019-10-27 16:13:57 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002cffc01230ffff09094600210000000000'
2019-10-27 16:13:57 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=44>, 255, -64, 0x3012, 255, 255, b'\tF\x00!\x00\x00\x00\x00\x00']
2019-10-27 16:13:57 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=70 command_id=Command.Read_Attributes>
2019-10-27 16:13:57 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 0]
2019-10-27 16:13:57 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:13:57 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:13:57 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002dfec01230ffff09094600210000000000'
2019-10-27 16:13:57 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=45>, 254, -64, 0x3012, 255, 255, b'\tF\x00!\x00\x00\x00\x00\x00']
2019-10-27 16:13:57 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=70 command_id=Command.Read_Attributes>
2019-10-27 16:13:57 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 0]
2019-10-27 16:13:57 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:13:57 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:13:58 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-10-27 16:13:58 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'12a321adc7537e'
2019-10-27 16:13:58 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22a3a1adf0227e'
2019-10-27 16:13:58 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2019-10-27 16:13:58 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-10-27 16:13:59 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'32a3b1ed542e14b25c954b65ab559267618e359b12316e82bac64289fc7e37a77bb07e'
2019-10-27 16:13:59 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2019-10-27 16:13:59 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'42a3b1ed542e14b25c954b65ab559266638f359b12316e82bac64289fc7e37a7fbde7e'
2019-10-27 16:13:59 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2019-10-27 16:13:59 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002efdc01230ffff09094700210000000800'
2019-10-27 16:13:59 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=46>, 253, -64, 0x3012, 255, 255, b'\tG\x00!\x00\x00\x00\x08\x00']
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=71 command_id=Command.Read_Attributes>
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 8]
2019-10-27 16:13:59 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:13:59 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002fffc11230ffff09094700210000000800'
2019-10-27 16:13:59 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=47>, 255, -63, 0x3012, 255, 255, b'\tG\x00!\x00\x00\x00\x08\x00']
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=71 command_id=Command.Read_Attributes>
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 8]
2019-10-27 16:13:59 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:13:59 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'52a3b1ed542e14b25c954b65ab559265638e359b12316e82bbc64289fc7e3fa76ebb7e'
2019-10-27 16:13:59 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2019-10-27 16:13:59 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002cffc01230ffff09094600210000000000'
2019-10-27 16:13:59 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=44>, 255, -64, 0x3012, 255, 255, b'\tF\x00!\x00\x00\x00\x00\x00']
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=70 command_id=Command.Read_Attributes>
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 0]
2019-10-27 16:13:59 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:13:59 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:00 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'62a3b1ed542e14b25c954b65ab559264638e359b12316e82bbc64289fc7e3fa723377e'
2019-10-27 16:14:00 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2019-10-27 16:14:00 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002dffc01230ffff09094600210000000000'
2019-10-27 16:14:00 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=45>, 255, -64, 0x3012, 255, 255, b'\tF\x00!\x00\x00\x00\x00\x00']
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=70 command_id=Command.Read_Attributes>
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 0]
2019-10-27 16:14:00 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:00 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'72a3b1ed542e14b25c954b65ab559279638e359b12316e82b5c64289fc7e31a790977e'
2019-10-27 16:14:00 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2019-10-27 16:14:00 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'02a3b1ed542e14b25c954b65ab559278638e359b12316e82b5c64289fc7e31a7fe6c7e'
2019-10-27 16:14:00 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000030ffc01230ffff09094800210000000e00'
2019-10-27 16:14:00 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2019-10-27 16:14:00 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=48>, 255, -64, 0x3012, 255, 255, b'\tH\x00!\x00\x00\x00\x0e\x00']
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=72 command_id=Command.Read_Attributes>
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 14]
2019-10-27 16:14:00 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:00 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000031ffc01230ffff09094800210000000e00'
2019-10-27 16:14:00 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=49>, 255, -64, 0x3012, 255, 255, b'\tH\x00!\x00\x00\x00\x0e\x00']
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=72 command_id=Command.Read_Attributes>
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 14]
2019-10-27 16:14:00 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:00 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:01 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12a3b1ed542e14b25c954b65ab559267638e359b12316e82bac64289fc7e37a7a2727e'
2019-10-27 16:14:01 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2019-10-27 16:14:01 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002effc01230ffff09094700210000000800'
2019-10-27 16:14:01 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=46>, 255, -64, 0x3012, 255, 255, b'\tG\x00!\x00\x00\x00\x08\x00']
2019-10-27 16:14:01 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=71 command_id=Command.Read_Attributes>
2019-10-27 16:14:01 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 8]
2019-10-27 16:14:01 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:01 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:01 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22a3b1ed542e14b25c954b65ab559266638e359b12316e82bac64289fc7e37a7effe7e'
2019-10-27 16:14:01 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2019-10-27 16:14:01 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002fffc01230ffff09094700210000000800'
2019-10-27 16:14:01 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=47>, 255, -64, 0x3012, 255, 255, b'\tG\x00!\x00\x00\x00\x08\x00']
2019-10-27 16:14:01 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=71 command_id=Command.Read_Attributes>
2019-10-27 16:14:01 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 8]
2019-10-27 16:14:01 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:01 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:02 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'32a3b1ed542e14b25c954b65ab559265638e359b12316e82bbc64289fc7e3fa7d4677e'
2019-10-27 16:14:02 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2019-10-27 16:14:02 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'42a3b1ed542e14b25c954b65ab55927b638e359b12316e82b4c64289fc7e2ba747567e'
2019-10-27 16:14:02 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2019-10-27 16:14:02 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100002cffc01230ffff09094600210000000000'
2019-10-27 16:14:02 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=44>, 255, -64, 0x3012, 255, 255, b'\tF\x00!\x00\x00\x00\x00\x00']
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=70 command_id=Command.Read_Attributes>
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 0]
2019-10-27 16:14:02 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:02 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000032ffc01230ffff09094900210000001400'
2019-10-27 16:14:02 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=50>, 255, -64, 0x3012, 255, 255, b'\tI\x00!\x00\x00\x00\x14\x00']
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=73 command_id=Command.Read_Attributes>
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 20]
2019-10-27 16:14:02 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:02 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'52a3b1ed542e14b25c954b65ab55927a638e359b12316e82b4c64289fc7e2ba793717e'
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:02 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2019-10-27 16:14:02 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000033ffc01230ffff09094900210000001400'
2019-10-27 16:14:02 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=51>, 255, -64, 0x3012, 255, 255, b'\tI\x00!\x00\x00\x00\x14\x00']
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=73 command_id=Command.Read_Attributes>
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 20]
2019-10-27 16:14:02 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:04 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'62a3b1ed542e14b05d954b65ab5592e0638e50d512316f83fdcc6389d5ae388d0e7e'
2019-10-27 16:14:04 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2019-10-27 16:14:04 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010204010140010000a9ffc0777effff0808000a000029d007'
2019-10-27 16:14:04 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1026 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=169>, 255, -64, 0x7e77, 255, 255, b'\x08\x00\n\x00\x00)\xd0\x07']
2019-10-27 16:14:04 DEBUG (MainThread) [zigpy.zcl] [0x7e77:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=0 command_id=Command.Report_Attributes>
2019-10-27 16:14:04 DEBUG (MainThread) [zigpy.zcl] [0x7e77:1:0x0402] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=int16s, value=2000>>]]
2019-10-27 16:14:04 DEBUG (MainThread) [zigpy.zcl] [0x7e77:1:0x0402] Attribute report received: measured_value=2000
2019-10-27 16:14:06 ERROR (Thread-28) [pychromecast.socket_client] [Home All:42872] Error reading from socket.
2019-10-27 16:14:06 WARNING (Thread-28) [pychromecast.socket_client] [Home All:42872] Error communicating with socket, resetting connection
2019-10-27 16:14:08 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-10-27 16:14:08 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'27a021ad0eaf7e'
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'73a0a1adaafd7e'
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'03a0b5ca7daabab859fb4725d27d7e'
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.ezsp] Application frame 98 (incomingSenderEui64Handler) received: b'2980af0a006f0d00'
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.zigbee.application] Received incomingSenderEui64Handler frame with [00:0d:6f:00:0a:af:80:29]
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'13a0b1ed502a15b259944a2dae55927d638e359b12316c771e433582fc1132a7ebcda1f07e'
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'040000000000000804000034ffc01230ffff0bfce385560b006f0d000000'
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, <EmberApsFrame profileId=0 clusterId=0 sourceEndpoint=0 destinationEndpoint=0 options=1032 groupId=0 sequence=52>, 255, -64, 0x3012, 255, 255, b'\xfc\xe3\x85V\x0b\x00o\r\x00\x00\x00']
2019-10-27 16:14:09 DEBUG (MainThread) [zigpy.zdo] [0x3012:zdo] ZDO request 0x0000: [00:0d:6f:00:0b:56:85:e3, 0, 0]
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x3012, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=252>, 249, b'\xfc\x00\xe3\x85V\x0b\x00o\r\x00\x00\x00\x00\x00')
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'32a1219c543825b25994ca25aa1593499cb2dea57d31ce840eabcd63e6f17d5e3fa7ebcdd1497e'
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'24a1a19c54a27bc57e'
2019-10-27 16:14:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2019-10-27 16:14:09 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0088'
2019-10-27 16:14:10 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x153d:1:0x0b04]: async_update
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x153d, <EmberApsFrame profileId=260 clusterId=2820 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=250>, 251, b'\x00\xfa\x00\x0b\x05')
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'43a6219c541700b658904124ab1593499cb4dcaeed346780f88d8a7e'
2019-10-27 16:14:10 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x8d79:1:0x0b04]: async_update
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x8d79, <EmberApsFrame profileId=260 clusterId=2820 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=252>, 253, b'\x00\xfc\x00\x0b\x05')
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'35a6a19c54a353147e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'54a7219c545398b658904124ab1593499cb2daaeed326780f8361c7e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0089'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'46a7a19c54a0425a7e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'008a'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'56a7b1f12da75d4a6a854a4aa7556d899c38417e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 89 (incomingRouteRecordHandler) received: b'798d48f83311006f0d00ffc000'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x8d79, 00:0d:6f:00:11:33:f8:48, 255, -64, []]
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'66a7b1f12da75d4a6a854a4aa7556df69c02257e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'76a7b1ed542e14b652954b65ab5592ac62f11abe12316e9307c7688cfc573fa7deca7e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 89 (incomingRouteRecordHandler) received: b'798d48f83311006f0d00ffbf00'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'06a7b197541700b658904124ab1593499cc7dcabed206a7e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x8d79, 00:0d:6f:00:11:33:f8:48, 255, -65, []]
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401040b010140010000e5febf3d15ffff0918fa010b0500290000'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=2820 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=229>, 254, -65, 0x153d, 255, 255, b'\x18\xfa\x01\x0b\x05\x00)\x00\x00']
2019-10-27 16:14:10 DEBUG (MainThread) [zigpy.zcl] [0x153d:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=250 command_id=Command.Read_Attributes_rsp>
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'003d150401040b01014001000089fb0000'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 5437, <EmberApsFrame profileId=260 clusterId=2820 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=137>, 251, <EmberStatus.SUCCESS: 0>, b'']
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'16a7b1ed542e14b652954b65ab55925163f15e2612316e9301c7688cfc573fa7e93d4f7e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401040b01014001000018ffbf798dffff0918fc010b050029000002'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=2820 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=24>, 255, -65, 0x8d79, 255, 255, b'\x18\xfc\x01\x0b\x05\x00)\x00\x00']
2019-10-27 16:14:10 DEBUG (MainThread) [zigpy.zcl] [0x8d79:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=252 command_id=Command.Read_Attributes_rsp>
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'26a7b197545398b658904124ab1593499cc4daabed358a7e'
2019-10-27 16:14:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00798d0401040b0101400100008afd0000'
2019-10-27 16:14:10 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 36217, <EmberApsFrame profileId=260 clusterId=2820 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=138>, 253, <EmberStatus.SUCCESS: 0>, b'']
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'36a7b1ed542e14b25c954b65ab55927c638f359b12316e82b7c64289fc7e08a713027e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000035ffc11230ffff09094a00210000003700'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=53>, 255, -63, 0x3012, 255, 255, b'\tJ\x00!\x00\x00\x007\x00']
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=74 command_id=Command.Read_Attributes>
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 55]
2019-10-27 16:14:11 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'46a7b1ed542e14b25c954b65ab55927f638f359b12316e82b7c64289fc7e08a75c3d7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000036ffc11230ffff09094a00210000003700'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=54>, 255, -63, 0x3012, 255, 255, b'\tJ\x00!\x00\x00\x007\x00']
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=74 command_id=Command.Read_Attributes>
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 55]
2019-10-27 16:14:11 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] No handler for cluster command 0
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'56a7b197543825b25994ca25aa1593499cc6deabed58fb7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0012300000008000004001000088f90000'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 12306, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=136>, 249, <EmberStatus.SUCCESS: 0>, b'']
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'66a7b5ca7daabab859fb472546817e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 98 (incomingSenderEui64Handler) received: b'2980af0a006f0d00'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingSenderEui64Handler frame with [00:0d:6f:00:0a:af:80:29]
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'76a7b1ed502a15b259944a2dae55927e638f359b12316c761e433582fc1132a7ebcdbde87e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'040000000000000804000037ffc11230ffff0bfde385560b006f0d000000'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, <EmberApsFrame profileId=0 clusterId=0 sourceEndpoint=0 destinationEndpoint=0 options=1032 groupId=0 sequence=55>, 255, -63, 0x3012, 255, 255, b'\xfd\xe3\x85V\x0b\x00o\r\x00\x00\x00']
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zdo] [0x3012:zdo] ZDO request 0x0000: [00:0d:6f:00:0b:56:85:e3, 0, 0]
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x3012, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=253>, 254, b'\xfd\x00\xe3\x85V\x0b\x00o\r\x00\x00\x00\x00\x00')
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'60a4219c543825b25994ca25aa1593499cb3d9a510ce840eabcd63e6f17d5e3fa7ebcd6e6c7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'07a4a19c54a6e3fe7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'008c'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'17a4b197543825b25994ca25aa1593499cc2d9abed87cf7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'001230000000800000400100008cfe0000'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 12306, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=140>, 254, <EmberStatus.SUCCESS: 0>, b'']
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'27a4b5ca7daabab859fb4725e04e7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 98 (incomingSenderEui64Handler) received: b'2980af0a006f0d00'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingSenderEui64Handler frame with [00:0d:6f:00:0a:af:80:29]
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'37a4b1ed502a15b259944a2dae559271638c359b12316c751e433582fc1132a7ebcd19b07e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'040000000000000804000038ffc21230ffff0bfee385560b006f0d000000'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, <EmberApsFrame profileId=0 clusterId=0 sourceEndpoint=0 destinationEndpoint=0 options=1032 groupId=0 sequence=56>, 255, -62, 0x3012, 255, 255, b'\xfe\xe3\x85V\x0b\x00o\r\x00\x00\x00']
2019-10-27 16:14:11 DEBUG (MainThread) [zigpy.zdo] [0x3012:zdo] ZDO request 0x0000: [00:0d:6f:00:0b:56:85:e3, 0, 0]
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x3012, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=254>, 255, b'\xfe\x00\xe3\x85V\x0b\x00o\r\x00\x00\x00\x00\x00')
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'74a5219c543825b25994ca25aa1593499cb0d8a57d33ce840eabcd63e6f17d5e3fa7ebcdf4be7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'40a5a19c54a4cbbc7e'
2019-10-27 16:14:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2019-10-27 16:14:11 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'008e'```
@probot-home-assistant
Copy link

Hey there @dmulcahey, @Adminiuga, mind taking a look at this issue as its been labeled with a integration (zha) you are listed as a codeowner for? Thanks!

@Adminiuga
Copy link
Contributor

I don't see that device ever resetting the state. You can just filter by these lines:

2019-10-27 16:14:02 DEBUG (MainThread) [zigpy.zcl] [0x3012:1:0x0500] ZCL request 0x0100: [33, 0, 0, 20]
2019-10-27 16:14:02 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0x3012:1:0x0500]: Updated alarm state: 1

Updated alarm state is always 1.
Also, the 4th parameter in ZCL request 0x0100: [33, 0, 0, 20] per zcl specs is a delay in 0.25s and if device sends it's correctly that means you received that notification 5s late which could indicate interference or weak network.

Another thing to keep in mind: adding a Zigbee router after a battery operated device was joined, does nothing to the battery operated device in the most cases as it would continue to use its previous parent device as long as it is reachable. You may want to reset it and rejoin to force it to pick a better parent.

@edif30
Copy link
Contributor Author

edif30 commented Nov 1, 2019 •
edited

@Adminiuga How can the sensor still use the same parent if after adding two additional mains and all the sensors RSSI and LQI improved?

According to everything I am seeing the mesh is in great shape.

Is there a way to see the parent of each of the sensors?

@dmulcahey
Copy link
Contributor

dmulcahey commented Nov 1, 2019 •
edited

https://share.icloud.com/photos/0UtDv70xtBfIkFXeMPG55hJHA

I can't reproduce this unless I move at extremely unreasonable speeds where the device itself starts ignoring the state changes.

2019-11-01 07:41:45 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=209>, 255, -52, 0xae49, 255, 255, b'\t\x05\x00 \x00\x00\x00\x00\x00']
2019-11-01 07:41:45 DEBUG (MainThread) [zigpy.zcl] [0xae49:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=5 command_id=0>
2019-11-01 07:41:45 DEBUG (MainThread) [zigpy.zcl] [0xae49:1:0x0500] ZCL request 0x0000: [32, 0, 0, 0]
2019-11-01 07:41:45 DEBUG (MainThread) [zigpy.zcl] [0xae49:1:0x0500] No handler for cluster command 0
2019-11-01 07:41:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xae49:1:0x0500]: Updated alarm state: 0

2019-11-01 07:41:43 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1280 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=203>, 255, -54, 0xae49, 255, 255, b'\t\x02\x00!\x00\x00\x00\x00\x00']
2019-11-01 07:41:43 DEBUG (MainThread) [zigpy.zcl] [0xae49:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=2 command_id=0>
2019-11-01 07:41:43 DEBUG (MainThread) [zigpy.zcl] [0xae49:1:0x0500] ZCL request 0x0000: [33, 0, 0, 0]
2019-11-01 07:41:43 DEBUG (MainThread) [zigpy.zcl] [0xae49:1:0x0500] No handler for cluster command 0
2019-11-01 07:41:43 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xae49:1:0x0500]: Updated alarm state: 1

@Adminiuga
Copy link
Contributor

@edif30 Zigbee end devices pick the parent with the strongest signal at the join time. And won't be looking for a new parent unless current one becomes unavailable and stops responding to data poll requests.
The debug log does show the zone status change was sent with a delay which means the device could not send it previously and kept re-trying.
Compare it to dmulcahey's log, where the delay is 0.

@edif30
Copy link
Contributor Author

edif30 commented Nov 1, 2019

Looks like I will be repairing a few of these :). Will do that and report back. I also moved the magnet a bit closer. It wasn't very far but just in case that was an issue I moved it about a 1/4 inch closer.

@dmulcahey
Copy link
Contributor

@edif30 are we good here? As I said, I can't reproduce this at all.

@edif30
Copy link
Contributor Author

edif30 commented Nov 19, 2019

Yes. I believe I may have some bad sensors. All others are working fine. I will close. Thanks!

@edif30 edif30 closed this as completed Nov 19, 2019
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@dmulcahey dmulcahey

@Adminiuga Adminiuga

Labels
integration: zha
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@dmulcahey @Adminiuga @edif30