Skip to content

Instantly share code, notes, and snippets.

@iantrich

iantrich/closet.md

Last active August 10, 2020 19:51
Show Gist options
  • Save iantrich/060707ee5c311da4407fd9453f7385c4 to your computer and use it in GitHub Desktop.
Save iantrich/060707ee5c311da4407fd9453f7385c4 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
closet.md 
binary_sensor.primary_closet_motion: 0xf10e, 3326-L by CentraLite
light.primary_closet: 0xb63f, E11-G13 by sengled

Took maybe 4-5 seconds I want to say to turn on

motion on: 23:43:28.675
motion event: 23:43:28.677
light on call: 23:43:28.694
toggle off:23:43:35.739

i don't see anything showing the delay...? i then toggled off right after it turned on and it was off instantly

2020-08-09 23:43:00.109 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'46c7b1ed542e14b75d954b65ab55927b638e358a12316f838bcc6389dd292df7a67e'
2020-08-09 23:43:00.110 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:00.111 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401050401014001000032ffc01221ffff0808760a0000215712'
2020-08-09 23:43:00.111 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=50>, 255, -64, 0x2112, 255, 255, b'\x08v\n\x00\x00!W\x12']
2020-08-09 23:43:00.111 DEBUG (MainThread) [zigpy.zcl] [0x2112:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=118 command_id=Command.Report_Attributes>
2020-08-09 23:43:00.111 DEBUG (MainThread) [zigpy.zcl] [0x2112:1:0x0405] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint16_t, value=4695>>]]
2020-08-09 23:43:00.112 DEBUG (MainThread) [zigpy.zcl] [0x2112:1:0x0405] Attribute report received: measured_value=4695
2020-08-09 23:43:00.124 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.girls_bath_humidity, old_state=<state sensor.girls_bath_humidity=47.5; unit_of_measurement=%, friendly_name=Girl's Bath Humidity, device_class=humidity @ 2020-08-09T23:41:59.469236-05:00>, new_state=<state sensor.girls_bath_humidity=47.0; unit_of_measurement=%, friendly_name=Girl's Bath Humidity, device_class=humidity @ 2020-08-09T23:43:00.124721-05:00>>
2020-08-09 23:43:00.124 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x2112, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=118>, 186, b'\x18v\x0b\n\x00')
2020-08-09 23:43:00.127 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'65c4219c543834b658914e24ab1593499c389daef5b86c81fd4afd7e'
2020-08-09 23:43:00.137 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'57c4a19c54137dc87e'
2020-08-09 23:43:00.137 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:00.140 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0039'
2020-08-09 23:43:02.159 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'67c4b197543834b658914e24ab1593499c779dabedf2677e'
2020-08-09 23:43:02.160 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:02.161 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0012210401050401014001000039ba0000'
2020-08-09 23:43:02.161 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 8466, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=57>, 186, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:04.293 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'77c4b1ed542e14b459954b65ab559217638edc2312316093d4cc6389ec7efb1b7e'
2020-08-09 23:43:04.294 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:04.295 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040106000101400100005effc0fb88ffff0718290a00001000'
2020-08-09 23:43:04.295 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=94>, 255, -64, 0x88fb, 255, 255, b'\x18)\n\x00\x00\x10\x00']
2020-08-09 23:43:04.295 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=41 command_id=Command.Report_Attributes>
2020-08-09 23:43:04.296 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.false>>]]
2020-08-09 23:43:04.296 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] Attribute report received: on_off=0
2020-08-09 23:43:05.126 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'07c4b1ed542e14b25c954b65ab5592d448f940bc12316e82abc64589fc7e3fa77e587e'
2020-08-09 23:43:05.127 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:05.128 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100009dd4b76717ffff09095600260000000000'
2020-08-09 23:43:05.128 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=157>, 212, -73, 0x1767, 255, 255, b'\tV\x00&\x00\x00\x00\x00\x00']
2020-08-09 23:43:05.128 DEBUG (MainThread) [zigpy.zcl] [0x1767:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=86 command_id=0>
2020-08-09 23:43:05.129 DEBUG (MainThread) [zigpy.zcl] [0x1767:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper|Alarm_2: 38>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:05.129 DEBUG (MainThread) [zigpy.zcl] [0x1767:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:05.129 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1767:1:0x0500]: Updated alarm state: ZoneStatus.Alarm_2
2020-08-09 23:43:05.129 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.living_room_motion_front, old_state=<state binary_sensor.living_room_motion_front=off; friendly_name=Living Room Motion Front, device_class=motion @ 2020-08-09T21:41:05.676117-05:00>, new_state=<state binary_sensor.living_room_motion_front=on; friendly_name=Living Room Motion Front, device_class=motion @ 2020-08-09T23:43:05.129772-05:00>>
2020-08-09 23:43:05.145 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=brightness_pct=1, entity_id=light.living_room_front>
2020-08-09 23:43:05.146 DEBUG (MainThread) [bellows.ezsp] Send command sendMulticast: (<EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=256 groupId=7 sequence=187>, 0, 3, 188, b'\x01\xbb\x04\x03\x00\x00')
2020-08-09 23:43:05.147 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'71c52190502b1db258954a24ad5529499ff221aa56ca648bfd14a57e'
2020-08-09 23:43:05.156 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'10c5a190541030ca7e'
2020-08-09 23:43:05.156 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:05.157 DEBUG (MainThread) [bellows.ezsp] Application frame 56 (sendMulticast) received: b'003a'
2020-08-09 23:43:05.304 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'20c5b1ed542e14b459954b65ab55927a67fd763f12316093aecc6389ec7f98c07e'
2020-08-09 23:43:05.304 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:05.306 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401060001014001000033fbb35194ffff0718530a00001001'
2020-08-09 23:43:05.307 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=51>, 251, -77, 0x9451, 255, 255, b'\x18S\n\x00\x00\x10\x01']
2020-08-09 23:43:05.307 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=83 command_id=Command.Report_Attributes>
2020-08-09 23:43:05.307 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:05.307 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:05.324 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'30c5b1ed542e14b05d954b65ab5592ac638e840c12316f83efcc6389d59438a10fb07e'
2020-08-09 23:43:05.324 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:05.351 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010204010140010000e5ffc0a3a7ffff0808120a000029ea0706'
2020-08-09 23:43:05.352 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=229>, 255, -64, 0xa7a3, 255, 255, b'\x08\x12\n\x00\x00)\xea\x07']
2020-08-09 23:43:05.352 DEBUG (MainThread) [zigpy.zcl] [0xa7a3:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=18 command_id=Command.Report_Attributes>
2020-08-09 23:43:05.352 DEBUG (MainThread) [zigpy.zcl] [0xa7a3:1:0x0402] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=int16s, value=2026>>]]
2020-08-09 23:43:05.352 DEBUG (MainThread) [zigpy.zcl] [0xa7a3:1:0x0402] Attribute report received: measured_value=2026
2020-08-09 23:43:05.358 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'40c5b1ed542e14b459954b65ab5592fd61fa036212316093f7cc6389ec7fa9667e'
2020-08-09 23:43:05.358 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:05.386 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50c5b1ed542e14b459954b65ab5592bb63f7f59212316093cacc6389ec7f0f7a7e'
2020-08-09 23:43:05.386 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:05.396 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.living_room_front_middle, old_state=<state light.living_room_front_middle=off; off_brightness=None, friendly_name=Living Room Front Middle, supported_features=41 @ 2020-08-09T21:50:40.206538-05:00>, new_state=<state light.living_room_front_middle=on; brightness=3, off_brightness=None, friendly_name=Living Room Front Middle, supported_features=41 @ 2020-08-09T23:43:05.396927-05:00>>
2020-08-09 23:43:05.397 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000b4fdb424c9ffff07180a0a00001001'
2020-08-09 23:43:05.397 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=180>, 253, -76, 0xc924, 255, 255, b'\x18\n\n\x00\x00\x10\x01']
2020-08-09 23:43:05.397 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=10 command_id=Command.Report_Attributes>
2020-08-09 23:43:05.397 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:05.397 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:05.445 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000f2ffb9d239ffff0718370a00001001'
2020-08-09 23:43:05.445 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=242>, 255, -71, 0x39d2, 255, 255, b'\x187\n\x00\x00\x10\x01']
2020-08-09 23:43:05.445 DEBUG (MainThread) [zigpy.zcl] [0x39d2:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=55 command_id=Command.Report_Attributes>
2020-08-09 23:43:05.445 DEBUG (MainThread) [zigpy.zcl] [0x39d2:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:05.445 DEBUG (MainThread) [zigpy.zcl] [0x39d2:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:05.509 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.middle_room_motion_temperature, old_state=<state sensor.middle_room_motion_temperature=68.4; unit_of_measurement=°F, friendly_name=Middle Room Motion Temperature, device_class=temperature @ 2020-08-09T23:28:07.271055-05:00>, new_state=<state sensor.middle_room_motion_temperature=68.5; unit_of_measurement=°F, friendly_name=Middle Room Motion Temperature, device_class=temperature @ 2020-08-09T23:43:05.509860-05:00>>
2020-08-09 23:43:05.510 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xa7a3, <EmberApsFrame profileId=260 clusterId=1026 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=18>, 189, b'\x18\x12\x0b\n\x00')
2020-08-09 23:43:05.514 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'06ca219c5489b2b658964e24ab1593499c5c9aaef5dc6c81fd32d37e'
2020-08-09 23:43:05.515 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.living_room_front_right, old_state=<state light.living_room_front_right=off; off_brightness=None, friendly_name=Living Room Front Right, supported_features=41 @ 2020-08-09T21:50:40.177647-05:00>, new_state=<state light.living_room_front_right=on; brightness=3, off_brightness=None, friendly_name=Living Room Front Right, supported_features=41 @ 2020-08-09T23:43:05.515307-05:00>>
2020-08-09 23:43:05.515 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.living_room_front_left, old_state=<state light.living_room_front_left=off; off_brightness=None, friendly_name=Living Room Front Left, supported_features=41 @ 2020-08-09T21:50:40.018781-05:00>, new_state=<state light.living_room_front_left=on; brightness=3, off_brightness=None, friendly_name=Living Room Front Left, supported_features=41 @ 2020-08-09T23:43:05.515501-05:00>>
2020-08-09 23:43:05.515 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.living_room, old_state=<state light.living_room=off; off_brightness=None, friendly_name=Living Room, icon=mdi:sofa, supported_features=41 @ 2020-08-09T21:50:40.207268-05:00>, new_state=<state light.living_room=on; brightness=3, off_brightness=None, friendly_name=Living Room, icon=mdi:sofa, supported_features=41 @ 2020-08-09T23:43:05.515857-05:00>>
2020-08-09 23:43:05.516 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.living_room_front, old_state=<state light.living_room_front=off; off_brightness=None, friendly_name=Living Room Front, supported_features=41 @ 2020-08-09T21:50:40.207445-05:00>, new_state=<state light.living_room_front=on; brightness=3, off_brightness=None, friendly_name=Living Room Front, supported_features=41 @ 2020-08-09T23:43:05.516057-05:00>>
2020-08-09 23:43:05.546 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'61caa19c5411304f7e'
2020-08-09 23:43:05.546 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:05.602 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'003b'
2020-08-09 23:43:05.704 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lights, old_state=<state light.lights=on; min_mireds=153, max_mireds=588, effect_list=['Facebook', 'Christmas', 'Stop', 'Strobe color', 'LSD', 'WhatsApp', 'Police', 'Random Loop', 'Strobe epilepsy!', 'Disco', 'Alarm', 'Twitter', 'Slow Temp', 'Fast Random Loop', 'Slowdown', 'RGB', 'Police2'], brightness=79, color_temp=230, hs_color=(0.0, 50.0), rgb_color=(255, 127, 127), xy_color=(0.526, 0.313), entity_id=['light.family_room', 'light.garage', 'light.landing', 'light.main_bath', 'light.office', 'light.kitchen', 'light.kitchen_cabinets', 'light.living_room', 'light.living_room_front_entrance', 'light.living_room_lamp', 'light.dining_room', 'light.sunroom', 'light.hallway', 'light.hallway_stairs', 'light.girls_bath', 'light.girls_room', 'light.master', 'light.master_bath', 'light.master_closet'], friendly_name=Lights, supported_features=63 @ 2020-08-09T07:19:43.075575-05:00>, new_state=<state light.lights=on; min_mireds=153, max_mireds=588, effect_list=['Facebook', 'Christmas', 'Stop', 'Strobe color', 'LSD', 'WhatsApp', 'Police', 'Random Loop', 'Strobe epilepsy!', 'Disco', 'Alarm', 'Twitter', 'Slow Temp', 'Fast Random Loop', 'Slowdown', 'RGB', 'Police2'], brightness=68, color_temp=230, hs_color=(0.0, 50.0), rgb_color=(255, 127, 127), xy_color=(0.526, 0.313), entity_id=['light.family_room', 'light.garage', 'light.landing', 'light.main_bath', 'light.office', 'light.kitchen', 'light.kitchen_cabinets', 'light.living_room', 'light.living_room_front_entrance', 'light.living_room_lamp', 'light.dining_room', 'light.sunroom', 'light.hallway', 'light.hallway_stairs', 'light.girls_bath', 'light.girls_room', 'light.master', 'light.master_bath', 'light.master_closet'], friendly_name=Lights, supported_features=63 @ 2020-08-09T07:19:43.075575-05:00>>
2020-08-09 23:43:06.083 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'71cab19757d7eab6589c4a245559934e9c749babedce0b7e'
2020-08-09 23:43:06.083 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:06.094 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'03fdff0401080001ff0c0107003abc0000'
2020-08-09 23:43:06.095 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_MULTICAST: 3>, 65533, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=255 options=268 groupId=7 sequence=58>, 188, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:06.100 DEBUG (MainThread) [bellows.ezsp] Send command sendMulticast: (<EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=256 groupId=7 sequence=190>, 0, 3, 191, b'\x01\xbe\x01')
2020-08-09 23:43:06.115 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'10cb2190502b7d33b258954a24ad552c499ff124aa53cf6b3f7e'
2020-08-09 23:43:06.126 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'02cba19054160e607e'
2020-08-09 23:43:06.126 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:06.167 DEBUG (MainThread) [bellows.ezsp] Application frame 56 (sendMulticast) received: b'003c'
2020-08-09 23:43:06.211 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2020-08-09 23:43:06.222 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'21c821ad1bfc7e'
2020-08-09 23:43:06.227 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'13c8a1adc1e57e'
2020-08-09 23:43:06.227 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:06.244 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2020-08-09 23:43:06.767 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'23c8b1ed542e14b459954b65ab5592fd63f6036212316093f7cc6389ec7feafb7e'
2020-08-09 23:43:06.767 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:06.778 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000b4ffb824c9ffff07180a0a00001001'
2020-08-09 23:43:06.778 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=180>, 255, -72, 0xc924, 255, 255, b'\x18\n\n\x00\x00\x10\x01']
2020-08-09 23:43:06.779 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=10 command_id=Command.Report_Attributes>
2020-08-09 23:43:06.779 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:06.779 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:06.785 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33c8b1ed542e14b459954b65ab55927a63f6763f12316093aecc6389ec7fdf817e'
2020-08-09 23:43:06.785 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:06.801 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401060001014001000033ffb85194ffff0718530a00001001'
2020-08-09 23:43:06.801 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=51>, 255, -72, 0x9451, 255, 255, b'\x18S\n\x00\x00\x10\x01']
2020-08-09 23:43:06.801 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=83 command_id=Command.Report_Attributes>
2020-08-09 23:43:06.801 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:06.801 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:07.083 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'43c8b19757d7eab658924a245559934e9c7298abedf3897e'
2020-08-09 23:43:07.083 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:07.094 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'03fdff0401060001ff0c0107003cbf0000'
2020-08-09 23:43:07.094 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_MULTICAST: 3>, 65533, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=255 options=268 groupId=7 sequence=60>, 191, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:07.095 DEBUG (MainThread) [homeassistant.components.zha.entity] light.living_room_front: turned on: {'move_to_level_with_on_off': [4, <Status.SUCCESS: 0>], 'on_off': [1, <Status.SUCCESS: 0>]}
2020-08-09 23:43:07.096 DEBUG (MainThread) [homeassistant.components.zha.entity] light.living_room_front_left: polling current state - from cache: False
2020-08-09 23:43:07.096 DEBUG (MainThread) [zigpy.device] [0x39d2] Extending timeout for 0xc0 request
2020-08-09 23:43:07.096 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2e:de:6e/0x39d2
2020-08-09 23:43:07.096 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2e:de:6e, True)
2020-08-09 23:43:07.096 DEBUG (MainThread) [homeassistant.components.zha.entity] light.living_room_front_middle: polling current state - from cache: False
2020-08-09 23:43:07.096 DEBUG (MainThread) [zigpy.device] [0x9451] Extending timeout for 0xc2 request
2020-08-09 23:43:07.097 DEBUG (MainThread) [homeassistant.components.zha.entity] light.living_room_front_right: polling current state - from cache: False
2020-08-09 23:43:07.097 DEBUG (MainThread) [zigpy.device] [0xc924] Extending timeout for 0xc4 request
2020-08-09 23:43:07.112 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'35c921d63af43bb14d8c8495ab6cdf7e'
2020-08-09 23:43:07.124 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'54c9a1d606987e'
2020-08-09 23:43:07.124 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:07.150 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:07.161 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x39d2, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=192>, 193, b'\x00\xc0\x00\x00\x00')
2020-08-09 23:43:07.186 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'46ce219c54f82cb658924a24ab1593499c8ee6aeed0e678bfd4a417e'
2020-08-09 23:43:07.204 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'65cea19c5417df2e7e'
2020-08-09 23:43:07.204 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:07.215 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'003d'
2020-08-09 23:43:07.225 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2f:14:66/0x9451
2020-08-09 23:43:07.225 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2f:14:66, True)
2020-08-09 23:43:07.236 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'57cf21d6323e3ab14d8c8495ab912c7e'
2020-08-09 23:43:07.237 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'75ceb1ed542e14b459954b65ab5592ba63f6f59212316f933dc76389fc6e3e931a7e'
2020-08-09 23:43:07.237 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:07.251 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'05ceb19754f82cb658924a24ab1593499c73e6abedd2fe7e'
2020-08-09 23:43:07.251 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:07.252 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000f3ffb8d239ffff0818c0010000001001'
2020-08-09 23:43:07.252 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=243>, 255, -72, 0x39d2, 255, 255, b'\x18\xc0\x01\x00\x00\x00\x10\x01']
2020-08-09 23:43:07.253 DEBUG (MainThread) [zigpy.zcl] [0x39d2:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=192 command_id=Command.Read_Attributes_rsp>
2020-08-09 23:43:07.253 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00d239040106000101400100003dc10000'
2020-08-09 23:43:07.253 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 14802, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=61>, 193, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:07.264 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'15ceb1975489b2b658964e24ab1593499c759aabed20f47e'
2020-08-09 23:43:07.264 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:07.264 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'26cfa1d61b257e'
2020-08-09 23:43:07.264 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:07.288 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'36cfb1ed542e14b25c954b65ab5592dc638e3ba112316e8293c64589fc7e3fa763437e'
2020-08-09 23:43:07.288 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:07.289 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00a3a7040102040101400100003bbd0000'
2020-08-09 23:43:07.289 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 42915, <EmberApsFrame profileId=260 clusterId=1026 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=59>, 189, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:07.289 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:07.289 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000095ffc01c0affff09096e00260000000000'
2020-08-09 23:43:07.289 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=149>, 255, -64, 0x0a1c, 255, 255, b'\tn\x00&\x00\x00\x00\x00\x00']
2020-08-09 23:43:07.290 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=110 command_id=0>
2020-08-09 23:43:07.290 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper|Alarm_2: 38>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:07.290 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:07.290 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0a1c:1:0x0500]: Updated alarm state: ZoneStatus.Alarm_2
2020-08-09 23:43:07.291 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.hallway_motion, old_state=<state binary_sensor.hallway_motion=off; friendly_name=Hallway Motion, device_class=motion @ 2020-08-09T21:40:42.630910-05:00>, new_state=<state binary_sensor.hallway_motion=on; friendly_name=Hallway Motion, device_class=motion @ 2020-08-09T23:43:07.291375-05:00>>
2020-08-09 23:43:07.312 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9451, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=194>, 195, b'\x00\xc2\x00\x00\x00')
2020-08-09 23:43:07.345 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'64cc219c547b81b658924a24ab1593499c8ce4aeed0c678bfd5ae97e'
2020-08-09 23:43:07.362 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'47cca19c541415867e'
2020-08-09 23:43:07.362 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:07.406 DEBUG (MainThread) [zigpy.device] [0x39d2] Extending timeout for 0xc6 request
2020-08-09 23:43:07.442 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'57ccb1ed542e14b459954b65ab55927d62f7763f12316f933fc76389fc6e3eb8d27e'
2020-08-09 23:43:07.442 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:07.459 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'67ccb197547b81b658924a24ab1593499c70e4abedda157e'
2020-08-09 23:43:07.459 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:07.459 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'003e'
2020-08-09 23:43:07.459 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401060001014001000034feb95194ffff0818c2010000001001'
2020-08-09 23:43:07.460 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=52>, 254, -71, 0x9451, 255, 255, b'\x18\xc2\x01\x00\x00\x00\x10\x01']
2020-08-09 23:43:07.460 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=194 command_id=Command.Read_Attributes_rsp>
2020-08-09 23:43:07.460 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'005194040106000101400100003ec30000'
2020-08-09 23:43:07.460 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37969, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=62>, 195, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:07.516 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=brightness_pct=1, color_name=red, entity_id=light.hallway_stairs, light.hallway>
2020-08-09 23:43:07.556 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2e:d7:77/0xc924
2020-08-09 23:43:07.556 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2e:d7:77, True)
2020-08-09 23:43:07.558 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'77cd21d623fd3bb14d8c8495ab79367e'
2020-08-09 23:43:07.601 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'70cda1d627e77e'
2020-08-09 23:43:07.601 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:07.629 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:07.642 DEBUG (MainThread) [zigpy.device] [0x88fb] Extending timeout for 0xc8 request
2020-08-09 23:43:07.670 DEBUG (MainThread) [zigpy.device] [0x9451] Extending timeout for 0xca request
2020-08-09 23:43:07.670 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xc924, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=196>, 197, b'\x00\xc4\x00\x00\x00')
2020-08-09 23:43:07.677 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'00d2219c540edcb658924a24ab1593499c8ae2aeed0a678bfd4c677e'
2020-08-09 23:43:07.716 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01d2a19c541529a47e'
2020-08-09 23:43:07.716 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:07.727 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'003f'
2020-08-09 23:43:07.764 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2e:de:6e/0x39d2
2020-08-09 23:43:07.764 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2e:de:6e, True)
2020-08-09 23:43:07.770 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d31d321d63af43bb14d8c8495ab6e087e'
2020-08-09 23:43:07.785 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12d3a1d6cb3f7e'
2020-08-09 23:43:07.785 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:07.803 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:07.806 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x39d2, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=198>, 199, b'\x00\xc6\x00\x00\x00')
2020-08-09 23:43:07.812 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'22d0219c54f82cb6589c4a24ab1593499c88e0aeed08678bfd99017e'
2020-08-09 23:43:07.842 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'23d0a19c546a5c177e'
2020-08-09 23:43:07.843 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:07.877 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0040'
2020-08-09 23:43:07.883 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2f:4b:f5/0x88fb
2020-08-09 23:43:07.883 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2f:4b:f5, True)
2020-08-09 23:43:07.886 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33d0b1ed542e14ba59954b65ab5592bd63f6f59212316f933bc76389fc5e3c00da7e'
2020-08-09 23:43:07.886 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:07.886 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'43d0b19754f82cb6589c4a24ab1593499c0ee0abeda77b7e'
2020-08-09 23:43:07.886 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:07.887 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'53d0b1ed542e14ba59954b65ab55925862fafe1912316093facc6389dc80f7367e'
2020-08-09 23:43:07.887 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:07.887 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'36d121d6a1613ab14d8c8495ab579f7e'
2020-08-09 23:43:07.898 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'64d1a1d6c0b37e'
2020-08-09 23:43:07.898 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:07.899 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010800010140010000f4ffb8d239ffff0818c6010000002003'
2020-08-09 23:43:07.899 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=244>, 255, -72, 0x39d2, 255, 255, b'\x18\xc6\x01\x00\x00\x00 \x03']
2020-08-09 23:43:07.899 DEBUG (MainThread) [zigpy.zcl] [0x39d2:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=198 command_id=Command.Read_Attributes_rsp>
2020-08-09 23:43:07.899 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00d2390401080001014001000040c70000'
2020-08-09 23:43:07.899 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 14802, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=64>, 199, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:07.899 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401080001014001000011feb4d9b2ffff0718070a000020fe'
2020-08-09 23:43:07.899 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=17>, 254, -76, 0xb2d9, 255, 255, b'\x18\x07\n\x00\x00 \xfe']
2020-08-09 23:43:07.900 DEBUG (MainThread) [zigpy.zcl] [0xb2d9:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=7 command_id=Command.Report_Attributes>
2020-08-09 23:43:07.900 DEBUG (MainThread) [zigpy.zcl] [0xb2d9:1:0x0008] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint8_t, value=254>>]]
2020-08-09 23:43:07.900 DEBUG (MainThread) [zigpy.zcl] [0xb2d9:1:0x0008] Attribute report received: current_level=254
2020-08-09 23:43:07.929 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xb2d9:1:0x0008]: received attribute: 0 update with value: 254
2020-08-09 23:43:07.929 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:07.930 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x88fb, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=200>, 201, b'\x01\xc8\x04\x03\x00\x00')
2020-08-09 23:43:07.935 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'47d6219c54d19db6589c4a24ab1593499c86eeadec066388fdc680127e'
2020-08-09 23:43:07.946 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'75d6a19c546b7cc67e'
2020-08-09 23:43:07.946 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:07.978 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x39d2:1:0x0008]: received attribute: 0 update with value: 3
2020-08-09 23:43:07.978 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0041'
2020-08-09 23:43:07.982 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2f:14:66/0x9451
2020-08-09 23:43:07.982 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2f:14:66, True)
2020-08-09 23:43:07.993 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'05d6b1ed542e14ba59954b65ab559216638fdc231231628335cd6789064a7e'
2020-08-09 23:43:07.993 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:07.993 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'51d721d6323e3ab14d8c8495ab32bf7e'
2020-08-09 23:43:07.999 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040108000101400100005fffc1fb88ffff0508c80b0400'
2020-08-09 23:43:07.999 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=95>, 255, -63, 0x88fb, 255, 255, b'\x08\xc8\x0b\x04\x00']
2020-08-09 23:43:07.999 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=200 command_id=Command.Default_Response>
2020-08-09 23:43:08.000 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'15d6b19754d19db6589c4a24ab1593499c0feeabede86c7e'
2020-08-09 23:43:08.000 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:08.001 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00fb880401080001014001000041c90000'
2020-08-09 23:43:08.001 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 35067, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=65>, 201, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:08.001 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x88fb:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(3, 0)' kwargs: '{}' result: [4, <Status.SUCCESS: 0>]
2020-08-09 23:43:08.001 DEBUG (MainThread) [zigpy.device] [0x88fb] Extending timeout for 0xcc request
2020-08-09 23:43:08.013 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'25d6b1ed542e14b459954b65ab559229638edc2312316093d7cc6389ec7fed447e'
2020-08-09 23:43:08.013 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:08.014 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'36d7a1d6ea407e'
2020-08-09 23:43:08.014 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:08.030 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401060001014001000060ffc0fb88ffff07182a0a00001001'
2020-08-09 23:43:08.030 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=96>, 255, -64, 0x88fb, 255, 255, b'\x18*\n\x00\x00\x10\x01']
2020-08-09 23:43:08.030 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=42 command_id=Command.Report_Attributes>
2020-08-09 23:43:08.030 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:08.030 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:08.083 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:08.089 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.hallway_stairs, old_state=<state light.hallway_stairs=off; off_brightness=None, friendly_name=Hallway Stairs, supported_features=41 @ 2020-08-09T21:42:42.707780-05:00>, new_state=<state light.hallway_stairs=on; brightness=3, off_brightness=None, friendly_name=Hallway Stairs, supported_features=41 @ 2020-08-09T23:43:08.089247-05:00>>
2020-08-09 23:43:08.089 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9451, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=202>, 203, b'\x00\xca\x00\x00\x00')
2020-08-09 23:43:08.095 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.lights, old_state=<state light.lights=on; min_mireds=153, max_mireds=588, effect_list=['Facebook', 'Christmas', 'Stop', 'Strobe color', 'LSD', 'WhatsApp', 'Police', 'Random Loop', 'Strobe epilepsy!', 'Disco', 'Alarm', 'Twitter', 'Slow Temp', 'Fast Random Loop', 'Slowdown', 'RGB', 'Police2'], brightness=68, color_temp=230, hs_color=(0.0, 50.0), rgb_color=(255, 127, 127), xy_color=(0.526, 0.313), entity_id=['light.family_room', 'light.garage', 'light.landing', 'light.main_bath', 'light.office', 'light.kitchen', 'light.kitchen_cabinets', 'light.living_room', 'light.living_room_front_entrance', 'light.living_room_lamp', 'light.dining_room', 'light.sunroom', 'light.hallway', 'light.hallway_stairs', 'light.girls_bath', 'light.girls_room', 'light.master', 'light.master_bath', 'light.master_closet'], friendly_name=Lights, supported_features=63 @ 2020-08-09T07:19:43.075575-05:00>, new_state=<state light.lights=on; min_mireds=153, max_mireds=588, effect_list=['Facebook', 'Christmas', 'Stop', 'Strobe color', 'LSD', 'WhatsApp', 'Police', 'Random Loop', 'Strobe epilepsy!', 'Disco', 'Alarm', 'Twitter', 'Slow Temp', 'Fast Random Loop', 'Slowdown', 'RGB', 'Police2'], brightness=60, color_temp=230, hs_color=(0.0, 50.0), rgb_color=(255, 127, 127), xy_color=(0.526, 0.313), entity_id=['light.family_room', 'light.garage', 'light.landing', 'light.main_bath', 'light.office', 'light.kitchen', 'light.kitchen_cabinets', 'light.living_room', 'light.living_room_front_entrance', 'light.living_room_lamp', 'light.dining_room', 'light.sunroom', 'light.hallway', 'light.hallway_stairs', 'light.girls_bath', 'light.girls_room', 'light.master', 'light.master_bath', 'light.master_closet'], friendly_name=Lights, supported_features=63 @ 2020-08-09T07:19:43.075575-05:00>>
2020-08-09 23:43:08.102 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'64d4219c547b81b6589c4a24ab1593499c84ecaeed04678bfd6b097e'
2020-08-09 23:43:08.123 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'47d4a19c5468acea7e'
2020-08-09 23:43:08.123 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:08.148 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0042'
2020-08-09 23:43:08.193 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2f:4b:f5/0x88fb
2020-08-09 23:43:08.194 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2f:4b:f5, True)
2020-08-09 23:43:08.195 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'75d521d6a1613ab14d8c8495abcf3e7e'
2020-08-09 23:43:08.200 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'57d4b1ed542e14ba59954b65ab55927c64f7763f12316f9337c76389fc5e3cb3dc7e'
2020-08-09 23:43:08.200 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:08.206 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401080001014001000035f8b95194ffff0818ca010000002003'
2020-08-09 23:43:08.206 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=53>, 248, -71, 0x9451, 255, 255, b'\x18\xca\x01\x00\x00\x00 \x03']
2020-08-09 23:43:08.206 DEBUG (MainThread) [zigpy.zcl] [0x9451:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=202 command_id=Command.Read_Attributes_rsp>
2020-08-09 23:43:08.212 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'60d5a1d6d6827e'
2020-08-09 23:43:08.212 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:08.218 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:08.224 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x88fb, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=204>, 205, b'\x01\xcc\x01')
2020-08-09 23:43:08.229 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'70d5b197547b81b6589c4a24ab1593499c0cecabed0a717e'
2020-08-09 23:43:08.229 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:08.246 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'00da219c54d19db658924a24ab1593499c82eaa8ec026672697e'
2020-08-09 23:43:08.251 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0051940401080001014001000042cb0000'
2020-08-09 23:43:08.251 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 37969, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=66>, 203, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:08.257 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01daa19c546994927e'
2020-08-09 23:43:08.257 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:08.269 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0043'
2020-08-09 23:43:08.297 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'11dab1ed542e14b459954b65ab559228638edc231231628331cd628942107e'
2020-08-09 23:43:08.298 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:08.298 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'21dab19754d19db658924a24ab1593499c0deaabed46ea7e'
2020-08-09 23:43:08.298 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:08.321 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9451:1:0x0008]: received attribute: 0 update with value: 3
2020-08-09 23:43:08.321 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401060001014001000061ffc0fb88ffff0508cc0b0100'
2020-08-09 23:43:08.321 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=97>, 255, -64, 0x88fb, 255, 255, b'\x08\xcc\x0b\x01\x00']
2020-08-09 23:43:08.322 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=204 command_id=Command.Default_Response>
2020-08-09 23:43:08.322 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00fb880401060001014001000043cd0000'
2020-08-09 23:43:08.322 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 35067, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=67>, 205, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:08.323 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x88fb:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: [1, <Status.SUCCESS: 0>]
2020-08-09 23:43:08.323 DEBUG (MainThread) [homeassistant.components.zha.entity] light.hallway_stairs: turned on: {'move_to_level_with_on_off': [4, <Status.SUCCESS: 0>], 'on_off': [1, <Status.SUCCESS: 0>]}
2020-08-09 23:43:08.336 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'31dab1ed542e14b459954b65ab5592fd63f7036212316093f7cc6389ec7f22347e'
2020-08-09 23:43:08.336 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:08.338 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000b4ffb924c9ffff07180a0a00001001'
2020-08-09 23:43:08.338 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=180>, 255, -71, 0xc924, 255, 255, b'\x18\n\n\x00\x00\x10\x01']
2020-08-09 23:43:08.338 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=10 command_id=Command.Report_Attributes>
2020-08-09 23:43:08.338 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:08.338 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:09.443 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'41dab1ed542e14ba59954b65ab55925864fcfe1912316093facc6389dc80f6ce7e'
2020-08-09 23:43:09.443 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:09.445 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401080001014001000011f8b2d9b2ffff0718070a000020fe'
2020-08-09 23:43:09.445 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=17>, 248, -78, 0xb2d9, 255, 255, b'\x18\x07\n\x00\x00 \xfe']
2020-08-09 23:43:09.445 DEBUG (MainThread) [zigpy.zcl] [0xb2d9:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=7 command_id=Command.Report_Attributes>
2020-08-09 23:43:09.445 DEBUG (MainThread) [zigpy.zcl] [0xb2d9:1:0x0008] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint8_t, value=254>>]]
2020-08-09 23:43:09.445 DEBUG (MainThread) [zigpy.zcl] [0xb2d9:1:0x0008] Attribute report received: current_level=254
2020-08-09 23:43:09.457 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xb2d9:1:0x0008]: received attribute: 0 update with value: 254
2020-08-09 23:43:09.695 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'51dab1ed542e14b25c954b65ab5592dc638e3ba112316e8293c64589fc7e3fa7b23b7e'
2020-08-09 23:43:09.696 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:09.696 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000095ffc01c0affff09096e00260000000000'
2020-08-09 23:43:09.697 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=149>, 255, -64, 0x0a1c, 255, 255, b'\tn\x00&\x00\x00\x00\x00\x00']
2020-08-09 23:43:09.697 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=110 command_id=0>
2020-08-09 23:43:09.697 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper|Alarm_2: 38>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:09.697 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:09.698 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0a1c:1:0x0500]: Updated alarm state: ZoneStatus.Alarm_2
2020-08-09 23:43:09.920 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'61dab5ca23fd3bb14d8c849529907e'
2020-08-09 23:43:09.920 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:09.921 DEBUG (MainThread) [bellows.ezsp] Application frame 98 (incomingSenderEui64Handler) received: b'77d72e031418ceb0'
2020-08-09 23:43:09.921 DEBUG (MainThread) [bellows.zigbee.application] Received incomingSenderEui64Handler frame with [b0:ce:18:14:03:2e:d7:77]
2020-08-09 23:43:09.927 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'71dab1ed502a15b259944a2dae5592fff3e2036212316c55acb79c83fc1132a7ebcd89d27e'
2020-08-09 23:43:09.927 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:09.928 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0400000000000008040000b66fac24c9ffff0bde5171ff0a006f0d000000'
2020-08-09 23:43:09.928 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=182>, 111, -84, 0xc924, 255, 255, b'\xdeQq\xff\n\x00o\r\x00\x00\x00']
2020-08-09 23:43:09.928 DEBUG (MainThread) [zigpy.zdo] [0xc924:zdo] ZDO request ZDOCmd.NWK_addr_req: [00:0d:6f:00:0a:ff:71:51, 0, 0]
2020-08-09 23:43:09.929 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xc924, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=222>, 206, b'\xde\x00Qq\xff\n\x00o\r\x00\x00\x00\x00\x00')
2020-08-09 23:43:09.930 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'10db219c540edcb25994ca25aa1593499c90e9a533ce36fa02cc63e6f17d5e3fa7ebcd77017e'
2020-08-09 23:43:09.942 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'02dba19c546f90e57e'
2020-08-09 23:43:09.942 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:09.942 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0045'
2020-08-09 23:43:10.880 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12dbb1ed542e14b459954b65ab5592fe638f036212316f9339c76389fc6e3eb4237e'
2020-08-09 23:43:10.880 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:10.881 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000b7ffc124c9ffff0818c4010000001001'
2020-08-09 23:43:10.881 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=183>, 255, -63, 0xc924, 255, 255, b'\x18\xc4\x01\x00\x00\x00\x10\x01']
2020-08-09 23:43:10.882 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=196 command_id=Command.Read_Attributes_rsp>
2020-08-09 23:43:11.000 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22dbb197540edcb658924a24ab1593499c71e2abede3387e'
2020-08-09 23:43:11.001 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:11.003 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0024c9040106000101400100003fc50000'
2020-08-09 23:43:11.003 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 51492, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=63>, 197, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:11.015 DEBUG (MainThread) [zigpy.device] [0xc924] Extending timeout for 0xcf request
2020-08-09 23:43:11.016 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2e:d7:77/0xc924
2020-08-09 23:43:11.016 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2e:d7:77, True)
2020-08-09 23:43:11.017 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'23d821d623fd3bb14d8c8495ab090e7e'
2020-08-09 23:43:11.023 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33d8a1d67a347e'
2020-08-09 23:43:11.023 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:11.024 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:11.024 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xc924, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=207>, 208, b'\x00\xcf\x00\x00\x00')
2020-08-09 23:43:11.025 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'34d9219c540edcb6589c4a24ab1593499c81f7aeed01678bfdc0b47e'
2020-08-09 23:43:11.034 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'44d9a19c546c03f47e'
2020-08-09 23:43:11.034 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:11.035 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0046'
2020-08-09 23:43:11.091 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'54d9b1ed542e14ba59954b65ab5592f1638e036212316f9332c76389fc5e3c5a797e'
2020-08-09 23:43:11.091 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:11.092 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010800010140010000b8ffc024c9ffff0818cf010000002003'
2020-08-09 23:43:11.092 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=184>, 255, -64, 0xc924, 255, 255, b'\x18\xcf\x01\x00\x00\x00 \x03']
2020-08-09 23:43:11.093 DEBUG (MainThread) [zigpy.zcl] [0xc924:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=207 command_id=Command.Read_Attributes_rsp>
2020-08-09 23:43:11.163 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'64d9b197540edcb6589c4a24ab1593499c08f7abed46597e'
2020-08-09 23:43:11.164 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:11.165 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0024c90401080001014001000046d00000'
2020-08-09 23:43:11.165 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 51492, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=70>, 208, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:11.177 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xc924:1:0x0008]: received attribute: 0 update with value: 3
2020-08-09 23:43:11.577 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'74d9b197540edcb25994ca25aa1593499c0be9abeda5887e'
2020-08-09 23:43:11.577 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:11.578 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0024c90000008000004001000045ce0000'
2020-08-09 23:43:11.578 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 51492, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=69>, 206, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:15.337 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'04d9b1ed542e14b25c954b65ab559226638ec22212316e82d6c64389fc813fa7c66d7e'
2020-08-09 23:43:15.337 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:15.338 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100006fffc0e589ffff09092b00200000ff0000'
2020-08-09 23:43:15.338 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=111>, 255, -64, 0x89e5, 255, 255, b'\t+\x00 \x00\x00\xff\x00\x00']
2020-08-09 23:43:15.339 DEBUG (MainThread) [zigpy.zcl] [0x89e5:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=43 command_id=0>
2020-08-09 23:43:15.339 DEBUG (MainThread) [zigpy.zcl] [0x89e5:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports: 32>, <bitmap8.0: 0>, 255, 0]
2020-08-09 23:43:15.339 DEBUG (MainThread) [zigpy.zcl] [0x89e5:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:15.339 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x89e5:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:15.340 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.main_bath_motion, old_state=<state binary_sensor.main_bath_motion=on; friendly_name=Main Bath Motion, device_class=motion @ 2020-08-09T23:39:46.792703-05:00>, new_state=<state binary_sensor.main_bath_motion=off; friendly_name=Main Bath Motion, device_class=motion @ 2020-08-09T23:43:15.340059-05:00>>
2020-08-09 23:43:15.937 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'14d9b1ed542e14b25c954b65ab5592b063f77fc512316e8297c64789fc7e3fa7effb7e'
2020-08-09 23:43:15.937 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:15.943 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010005010140010000f9ffb9586effff09096a00240000000000'
2020-08-09 23:43:15.943 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=249>, 255, -71, 0x6e58, 255, 255, b'\tj\x00$\x00\x00\x00\x00\x00']
2020-08-09 23:43:15.944 DEBUG (MainThread) [zigpy.zcl] [0x6e58:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=106 command_id=0>
2020-08-09 23:43:15.944 DEBUG (MainThread) [zigpy.zcl] [0x6e58:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper: 36>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:15.944 DEBUG (MainThread) [zigpy.zcl] [0x6e58:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:15.944 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6e58:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:15.955 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.office_motion, old_state=<state binary_sensor.office_motion=on; friendly_name=Office Motion, device_class=motion @ 2020-08-09T23:42:17.870027-05:00>, new_state=<state binary_sensor.office_motion=off; friendly_name=Office Motion, device_class=motion @ 2020-08-09T23:43:15.955693-05:00>>
2020-08-09 23:43:16.257 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2020-08-09 23:43:16.258 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'42de21ad28317e'
2020-08-09 23:43:16.262 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'25dea1ad3b567e'
2020-08-09 23:43:16.263 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:16.264 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2020-08-09 23:43:16.705 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'35deb1ed542e14b25c954b65ab55922862f8cb1a12316e8299c64789fc813fa7efd9797e'
2020-08-09 23:43:16.705 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:16.706 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000061feb6ecb1ffff09096400240000ff000004'
2020-08-09 23:43:16.706 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=97>, 254, -74, 0xb1ec, 255, 255, b'\td\x00$\x00\x00\xff\x00\x00']
2020-08-09 23:43:16.707 DEBUG (MainThread) [zigpy.zcl] [0xb1ec:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=100 command_id=0>
2020-08-09 23:43:16.707 DEBUG (MainThread) [zigpy.zcl] [0xb1ec:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper: 36>, <bitmap8.0: 0>, 255, 0]
2020-08-09 23:43:16.707 DEBUG (MainThread) [zigpy.zcl] [0xb1ec:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:16.707 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xb1ec:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:16.708 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.landing_motion, old_state=<state binary_sensor.landing_motion=on; friendly_name=Landing Motion, device_class=motion @ 2020-08-09T23:42:16.558538-05:00>, new_state=<state binary_sensor.landing_motion=off; friendly_name=Landing Motion, device_class=motion @ 2020-08-09T23:43:16.708098-05:00>>
2020-08-09 23:43:21.190 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'45deb1ed542e14ba59954b65ab5592a863f9d73412316093b2cc6389dc7d12637e'
2020-08-09 23:43:21.190 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:21.192 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010800010140010000e1ffb7f09fffff07184f0a00002003'
2020-08-09 23:43:21.192 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=225>, 255, -73, 0x9ff0, 255, 255, b'\x18O\n\x00\x00 \x03']
2020-08-09 23:43:21.193 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=79 command_id=Command.Report_Attributes>
2020-08-09 23:43:21.193 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint8_t, value=3>>]]
2020-08-09 23:43:21.193 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] Attribute report received: current_level=3
2020-08-09 23:43:21.206 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9ff0:1:0x0008]: received attribute: 0 update with value: 3
2020-08-09 23:43:22.835 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'55deb1ed542e14ba59954b65ab5592a863f9d73412316093b2cc6389dc7d83937e'
2020-08-09 23:43:22.835 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:22.852 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010800010140010000e1ffb7f09fffff07184f0a00002003'
2020-08-09 23:43:22.852 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=225>, 255, -73, 0x9ff0, 255, 255, b'\x18O\n\x00\x00 \x03']
2020-08-09 23:43:22.853 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=79 command_id=Command.Report_Attributes>
2020-08-09 23:43:22.853 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint8_t, value=3>>]]
2020-08-09 23:43:22.853 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] Attribute report received: current_level=3
2020-08-09 23:43:22.874 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9ff0:1:0x0008]: received attribute: 0 update with value: 3
2020-08-09 23:43:24.331 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'65deb1ed542e14ba59954b65ab5592a863f6d73412316093b2cc6389dc7de0f07e'
2020-08-09 23:43:24.331 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:24.341 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010800010140010000e1ffb8f09fffff07184f0a00002003'
2020-08-09 23:43:24.342 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=225>, 255, -72, 0x9ff0, 255, 255, b'\x18O\n\x00\x00 \x03']
2020-08-09 23:43:24.342 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=79 command_id=Command.Report_Attributes>
2020-08-09 23:43:24.342 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint8_t, value=3>>]]
2020-08-09 23:43:24.342 DEBUG (MainThread) [zigpy.zcl] [0x9ff0:1:0x0008] Attribute report received: current_level=3
2020-08-09 23:43:24.385 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x9ff0:1:0x0008]: received attribute: 0 update with value: 3
2020-08-09 23:43:25.950 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'75deb5ca9aee3bb14d8c84954ffe7e'
2020-08-09 23:43:25.951 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:25.951 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'05deb1ed502a15b259944a2dae5592aa63f6d73412316c36acb79c83fc1132a7ebcd4a047e'
2020-08-09 23:43:25.951 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:25.957 DEBUG (MainThread) [bellows.ezsp] Application frame 98 (incomingSenderEui64Handler) received: b'cec42e031418ceb0'
2020-08-09 23:43:25.957 DEBUG (MainThread) [bellows.zigbee.application] Received incomingSenderEui64Handler frame with [b0:ce:18:14:03:2e:c4:ce]
2020-08-09 23:43:25.957 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0400000000000008040000e3ffb8f09fffff0bbd5171ff0a006f0d000000'
2020-08-09 23:43:25.957 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=227>, 255, -72, 0x9ff0, 255, 255, b'\xbdQq\xff\n\x00o\r\x00\x00\x00']
2020-08-09 23:43:25.957 DEBUG (MainThread) [zigpy.zdo] [0x9ff0:zdo] ZDO request ZDOCmd.NWK_addr_req: [00:0d:6f:00:0a:ff:71:51, 0, 0]
2020-08-09 23:43:25.958 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x9ff0, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=189>, 209, b'\xbd\x00Qq\xff\n\x00o\r\x00\x00\x00\x00\x00')
2020-08-09 23:43:25.964 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'51df219c54da8ab25994ca25aa1593499cf3f6a550ce36fa02cc63e6f17d5e3fa7ebcdfdd77e'
2020-08-09 23:43:25.997 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'16dfa19c5462d46b7e'
2020-08-09 23:43:25.997 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:26.002 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0048'
2020-08-09 23:43:26.266 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2020-08-09 23:43:26.266 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'62dc21ad711f7e'
2020-08-09 23:43:26.295 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'27dca1adb85e7e'
2020-08-09 23:43:26.295 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:26.321 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2020-08-09 23:43:26.639 INFO (MainThread) [homeassistant.components.discovery] Ignoring service: yeelight {'host': '192.168.1.230', 'port': 54321, 'hostname': 'yeelink-light-color1_miio53851417.local.', 'properties': {'epoch': '1', 'mac': '286c07b1368f'}, 'mac_address': '286c07b1368f', 'device_type': 'color1'}
2020-08-09 23:43:27.590 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'37dcb19754da8ab25994ca25aa1593499c06f6abedf0077e'
2020-08-09 23:43:27.590 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:27.591 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00f09f0000008000004001000048d10000'
2020-08-09 23:43:27.591 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 40944, <EmberApsFrame profileId=0 clusterId=32768 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=72>, 209, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:28.674 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'47dcb1ed542e14b25c954b65ab559207638e295a12316e82b3c64589fc7e3fa7deeb7e'
2020-08-09 23:43:28.675 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:28.675 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100004effc00ef1ffff09094e00260000000000'
2020-08-09 23:43:28.675 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=78>, 255, -64, 0xf10e, 255, 255, b'\tN\x00&\x00\x00\x00\x00\x00']
2020-08-09 23:43:28.676 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=78 command_id=0>
2020-08-09 23:43:28.676 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper|Alarm_2: 38>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:28.676 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:28.676 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xf10e:1:0x0500]: Updated alarm state: ZoneStatus.Alarm_2
2020-08-09 23:43:28.677 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.primary_closet_motion, old_state=<state binary_sensor.primary_closet_motion=off; friendly_name=Primary Closet Motion, device_class=motion @ 2020-08-09T22:15:32.911817-05:00>, new_state=<state binary_sensor.primary_closet_motion=on; friendly_name=Primary Closet Motion, device_class=motion @ 2020-08-09T23:43:28.677147-05:00>>
2020-08-09 23:43:28.694 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=brightness_pct=1, entity_id=light.primary_closet>
2020-08-09 23:43:28.694 DEBUG (MainThread) [zigpy.device] [0xb63f] Extending timeout for 0xd2 request
2020-08-09 23:43:28.694 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:5d:50:7c/0xb63f
2020-08-09 23:43:28.694 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:5d:50:7c, True)
2020-08-09 23:43:28.695 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'75dd21d6287a48b14d8c8495aba6617e'
2020-08-09 23:43:28.701 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50dda1d653ca7e'
2020-08-09 23:43:28.701 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:28.702 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:28.702 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb63f, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=210>, 211, b'\x01\xd2\x04\x03\x00\x00')
2020-08-09 23:43:28.703 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'06e2219c5415a3b6589c4a24ab1593499c9cf4adec1c6388fdc62e7d3a7e'
2020-08-09 23:43:28.714 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'61e2a19c546364037e'
2020-08-09 23:43:28.714 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:28.714 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0049'
2020-08-09 23:43:28.785 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'71e2b1ed542e14ba59954b65ab5592f563f9181d123162832fcd67898cd77e'
2020-08-09 23:43:28.785 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:28.785 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010800010140010000bcffb73fb6ffff0508d20b0400'
2020-08-09 23:43:28.786 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=188>, 255, -73, 0xb63f, 255, 255, b'\x08\xd2\x0b\x04\x00']
2020-08-09 23:43:28.786 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=210 command_id=Command.Default_Response>
2020-08-09 23:43:28.804 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01e2b1975415a3b6589c4a24ab1593499c07f4abed4d797e'
2020-08-09 23:43:28.805 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:28.805 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'003fb60401080001014001000049d30000'
2020-08-09 23:43:28.805 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 46655, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=73>, 211, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:28.806 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xb63f:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(3, 0)' kwargs: '{}' result: [4, <Status.SUCCESS: 0>]
2020-08-09 23:43:28.806 DEBUG (MainThread) [zigpy.device] [0xb63f] Extending timeout for 0xd4 request
2020-08-09 23:43:28.806 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:5d:50:7c/0xb63f
2020-08-09 23:43:28.806 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:5d:50:7c, True)
2020-08-09 23:43:28.807 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d31e321d6287a48b14d8c8495ab9d127e'
2020-08-09 23:43:28.813 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'12e3a5d6c25e7e'
2020-08-09 23:43:28.813 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:28.814 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:28.814 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb63f, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=212>, 213, b'\x01\xd4\x01')
2020-08-09 23:43:28.815 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'22e0219c5415a3b658924a24ab1593499c9af2a8ec7d3a663a787e'
2020-08-09 23:43:28.820 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22e2b1ed542e14b459954b65ab5592f463f9181d12316093b6cc6389ec7f747c7e'
2020-08-09 23:43:28.820 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:28.821 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000bdffb73fb6ffff07184b0a00001001'
2020-08-09 23:43:28.821 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=189>, 255, -73, 0xb63f, 255, 255, b'\x18K\n\x00\x00\x10\x01']
2020-08-09 23:43:28.821 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=75 command_id=Command.Report_Attributes>
2020-08-09 23:43:28.821 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.true>>]]
2020-08-09 23:43:28.821 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] Attribute report received: on_off=1
2020-08-09 23:43:28.824 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33e0a19c5460eb377e'
2020-08-09 23:43:28.824 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:28.834 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'004a'
2020-08-09 23:43:28.834 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.primary_closet, old_state=<state light.primary_closet=off; off_brightness=None, friendly_name=Primary Closet, supported_features=41 @ 2020-08-09T22:15:25.176135-05:00>, new_state=<state light.primary_closet=on; brightness=3, off_brightness=None, friendly_name=Primary Closet, supported_features=41 @ 2020-08-09T23:43:28.834632-05:00>>
2020-08-09 23:43:28.884 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'43e0b1975415a3b658924a24ab1593499c04f2abedbf4e7e'
2020-08-09 23:43:28.884 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:28.886 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'003fb6040106000101400100004ad50000'
2020-08-09 23:43:28.886 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 46655, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=74>, 213, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:28.994 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'53e0b1ed542e14b459954b65ab5592f763f9181d1231628329cd62897c9d7e'
2020-08-09 23:43:28.994 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:28.994 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000beffb73fb6ffff0508d40b0100'
2020-08-09 23:43:28.995 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=190>, 255, -73, 0xb63f, 255, 255, b'\x08\xd4\x0b\x01\x00']
2020-08-09 23:43:28.995 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=212 command_id=Command.Default_Response>
2020-08-09 23:43:28.995 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xb63f:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: [1, <Status.SUCCESS: 0>]
2020-08-09 23:43:28.996 DEBUG (MainThread) [homeassistant.components.zha.entity] light.primary_closet: turned on: {'move_to_level_with_on_off': [4, <Status.SUCCESS: 0>], 'on_off': [1, <Status.SUCCESS: 0>]}
2020-08-09 23:43:30.013 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'63e0b1ed542e14b75d954b65ab5592ef638e119612316f83efcc6389dd732d9b2d7e'
2020-08-09 23:43:30.013 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:30.014 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010504010140010000a6ffc0363dffff0808120a0000210d12'
2020-08-09 23:43:30.015 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=166>, 255, -64, 0x3d36, 255, 255, b'\x08\x12\n\x00\x00!\r\x12']
2020-08-09 23:43:30.015 DEBUG (MainThread) [zigpy.zcl] [0x3d36:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=18 command_id=Command.Report_Attributes>
2020-08-09 23:43:30.015 DEBUG (MainThread) [zigpy.zcl] [0x3d36:1:0x0405] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint16_t, value=4621>>]]
2020-08-09 23:43:30.015 DEBUG (MainThread) [zigpy.zcl] [0x3d36:1:0x0405] Attribute report received: measured_value=4621
2020-08-09 23:43:30.029 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.primary_bath_humidity, old_state=<state sensor.primary_bath_humidity=46.8; unit_of_measurement=%, friendly_name=Primary Bath Humidity, device_class=humidity @ 2020-08-09T23:41:59.000805-05:00>, new_state=<state sensor.primary_bath_humidity=46.2; unit_of_measurement=%, friendly_name=Primary Bath Humidity, device_class=humidity @ 2020-08-09T23:43:30.029038-05:00>>
2020-08-09 23:43:30.029 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x3d36, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=18>, 214, b'\x18\x12\x0b\n\x00')
2020-08-09 23:43:30.032 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'37e1219c541c28b658914e24ab1593499c5cf1aef5dc6c81fdc6797e'
2020-08-09 23:43:30.041 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'74e1a19c5461f3167e'
2020-08-09 23:43:30.041 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:30.042 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'004b'
2020-08-09 23:43:31.021 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'04e1b197541c28b658914e24ab1592499c05f1abed72857e'
2020-08-09 23:43:31.021 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:31.021 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00363d040105040101400000004bd60000'
2020-08-09 23:43:31.022 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 15670, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=64 groupId=0 sequence=75>, 214, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:31.402 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'14e1b1ed542e14b25c954b65ab559207638e295a12316e82b3c64589fc7e3fa7d1387e'
2020-08-09 23:43:31.402 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:31.404 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100004effc00ef1ffff09094e00260000000000'
2020-08-09 23:43:31.405 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=78>, 255, -64, 0xf10e, 255, 255, b'\tN\x00&\x00\x00\x00\x00\x00']
2020-08-09 23:43:31.405 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=78 command_id=0>
2020-08-09 23:43:31.405 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper|Alarm_2: 38>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:31.405 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:31.405 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xf10e:1:0x0500]: Updated alarm state: ZoneStatus.Alarm_2
2020-08-09 23:43:32.021 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'24e1b1ed542e14b75d954b65ab5592ef62f9119612316f83efcc6389dd732d38b27e'
2020-08-09 23:43:32.021 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:32.022 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010504010140010000a6feb7363dffff0808120a0000210d12'
2020-08-09 23:43:32.022 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=166>, 254, -73, 0x3d36, 255, 255, b'\x08\x12\n\x00\x00!\r\x12']
2020-08-09 23:43:32.023 DEBUG (MainThread) [zigpy.zcl] [0x3d36:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=18 command_id=Command.Report_Attributes>
2020-08-09 23:43:32.023 DEBUG (MainThread) [zigpy.zcl] [0x3d36:1:0x0405] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint16_t, value=4621>>]]
2020-08-09 23:43:32.023 DEBUG (MainThread) [zigpy.zcl] [0x3d36:1:0x0405] Attribute report received: measured_value=4621
2020-08-09 23:43:32.036 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x3d36, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=18>, 215, b'\x18\x12\x0b\n\x00')
2020-08-09 23:43:32.038 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'43e6219c541c28b658914e24ab1593499c5cf0aef5dc6c81fdbebc7e'
2020-08-09 23:43:32.047 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'35e6a19c5466cb957e'
2020-08-09 23:43:32.047 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:32.048 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'004c'
2020-08-09 23:43:34.064 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'45e6b197541c28b658914e24ab1593499c02f0abedea607e'
2020-08-09 23:43:34.064 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:34.075 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00363d040105040101400100004cd70000'
2020-08-09 23:43:34.075 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 15670, <EmberApsFrame profileId=260 clusterId=1029 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=76>, 215, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:35.728 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'55e6b1ed562e14b45995b529aa56d23563f623371231648a8ec691c87e'
2020-08-09 23:43:35.728 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:35.729 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'020401060001ff0c0003407cffb8049cffff03017300'
2020-08-09 23:43:35.729 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_MULTICAST: 2>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=255 options=12 groupId=16387 sequence=124>, 255, -72, 0x9c04, 255, 255, b'\x01s\x00']
2020-08-09 23:43:35.730 DEBUG (MainThread) [zigpy.zcl] [0x9c04:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=115 command_id=0>
2020-08-09 23:43:35.730 DEBUG (MainThread) [zigpy.zcl] [0x9c04:1:0x0006] ZCL request 0x0000: []
2020-08-09 23:43:35.730 DEBUG (MainThread) [zigpy.zcl] [0x9c04:1:0x0006] No handler for cluster command 0
2020-08-09 23:43:35.730 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=cc:cc:cc:ff:fe:d6:76:93, unique_id=cc:cc:cc:ff:fe:d6:76:93:1:0x0006, endpoint_id=1, cluster_id=6, command=off, args=[]>
2020-08-09 23:43:35.739 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=toggle, service_data=entity_id=light.primary_closet>
2020-08-09 23:43:35.740 DEBUG (MainThread) [zigpy.device] [0xb63f] Extending timeout for 0xd8 request
2020-08-09 23:43:35.740 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:5d:50:7c/0xb63f
2020-08-09 23:43:35.741 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:5d:50:7c, True)
2020-08-09 23:43:35.742 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'56e721d6287a48b14d8c8495ab001e7e'
2020-08-09 23:43:35.748 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'66e7a1d65ade7e'
2020-08-09 23:43:35.748 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:35.749 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:35.749 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb63f, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=216>, 217, b'\x01\xd8\x00')
2020-08-09 23:43:35.749 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'67e4219c5415a3b658924a24ab1593499c96fea8ec166768637e'
2020-08-09 23:43:35.758 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'77e4a19c54677e677e'
2020-08-09 23:43:35.758 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:35.759 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'004d'
2020-08-09 23:43:35.839 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'07e4b1ed542e14b459954b65ab5592f663f6181d1231628325cd6389d42d7e'
2020-08-09 23:43:35.839 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:35.840 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000bfffb83fb6ffff0508d80b0000'
2020-08-09 23:43:35.840 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=191>, 255, -72, 0xb63f, 255, 255, b'\x08\xd8\x0b\x00\x00']
2020-08-09 23:43:35.840 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=216 command_id=Command.Default_Response>
2020-08-09 23:43:35.867 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'17e4b1ed542e14b459954b65ab55928963f9181d12316093b1cc6389ec7ecac47e'
2020-08-09 23:43:35.867 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:35.868 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010600010140010000c0ffb73fb6ffff07184c0a00001000'
2020-08-09 23:43:35.868 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=192>, 255, -73, 0xb63f, 255, 255, b'\x18L\n\x00\x00\x10\x00']
2020-08-09 23:43:35.869 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=76 command_id=Command.Report_Attributes>
2020-08-09 23:43:35.869 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=Bool, value=Bool.false>>]]
2020-08-09 23:43:35.869 DEBUG (MainThread) [zigpy.zcl] [0xb63f:1:0x0006] Attribute report received: on_off=0
2020-08-09 23:43:35.874 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'27e4b1975415a3b658924a24ab1593499c03feabed3cb87e'
2020-08-09 23:43:35.874 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:35.882 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'003fb6040106000101400100004dd90000'
2020-08-09 23:43:35.882 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 46655, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=77>, 217, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:35.882 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.primary_closet, old_state=<state light.primary_closet=on; brightness=3, off_brightness=None, friendly_name=Primary Closet, supported_features=41 @ 2020-08-09T23:43:28.834632-05:00>, new_state=<state light.primary_closet=off; off_brightness=None, friendly_name=Primary Closet, supported_features=41 @ 2020-08-09T23:43:35.882748-05:00>>
2020-08-09 23:43:35.889 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xb63f:1:0x0006]: executed 'off' command with args: '()' kwargs: '{}' result: [0, <Status.SUCCESS: 0>]
2020-08-09 23:43:35.889 DEBUG (MainThread) [homeassistant.components.zha.entity] light.primary_closet: turned off: [0, <Status.SUCCESS: 0>]
2020-08-09 23:43:36.328 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2020-08-09 23:43:36.329 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'73e521ad47387e'
2020-08-09 23:43:36.333 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'30e5a1ada9e07e'
2020-08-09 23:43:36.333 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:36.334 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2020-08-09 23:43:39.443 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'40e5b1ed542e14b25c954b65ab5592e4638e9fb312316e82c1c64389fc813fa728557e'
2020-08-09 23:43:39.443 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:39.448 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010005010140010000adffc0b818ffff09093c00200000ff0000'
2020-08-09 23:43:39.448 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=173>, 255, -64, 0x18b8, 255, 255, b'\t<\x00 \x00\x00\xff\x00\x00']
2020-08-09 23:43:39.448 DEBUG (MainThread) [zigpy.zcl] [0x18b8:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=60 command_id=0>
2020-08-09 23:43:39.449 DEBUG (MainThread) [zigpy.zcl] [0x18b8:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports: 32>, <bitmap8.0: 0>, 255, 0]
2020-08-09 23:43:39.449 DEBUG (MainThread) [zigpy.zcl] [0x18b8:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:39.449 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x18b8:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:39.449 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.kitchen_motion, old_state=<state binary_sensor.kitchen_motion=on; friendly_name=Kitchen Motion, device_class=motion @ 2020-08-09T23:42:53.903439-05:00>, new_state=<state binary_sensor.kitchen_motion=off; friendly_name=Kitchen Motion, device_class=motion @ 2020-08-09T23:43:39.449477-05:00>>
2020-08-09 23:43:43.818 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'50e5b1ed542e14b25c954b65ab559206638e295a12316e82b2c64789fc7e3fa743e17e'
2020-08-09 23:43:43.819 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:43.829 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100004fffc00ef1ffff09094f00240000000000'
2020-08-09 23:43:43.829 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=79>, 255, -64, 0xf10e, 255, 255, b'\tO\x00$\x00\x00\x00\x00\x00']
2020-08-09 23:43:43.830 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=79 command_id=0>
2020-08-09 23:43:43.830 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper: 36>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:43.830 DEBUG (MainThread) [zigpy.zcl] [0xf10e:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:43.830 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xf10e:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:43.831 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.primary_closet_motion, old_state=<state binary_sensor.primary_closet_motion=on; friendly_name=Primary Closet Motion, device_class=motion @ 2020-08-09T23:43:28.677147-05:00>, new_state=<state binary_sensor.primary_closet_motion=off; friendly_name=Primary Closet Motion, device_class=motion @ 2020-08-09T23:43:43.831055-05:00>>
2020-08-09 23:43:46.335 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2020-08-09 23:43:46.336 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'06ea21ad95397e'
2020-08-09 23:43:46.341 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'61eaa1ad865e7e'
2020-08-09 23:43:46.341 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:46.342 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2020-08-09 23:43:46.801 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'71eab1ed542e14ba59954b65ab559275638d838c12316093a2cc6389dc8026cf7e'
2020-08-09 23:43:46.801 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:46.802 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040108000101400100003cffc3a427ffff07185f0a000020fe'
2020-08-09 23:43:46.802 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=60>, 255, -61, 0x27a4, 255, 255, b'\x18_\n\x00\x00 \xfe']
2020-08-09 23:43:46.802 DEBUG (MainThread) [zigpy.zcl] [0x27a4:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=95 command_id=Command.Report_Attributes>
2020-08-09 23:43:46.803 DEBUG (MainThread) [zigpy.zcl] [0x27a4:1:0x0008] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=uint8_t, value=254>>]]
2020-08-09 23:43:46.803 DEBUG (MainThread) [zigpy.zcl] [0x27a4:1:0x0008] Attribute report received: current_level=254
2020-08-09 23:43:46.816 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x27a4:1:0x0008]: received attribute: 0 update with value: 254
2020-08-09 23:43:47.650 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'01eab1ed542e14b25c954b65ab5592d763f840bc12316e82aac64789fc7e3fa7b7117e'
2020-08-09 23:43:47.650 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:47.651 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100050101400100009effb66717ffff09095700240000000000'
2020-08-09 23:43:47.651 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=158>, 255, -74, 0x1767, 255, 255, b'\tW\x00$\x00\x00\x00\x00\x00']
2020-08-09 23:43:47.651 DEBUG (MainThread) [zigpy.zcl] [0x1767:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=87 command_id=0>
2020-08-09 23:43:47.651 DEBUG (MainThread) [zigpy.zcl] [0x1767:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper: 36>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:47.651 DEBUG (MainThread) [zigpy.zcl] [0x1767:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:47.652 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1767:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:47.652 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.living_room_motion_front, old_state=<state binary_sensor.living_room_motion_front=on; friendly_name=Living Room Motion Front, device_class=motion @ 2020-08-09T23:43:05.129772-05:00>, new_state=<state binary_sensor.living_room_motion_front=off; friendly_name=Living Room Motion Front, device_class=motion @ 2020-08-09T23:43:47.652474-05:00>>
2020-08-09 23:43:52.044 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'11eab1ed542e14b25c954b65ab5592df638e3ba112316e8292c64789fc7e3fa7c8517e'
2020-08-09 23:43:52.045 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:52.046 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000501014001000096ffc01c0affff09096f00240000000000'
2020-08-09 23:43:52.046 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=150>, 255, -64, 0x0a1c, 255, 255, b'\to\x00$\x00\x00\x00\x00\x00']
2020-08-09 23:43:52.046 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=111 command_id=0>
2020-08-09 23:43:52.046 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] ZCL request 0x0000: [<ZoneStatus.Restore_reports|Tamper: 36>, <bitmap8.0: 0>, 0, 0]
2020-08-09 23:43:52.047 DEBUG (MainThread) [zigpy.zcl] [0x0a1c:1:0x0500] No handler for cluster command 0
2020-08-09 23:43:52.047 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0a1c:1:0x0500]: Updated alarm state: ZoneStatus.0
2020-08-09 23:43:52.047 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.hallway_motion, old_state=<state binary_sensor.hallway_motion=on; friendly_name=Hallway Motion, device_class=motion @ 2020-08-09T23:43:07.291375-05:00>, new_state=<state binary_sensor.hallway_motion=off; friendly_name=Hallway Motion, device_class=motion @ 2020-08-09T23:43:52.047861-05:00>>
2020-08-09 23:43:52.063 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=brightness_pct=1, color_name=red, entity_id=light.hallway_stairs, light.hallway>
2020-08-09 23:43:52.064 DEBUG (MainThread) [zigpy.device] [0x88fb] Extending timeout for 0xda request
2020-08-09 23:43:52.064 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2f:4b:f5/0x88fb
2020-08-09 23:43:52.064 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2f:4b:f5, True)
2020-08-09 23:43:52.065 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'12eb21d6a1613ab14d8c8495ab3be87e'
2020-08-09 23:43:52.071 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'22eba1d68bd27e'
2020-08-09 23:43:52.071 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:52.071 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:52.071 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x88fb, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=218>, 219, b'\x01\xda\x04\x03\x00\x00')
2020-08-09 23:43:52.072 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'23e8219c54d19db6589c4a24ab1593499c94fcadec146388fdc6994c7e'
2020-08-09 23:43:52.082 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'33e8a19c5464a99e7e'
2020-08-09 23:43:52.082 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:52.083 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'004e'
2020-08-09 23:43:52.111 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'43e8b1ed542e14ba59954b65ab55922b638edc231231628327cd678929727e'
2020-08-09 23:43:52.111 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:52.113 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'53e8b19754d19db6589c4a24ab1593499c00fcabed6b677e'
2020-08-09 23:43:52.113 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:52.113 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401080001014001000062ffc0fb88ffff0508da0b0400'
2020-08-09 23:43:52.114 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=98>, 255, -64, 0x88fb, 255, 255, b'\x08\xda\x0b\x04\x00']
2020-08-09 23:43:52.114 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=218 command_id=Command.Default_Response>
2020-08-09 23:43:52.114 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00fb88040108000101400100004edb0000'
2020-08-09 23:43:52.114 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 35067, <EmberApsFrame profileId=260 clusterId=8 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=78>, 219, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:52.117 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x88fb:1:0x0008]: executed 'move_to_level_with_on_off' command with args: '(3, 0)' kwargs: '{}' result: [4, <Status.SUCCESS: 0>]
2020-08-09 23:43:52.117 DEBUG (MainThread) [zigpy.device] [0x88fb] Extending timeout for 0xdc request
2020-08-09 23:43:52.117 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for b0:ce:18:14:03:2f:4b:f5/0x88fb
2020-08-09 23:43:52.117 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (b0:ce:18:14:03:2f:4b:f5, True)
2020-08-09 23:43:52.118 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'36e921d6a1613ab14d8c8495ab15c77e'
2020-08-09 23:43:52.124 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'64e9a1d6acb77e'
2020-08-09 23:43:52.124 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2020-08-09 23:43:52.125 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2020-08-09 23:43:52.125 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x88fb, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=220>, 221, b'\x01\xdc\x01')
2020-08-09 23:43:52.125 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'47ee219c54d19db658924a24ab1593499c92faa8ec1266f5a67e'
2020-08-09 23:43:52.135 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'75eea19c546593cb7e'
2020-08-09 23:43:52.135 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2020-08-09 23:43:52.136 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'004f'
2020-08-09 23:43:52.158 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'05eeb1ed542e14b459954b65ab55922a638edc231231628321cd6289b7787e'
2020-08-09 23:43:52.158 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2020-08-09 23:43:52.159 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401060001014001000063ffc0fb88ffff0508dc0b0100'
2020-08-09 23:43:52.159 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=99>, 255, -64, 0x88fb, 255, 255, b'\x08\xdc\x0b\x01\x00']
2020-08-09 23:43:52.159 DEBUG (MainThread) [zigpy.zcl] [0x88fb:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=220 command_id=Command.Default_Response>
2020-08-09 23:43:52.168 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'15eeb19754d19db658924a24ab1593499c01faabedc54f7e'
2020-08-09 23:43:52.168 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2020-08-09 23:43:52.169 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00fb88040106000101400100004fdd0000'
2020-08-09 23:43:52.169 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 35067, <EmberApsFrame profileId=260 clusterId=6 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=79>, 221, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:52.170 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x88fb:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: [1, <Status.SUCCESS: 0>]
2020-08-09 23:43:52.170 DEBUG (MainThread) [homeassistant.components.zha.entity] light.hallway_stairs: turned on: {'move_to_level_with_on_off': [4, <Status.SUCCESS: 0>], 'on_off': [1, <Status.SUCCESS: 0>]}
2020-08-09 23:43:52.440 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'25eeb1ed542e14b05d954b65ab55929f61f8ac4112316f83f4cc6389d54638a348517e'
2020-08-09 23:43:52.440 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2020-08-09 23:43:52.441 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0004010204010140010000d6fdb68beaffff0808090a000029380704'
2020-08-09 23:43:52.442 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=214>, 253, -74, 0xea8b, 255, 255, b'\x08\t\n\x00\x00)8\x07']
2020-08-09 23:43:52.442 DEBUG (MainThread) [zigpy.zcl] [0xea8b:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=9 command_id=Command.Report_Attributes>
2020-08-09 23:43:52.442 DEBUG (MainThread) [zigpy.zcl] [0xea8b:1:0x0402] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=int16s, value=1848>>]]
2020-08-09 23:43:52.442 DEBUG (MainThread) [zigpy.zcl] [0xea8b:1:0x0402] Attribute report received: measured_value=1848
2020-08-09 23:43:52.455 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xea8b, <EmberApsFrame profileId=260 clusterId=1026 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=9>, 222, b'\x18\t\x0b\n\x00')
2020-08-09 23:43:52.456 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'53ef219c54a1ffb658964e24ab1593499c47f9aef5c76c81fd47857e'
2020-08-09 23:43:52.466 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'36efa19c547a7eb47e'
2020-08-09 23:43:52.467 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2020-08-09 23:43:52.467 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0050'
2020-08-09 23:43:53.481 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'46efb19754a1ffb658964e24ab1593499c1ef9abedd2047e'
2020-08-09 23:43:53.482 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2020-08-09 23:43:53.483 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'008bea0401020401014001000050de0000'
2020-08-09 23:43:53.483 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 60043, <EmberApsFrame profileId=260 clusterId=1026 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=80>, 222, <EmberStatus.SUCCESS: 0>, b'']
2020-08-09 23:43:56.347 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2020-08-09 23:43:56.358 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'65ec21ade5977e'
2020-08-09 23:43:56.365 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'57eca1ad3f8e7e'
2020-08-09 23:43:56.365 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2020-08-09 23:43:56.386 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2020-08-09 23:43:57.510 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=device_tracker.pixel_3a_2, old_state=<state device_tracker.pixel_3a_2=home; source_type=gps, latitude=41.2737594, longitude=-96.0020565, gps_accuracy=76, attribution=Data provided by Tile, altitude=336.70001220703125, is_lost=False, ring_state=STOPPED, voip_state=OFFLINE, friendly_name=Pixel 3a, icon=mdi:view-grid @ 2020-08-09T23:41:57.357288-05:00>, new_state=<state device_tracker.pixel_3a_2=home; source_type=gps, latitude=41.2737594, longitude=-96.0020565, gps_accuracy=76, attribution=Data provided by Tile, altitude=336.70001220703125, is_lost=False, ring_state=STOPPED, voip_state=OFFLINE, friendly_name=Pixel 3a, icon=mdi:view-grid @ 2020-08-09T23:43:57.510734-05:00>>
2020-08-09 23:43:57.511 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=device_tracker.keys, old_state=<state device_tracker.keys=home; source_type=gps, latitude=41.27336301844686, longitude=-96.00212378436326, gps_accuracy=17, attribution=Data provided by Tile, altitude=355.2361717224121, is_lost=False, ring_state=STOPPED, voip_state=OFFLINE, friendly_name=Keys, icon=mdi:view-grid @ 2020-08-09T23:41:57.358584-05:00>, new_state=<state device_tracker.keys=home; source_type=gps, latitude=41.27336301844686, longitude=-96.00212378436326, gps_accuracy=17, attribution=Data provided by Tile, altitude=355.2361717224121, is_lost=False, ring_state=STOPPED, voip_state=OFFLINE, friendly_name=Keys, icon=mdi:view-grid @ 2020-08-09T23:43:57.511433-05:00>>
2020-08-09 23:44:00.369 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'67ecb1ed542e14b05e954b65ab55928863f6181d12316e93b0cc638dd67e3fa7808f7e'
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment