core: ZHA - lumi.plug fails to use quirk

The problem

I have a xiaomi lumi zigbee plug. I can get it to pair correctly, but it doesn’t pick up the lumi.plug quirk?

(same plug I queried in https://github.com/home-assistant/core/issues/37898 )

What is version of Home Assistant Core has the issue?

core-2021.3.2

What was the last working version of Home Assistant Core?

No response

What type of installation are you running?

Home Assistant Core

Integration causing the issue

ZHA

Link to integration documentation on our website

https://www.home-assistant.io/integrations/zha

Example YAML snippet

# Put your YAML below this line

Anything in the logs that might be useful for us?

# Put your logs below this line
2021-03-09 17:37:09 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=1), 0, 2, b'\x01<\x00')
2021-03-09 17:37:09 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57])
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'14722157541ce94d59947c25aa5592499c4e26abefcd66b7fd97ce7e'
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4272a157541c15af4bef7e'
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5272b157546f10b259a24a25aa5592499c53d8abedce9874fec75f8965457e'
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'267321575485ea4da66bb5da55aac820fb0c42ceaca20be29ca800eccc47806a7e'
2021-03-09 17:37:09 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 54 (sendBroadcast) received: b'001d'
2021-03-09 17:37:09 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'05000036000000000000001dff000000ffff03013c00'
2021-03-09 17:37:09 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=29), 255, 0, 0x0000, 255, 255, b'\x01<\x00']
2021-03-09 17:37:09 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [60, <Bool.false: 0>]
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6373a15754851591fe7e'
2021-03-09 17:37:09 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:09 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 175 (addTransientLinkKey) received: b'00'
2021-03-09 17:37:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7373b1575415134ea6944a13aa5592499c4e27b6efce6769bc7e'
2021-03-09 17:37:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:10 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'06fcff000036000000000000001d020000'
2021-03-09 17:37:10 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=29), 2, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:10 DEBUG (MainThread) [bellows.ezsp.protocol] Send command permitJoining: (60,)
2021-03-09 17:37:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'30702157540829d5c07e'
2021-03-09 17:37:10 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0470a15754081598847e'
2021-03-09 17:37:10 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:10 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 34 (permitJoining) received: b'00'
2021-03-09 17:37:11 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=3), 0, 4, b'\x03<\x00')
2021-03-09 17:37:11 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57])
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'41712157541ce94d59947c25aa5592499c4e24abe9cd64b7fd3a5c7e'
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1571a157541c15acaf727e'
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2571b157546f10b259a24a25aa5592499c50d8abedce9874fec55f89bcb67e'
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'537621575485ea4da66bb5da55aac820fb0c42ceaca20be29ca800eccc476ba77e'
2021-03-09 17:37:11 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 54 (sendBroadcast) received: b'001e'
2021-03-09 17:37:11 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'05000036000000000000001eff000000ffff03033c00'
2021-03-09 17:37:11 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=30), 255, 0, 0x0000, 255, 255, b'\x03<\x00']
2021-03-09 17:37:11 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [60, <Bool.false: 0>]
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3676a15754851541cf7e'
2021-03-09 17:37:11 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:11 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 175 (addTransientLinkKey) received: b'00'
2021-03-09 17:37:12 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4676b1575415134ea6944a13aa5592499c4e27b5e9ce673bc57e'
2021-03-09 17:37:12 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:12 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'06fcff000036000000000000001e040000'
2021-03-09 17:37:12 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=30), 4, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:12 DEBUG (MainThread) [bellows.ezsp.protocol] Send command permitJoining: (60,)
2021-03-09 17:37:12 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'657721575408298eb17e'
2021-03-09 17:37:12 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5777a15754081562d07e'
2021-03-09 17:37:12 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:12 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 34 (permitJoining) received: b'00'
2021-03-09 17:37:16 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:37:16 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'76742157542fdfbf7e'
2021-03-09 17:37:16 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6074a157542f95e27e'
2021-03-09 17:37:16 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:16 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:37:19 ERROR (stream_worker) [libav.h264] error while decoding MB 57 33, bytestream -18
2021-03-09 17:37:19 ERROR (stream_worker) [homeassistant.components.stream.worker] Error demuxing stream while finding first packet: Invalid data - got 7 packets with missing DTS while initializing
2021-03-09 17:37:23 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7074b157540eaa2a96e5c127aad887499d4e27ab1ba57e'
2021-03-09 17:37:23 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:23 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 36 (trustCenterJoinHandler) received: b'bf98cf718b02008d150001000000'
2021-03-09 17:37:23 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x98bf, 00:15:8d:00:02:8b:71:cf, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000]
2021-03-09 17:37:23 INFO (MainThread) [zigpy.application] Device 0x98bf (00:15:8d:00:02:8b:71:cf) joined the network
2021-03-09 17:37:23 DEBUG (MainThread) [bellows.ezsp.protocol] Send command findKeyTableEntry: (00:15:8d:00:02:8b:71:cf, True)
2021-03-09 17:37:23 INFO (MainThread) [zigpy.device] [0x98bf] Requesting 'Node Descriptor'
2021-03-09 17:37:23 DEBUG (MainThread) [zigpy.util] Tries remaining: 2
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.device] [0x98bf] Extending timeout for 0x05 request
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.zigbee.application] Extending timeout for 00:15:8d:00:02:8b:71:cf/0x98bf
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (00:15:8d:00:02:8b:71:cf, True)
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'00752157545fdac3d2964aa8bf5593978e7e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0175a157545fea2bfa7e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d317a21575454dac3d2964aa8bf5593177d317e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 117 (findKeyTableEntry) received: b'ff'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'127aa15754545b6a7e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 126 (setExtendedTimeout) received: b''
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=5), 6, b'\x05\xbf\x98')
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'227b2157541e150dc1944a27aa5592099d4e27aeebcd62346503687e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'237ba157541e159223d67e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0020'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'337bb157546f11b259874a25aa5593499c0fc96c52569874f1d4dc11330fb4a5eb40cb6f01d9977e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'040000130000000001000041eec7bf98ffff0c12bf98cf718b02008d15008e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=65), 238, -57, 0x98bf, 255, 255, b'\x12\xbf\x98\xcfq\x8b\x02\x00\x8d\x15\x00\x8e']
2021-03-09 17:37:24 INFO (MainThread) [bellows.zigbee.application] ZDO Device announce: 0x98bf, 00:15:8d:00:02:8b:71:cf
2021-03-09 17:37:24 INFO (MainThread) [zigpy.application] Device 0x98bf (00:15:8d:00:02:8b:71:cf) joined the network
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device 00:15:8d:00:02:8b:71:cf
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Device_annce: [0x98BF, 00:15:8d:00:02:8b:71:cf, 142]
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'437bb1575473aa2a96e5c127aad88749718927caad7e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 89 (incomingRouteRecordHandler) received: b'bf98cf718b02008d1500edc700'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.zigbee.application] Received incomingRouteRecordHandler frame with [0x98bf, 00:15:8d:00:02:8b:71:cf, 237, -57, []]
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.zigbee.application] Processing route record request: (0x98bf, 00:15:8d:00:02:8b:71:cf, 237, -57, [])
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'537bb157546f15b658944a24ab5593499c0cd76c52569874e9de3483f97e7dae87b8b306a18fabaeb2d369ac4721680c7e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401000001010001000042f0c7bf98ffff1418570a050042096c756d692e706c75670100200102'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=66), 240, -57, 0x98bf, 255, 255, b'\x18W\n\x05\x00B\tlumi.plug\x01\x00 \x01']
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device 00:15:8d:00:02:8b:71:cf (Status.NEW) for endpoint: 1
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.device] [0x98bf] Received ZCL while uninitialized on endpoint id 1, cluster 0x0000 id, hdr: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=87 command_id=Command.Report_Attributes>, payload: b'\x05\x00B\tlumi.plug\x01\x00 \x01'
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.device] [0x98bf] Uninitialized device command 'Command.Report_Attributes' args: [[Attribute(attrid=5, value=<TypeValue type=CharacterString, value=lumi.plug>), Attribute(attrid=1, value=<TypeValue type=uint8_t, value=1>)]]
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'637bb157546f15b6589e4a24ab5593499c0dc86c52569874f8d63b89fc7e3d51ec7e'
2021-03-09 17:37:24 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010a0001010001000043efc7bf98ffff05105800000002'
2021-03-09 17:37:24 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=67), 239, -57, 0x98bf, 255, 255, b'\x10X\x00\x00\x00']
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device 00:15:8d:00:02:8b:71:cf (Status.NEW) for endpoint: 1
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.device] [0x98bf] Received ZCL while uninitialized on endpoint id 1, cluster 0x000a id, hdr: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=88 command_id=Command.Read_Attributes>, payload: b'\x00\x00'
2021-03-09 17:37:24 DEBUG (MainThread) [zigpy.device] [0x98bf] Uninitialized device command 'Command.Read_Attributes' args: [[0]]
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'737bb157546f15b658944a24ab5593499c0ac86252569874c7da3c98a6743e58a9fcba7f8ffceff84deb698c46233cd56dfd19d5e3a16b914c261690f370a23c1e0f9af3d962ad459a4d9e4f9ff7c3d0f56a34a0de307e'
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401000001010001000044efc9bf98ffff3a1c5f115a0a01ff423164100003282398390000000095391bc6bc3f96232a090000052113009a201008211613072700000000000000000921000102'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=68), 239, -55, 0x98bf, 255, 255, b"\x1c_\x11Z\n\x01\xffB1d\x10\x00\x03(#\x989\x00\x00\x00\x00\x959\x1b\xc6\xbc?\x96#*\t\x00\x00\x05!\x13\x00\x9a \x10\x08!\x16\x13\x07'\x00\x00\x00\x00\x00\x00\x00\x00\t!\x00\x01"]
2021-03-09 17:37:25 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device 00:15:8d:00:02:8b:71:cf (Status.NEW) for endpoint: 1
2021-03-09 17:37:25 DEBUG (MainThread) [zigpy.device] [0x98bf] Received ZCL while uninitialized on endpoint id 1, cluster 0x0000 id, hdr: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=True is_reply=True disable_default_response=True> manufacturer=4447 tsn=90 command_id=Command.Report_Attributes>, payload: b"\x01\xffB1d\x10\x00\x03(#\x989\x00\x00\x00\x00\x959\x1b\xc6\xbc?\x96#*\t\x00\x00\x05!\x13\x00\x9a \x10\x08!\x16\x13\x07'\x00\x00\x00\x00\x00\x00\x00\x00\t!\x00\x01"
2021-03-09 17:37:25 DEBUG (MainThread) [zigpy.device] [0x98bf] Uninitialized device command 'Command.Report_Attributes' args: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=d>)]]
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'037bb1575415150dc1944a27aa5592099c4e278bebce6704a57e'
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000020000004000000020060000'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY: 64>, groupId=0, sequence=32), 6, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'137bb157546f15b25996ca25aa1593499c0bcc6252569874ecc36336647f7f29b4dca10b8fffc7bfd5d26ba86e7e'
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000045ebc9bf98ffff110500bf9801408e5f117f6400000064000002'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=69), 235, -55, 0x98bf, 255, 255, b'\x05\x00\xbf\x98\x01@\x8e_\x11\x7fd\x00\x00\x00d\x00\x00']
2021-03-09 17:37:25 INFO (MainThread) [zigpy.device] [0x98bf] Node Descriptor: NodeDescriptor(byte1=1, byte2=64, mac_capability_flags=142, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=0, maximum_outgoing_transfer_size=100, descriptor_capability_field=0)
2021-03-09 17:37:25 INFO (MainThread) [zigpy.device] [0x98bf] Discovering endpoints
2021-03-09 17:37:25 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=7), 8, b'\x07\xbf\x98')
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'32782157541e150dc1944a20aa5592099d4e27ace5cd6034651b277e'
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2478a157541e15932c6d7e'
2021-03-09 17:37:25 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:25 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0021'
2021-03-09 17:37:26 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:37:26 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'43792157542f92487e'
2021-03-09 17:37:26 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3579a157542f870d7e'
2021-03-09 17:37:26 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:26 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4579b157546f15b25996ca25aa1593499c0bd56052569874ecc36336647f7f29b4dca10b8fffc7bfd5d26bbefd7e'
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000045f2cbbf98ffff110500bf9801408e5f117f6400000064000002'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=69), 242, -53, 0x98bf, 255, 255, b'\x05\x00\xbf\x98\x01@\x8e_\x11\x7fd\x00\x00\x00d\x00\x00']
2021-03-09 17:37:27 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Node_Desc_rsp: [<Status.SUCCESS: 0>, 0x98BF, NodeDescriptor(byte1=1, byte2=64, mac_capability_flags=142, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=0, maximum_outgoing_transfer_size=100, descriptor_capability_field=0)]
2021-03-09 17:37:27 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] Unsupported ZDO request:ZDOCmd.Node_Desc_rsp
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5579b1575415150dc1944a20aa5592099c4e278ae5ce67055e7e'
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000050000004000000021080000'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY: 64>, groupId=0, sequence=33), 8, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6579b157546f15b25991ca25aa1593499c08c86052569874f4c16336647a3ea5e8a9dc9c4d7e'
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000058000004001000046efcbbf98ffff090700bf98040102036402'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=70), 239, -53, 0x98bf, 255, 255, b'\x07\x00\xbf\x98\x04\x01\x02\x03d']
2021-03-09 17:37:27 INFO (MainThread) [zigpy.device] [0x98bf] Discovered endpoints: [1, 2, 3, 100]
2021-03-09 17:37:27 INFO (MainThread) [zigpy.endpoint] [0x98bf:1] Discovering endpoint information
2021-03-09 17:37:27 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=9), 10, b'\t\xbf\x98\x01')
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'577d5e2157541e150dc1944a21aa5592099d4e27a2e7ca6e3465c70c887e'
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'767ea157541e1590f9e97e'
2021-03-09 17:37:27 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:27 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0022'
2021-03-09 17:37:28 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'067eb157546f15b25996ca25aa1593499c0bd26752569874ecc36336647f7f29b4dca10b8fffc7bfd5d26b2dc77e'
2021-03-09 17:37:28 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:28 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000045f5ccbf98ffff110500bf9801408e5f117f6400000064000002'
2021-03-09 17:37:28 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=69), 245, -52, 0x98bf, 255, 255, b'\x05\x00\xbf\x98\x01@\x8e_\x11\x7fd\x00\x00\x00d\x00\x00']
2021-03-09 17:37:28 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Node_Desc_rsp: [<Status.SUCCESS: 0>, 0x98BF, NodeDescriptor(byte1=1, byte2=64, mac_capability_flags=142, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=0, maximum_outgoing_transfer_size=100, descriptor_capability_field=0)]
2021-03-09 17:37:28 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] Unsupported ZDO request:ZDOCmd.Node_Desc_rsp
2021-03-09 17:37:28 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'167eb157546f15b25991ca25aa1593499c08d26052569874f4c16336647a3ea5e8a9dc13bd7e'
2021-03-09 17:37:28 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:28 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000058000004001000046f5cbbf98ffff090700bf98040102036402'
2021-03-09 17:37:28 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=70), 245, -53, 0x98bf, 255, 255, b'\x07\x00\xbf\x98\x04\x01\x02\x03d']
2021-03-09 17:37:28 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Active_EP_rsp: [<Status.SUCCESS: 0>, 0x98BF, [1, 2, 3, 100]]
2021-03-09 17:37:28 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] Unsupported ZDO request:ZDOCmd.Active_EP_rsp
2021-03-09 17:37:28 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'267eb1575415150dc1944a21aa5592099c4e2789e7ce67dce17e'
2021-03-09 17:37:28 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:28 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9800000400000040000000220a0000'
2021-03-09 17:37:28 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY: 64>, groupId=0, sequence=34), 10, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:30 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'367eb157546f15b25996ca25aa1593499c0bd26052569874ecc36336647f7f29b4dca10b8fffc7bfd5d26be0457e'
2021-03-09 17:37:30 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:30 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'467eb157546f15b25991ca25aa1593499c08d46052569874f4c16336647a3ea5e8a9dc2c3f7e'
2021-03-09 17:37:30 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:30 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000045f5cbbf98ffff110500bf9801408e5f117f6400000064000002'
2021-03-09 17:37:30 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=69), 245, -53, 0x98bf, 255, 255, b'\x05\x00\xbf\x98\x01@\x8e_\x11\x7fd\x00\x00\x00d\x00\x00']
2021-03-09 17:37:30 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Node_Desc_rsp: [<Status.SUCCESS: 0>, 0x98BF, NodeDescriptor(byte1=1, byte2=64, mac_capability_flags=142, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=0, maximum_outgoing_transfer_size=100, descriptor_capability_field=0)]
2021-03-09 17:37:30 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] Unsupported ZDO request:ZDOCmd.Node_Desc_rsp
2021-03-09 17:37:30 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000058000004001000046f3cbbf98ffff090700bf98040102036402'
2021-03-09 17:37:30 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=70), 243, -53, 0x98bf, 255, 255, b'\x07\x00\xbf\x98\x04\x01\x02\x03d']
2021-03-09 17:37:30 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Active_EP_rsp: [<Status.SUCCESS: 0>, 0x98BF, [1, 2, 3, 100]]
2021-03-09 17:37:30 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] Unsupported ZDO request:ZDOCmd.Active_EP_rsp
2021-03-09 17:37:32 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'567eb157546f15b25991ca25aa1593499c08c86752569874f4c16336647a3ea5e8a9dc8daf7e'
2021-03-09 17:37:32 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:32 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000058000004001000046efccbf98ffff090700bf98040102036402'
2021-03-09 17:37:32 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=70), 239, -52, 0x98bf, 255, 255, b'\x07\x00\xbf\x98\x04\x01\x02\x03d']
2021-03-09 17:37:32 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] ZDO request ZDOCmd.Active_EP_rsp: [<Status.SUCCESS: 0>, 0x98BF, [1, 2, 3, 100]]
2021-03-09 17:37:32 DEBUG (MainThread) [zigpy.zdo] [0x98bf:zdo] Unsupported ZDO request:ZDOCmd.Active_EP_rsp
2021-03-09 17:37:35 DEBUG (MainThread) [zigpy.util] Tries remaining: 2
2021-03-09 17:37:35 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=11), 12, b'\x0b\xbf\x98\x01')
2021-03-09 17:37:35 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'667f2157541e150dc1944a21aa5592099d4e27a0e1ca6c3465c74cd97e'
2021-03-09 17:37:35 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'677fa157541e15912bce7e'
2021-03-09 17:37:35 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:35 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0023'
2021-03-09 17:37:36 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:37:36 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'777c2157542f98327e'
2021-03-09 17:37:36 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'707ca157542f8d4b7e'
2021-03-09 17:37:36 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:36 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'007cb1575415150dc1944a21aa5592099c4e2788e1ce67edb27e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9800000400000040000000230c0000'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_RETRY: 64>, groupId=0, sequence=35), 12, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'107cb157546f15b25990ca25aa1593499c09d66552569874decd633664603ea3ea9cde6e86ffc7dfd5d1698a4633a9e97631a5eb7580419a552619b1e29e967e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000048000004001000047f1cebf98ffff230b00bf981e010401510001090000040003000600100005000a00010002000219000a0002'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=71), 241, -50, 0x98bf, 255, 255, b'\x0b\x00\xbf\x98\x1e\x01\x04\x01Q\x00\x01\t\x00\x00\x04\x00\x03\x00\x06\x00\x10\x00\x05\x00\n\x00\x01\x00\x02\x00\x02\x19\x00\n\x00']
2021-03-09 17:37:37 INFO (MainThread) [zigpy.endpoint] [0x98bf:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=81, device_version=1, input_clusters=[0, 4, 3, 6, 16, 5, 10, 1, 2], output_clusters=[25, 10])
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=13), 14, b'\x00\r\x00\x04\x00\x05\x00')
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'027d5d2157541e150dc1904b25aa5493099d4e27a6e3c96786fdc2638cfccebd7e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'217da157541e15964dd17e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0024'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'317db1575415150dc1904b25aa5493099d4e278fe3ce6703a37e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010000010140010000240e0000'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=36), 14, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'417db157546f15b658944a24ab5593499c06d26552569874e7de6e88f87e3fe5ef818b22c6fac7db97db05f92b4a879c1a4ec2e8e38f7e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401000001010001000048f5cebf98ffff1a180d0104000042044c554d4905000042096c756d692e706c756702'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=72), 245, -50, 0x98bf, 255, 255, b'\x18\r\x01\x04\x00\x00B\x04LUMI\x05\x00\x00B\tlumi.plug']
2021-03-09 17:37:37 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=13 command_id=Command.Read_Attributes_rsp>
2021-03-09 17:37:37 DEBUG (MainThread) [zigpy.endpoint] [0x98bf:1] Manufacturer: LUMI
2021-03-09 17:37:37 DEBUG (MainThread) [zigpy.endpoint] [0x98bf:1] Model: lumi.plug
2021-03-09 17:37:37 INFO (MainThread) [zigpy.endpoint] [0x98bf:2] Discovering endpoint information
2021-03-09 17:37:37 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=15), 16, b'\x0f\xbf\x98\x02')
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'15022157541e150dc1944a21aa5592099d4e27a4fdca683465c4ab347e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5202a157541e15972b017e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0025'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6202b1575415150dc1944a21aa5592099d4e278efdce670f897e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000040000004001000025100000'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=37), 16, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7202b157546f15b658924a24ab5593499c07d06552569874f3de0483fc7e2fa6eb3dfd6f3067c4d915157e'
2021-03-09 17:37:37 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000049f7cebf98ffff0e18670a0000100100f02300bf980302'
2021-03-09 17:37:37 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=73), 247, -50, 0x98bf, 255, 255, b'\x18g\n\x00\x00\x10\x01\x00\xf0#\x00\xbf\x98\x03']
2021-03-09 17:37:37 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device 00:15:8d:00:02:8b:71:cf endpoint 1 for cluster: 6
2021-03-09 17:37:41 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0202b157546f15b6589e4a24ab5593499c04da6e52569874f8d60e89fc7e3d27cb7e'
2021-03-09 17:37:41 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:41 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010a000101000100004afdc5bf98ffff05106d00000002'
2021-03-09 17:37:41 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=74), 253, -59, 0x98bf, 255, 255, b'\x10m\x00\x00\x00']
2021-03-09 17:37:41 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device 00:15:8d:00:02:8b:71:cf endpoint 1 for cluster: 10
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.util] Tries remaining: 2
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=17), 18, b'\x11\xbf\x98\x02')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'21032157541e150dc1944a21aa5592099d4e27baffca763465c4e1e67e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1303a157541e159412007e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0026'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2303b1575415150dc1944a21aa5592099d4e278dffce67333e7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000040000004001000026120000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=38), 18, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3303b157546f15b25990ca25aa1593499c05d36752569874eed7633664703da3eac4de6e8ef3c7d9d9d26d8c4460b67e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00000004800000400100004bf4ccbf98ffff131100bf980e020401090001010c00020c00040002'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=75), 244, -52, 0x98bf, 255, 255, b'\x11\x00\xbf\x98\x0e\x02\x04\x01\t\x00\x01\x01\x0c\x00\x02\x0c\x00\x04\x00']
2021-03-09 17:37:44 INFO (MainThread) [zigpy.endpoint] [0x98bf:2] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=2, profile=260, device_type=9, device_version=1, input_clusters=[12], output_clusters=[12, 4])
2021-03-09 17:37:44 INFO (MainThread) [zigpy.endpoint] [0x98bf:3] Discovering endpoint information
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=19), 20, b'\x13\xbf\x98\x03')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'34002157541e150dc1944a21aa5592099d4e27b8f9ca743465c5658c7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4400a157541e1595d6df7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0027'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5400b1575415150dc1944a21aa5592099d4e278cf9ce67fb107e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000040000004001000027140000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=39), 20, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6400b157546f15b25990ca25aa1593499c02d46052569874ecd5633664723ca3ea9ede6e8ef3c7dad9d26b97587e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00000004800000400100004cf3cbbf98ffff111300bf980c030401530001010c00010c0002'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=76), 243, -53, 0x98bf, 255, 255, b'\x13\x00\xbf\x98\x0c\x03\x04\x01S\x00\x01\x01\x0c\x00\x01\x0c\x00']
2021-03-09 17:37:44 INFO (MainThread) [zigpy.endpoint] [0x98bf:3] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=3, profile=260, device_type=83, device_version=1, input_clusters=[12], output_clusters=[12])
2021-03-09 17:37:44 INFO (MainThread) [zigpy.endpoint] [0x98bf:100] Discovering endpoint information
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=21), 22, b'\x15\xbf\x98d')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'47012157541e150dc1944a21aa5592099d4e27befbca723465a2f1a07e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7501a157541e159a9e5e7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0028'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0501b1575415150dc1944a21aa5592099d4e2783fbce67b8e37e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000040000004001000028160000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=40), 22, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1501b157546f15b25990ca25aa1593499c03de6052569874eed3633664705ba3eacadf6d8ef0c7d9dad26d8c44ad027e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00000004800000400100004df9cbbf98ffff131500bf980e640401070102010f00020f00040002'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=77), 249, -53, 0x98bf, 255, 255, b'\x15\x00\xbf\x98\x0ed\x04\x01\x07\x01\x02\x01\x0f\x00\x02\x0f\x00\x04\x00']
2021-03-09 17:37:44 INFO (MainThread) [zigpy.endpoint] [0x98bf:100] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=100, profile=260, device_type=263, device_version=2, input_clusters=[15], output_clusters=[15, 4])
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.plug (00:15:8d:00:02:8b:71:cf)
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.plug.Plug'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 3} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rwl021.PhilipsRWL021'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rwl020.PhilipsRWL020'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rom001.PhilipsROM001'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'bellows.zigbee.application.EZSPCoordinator'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x98BF:00:15:8d:00:02:8b:71:cf entering async_device_initialized - is_new_join: True
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x98BF:00:15:8d:00:02:8b:71:cf has joined the ZHA zigbee network
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): started configuration
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:ZDO](lumi.plug): 'async_configure' stage succeeded
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=23), 24, b'\x17\xcfq\x8b\x02\x00\x8d\x15\x00\x01\x06\x00\x03\xed\xe9\xf5\x11\x00o\r\x00\x01')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'52062157541e150dc1944a04aa5592099d4e27bcf5d870448c4d6189716b3fa6edcddd82660ad6dbbadf698d07127e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2606a157541e159b942a7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0029'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=25), 26, b'\x19\xcfq\x8b\x02\x00\x8d\x15\x00\x01\x00\x00\x03\xed\xe9\xf5\x11\x00o\r\x00\x01')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'63072157541e150dc1944a04aa5592099d4e27b2f7d87d5e448c4d6189716b3fa6ebcddd82660ad6dbbadf698dafa97e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3707a157541e1598664f7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'002a'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=27), 28, b'\x1b\xcfq\x8b\x02\x00\x8d\x15\x00\x01\x19\x00\x03\xed\xe9\xf5\x11\x00o\r\x00\x01')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'74042157541e150dc1944a04aa5592099d4e27b0f1d87c448c4d6189716b3fa6f2cddd82660ad6dbbadf698d24897e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4004a157541e1599d9f87e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'002b'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5004b1575415150dc1944a04aa5592099d4e2782f5ce676f667e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980000210000004001000029180000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=41), 24, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6004b157546f15b259b5ca25aa1593499c00d16752569874ffd1638bce6f7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00000021800000400100004ef6ccbf98ffff02170002'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=78), 246, -52, 0x98bf, 255, 255, b'\x17\x00']
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: bound 'on_off' cluster: Status.SUCCESS
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=29), 30, b'\x00\x1d\x06\x00\x00\x00\x10\x00\x00\x84\x03')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'07052157541e150dc1904b23aa5493099d4e27b6f3c56796fbc66389ec7d5e3f23e8b91b7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7004b1575415150dc1944a04aa5592099d4e2781f7ce67fa367e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf98000021000000400100002a1a0000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=42), 26, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0105a157541e159ea07d7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'002c'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1105b1575415150dc1944a04aa5592099d4e2780f1ce6797c57e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2105b1575415150dc1904b23aa5493099d4e2787f3ce67a2597e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3105b157546f15b658924a24ab5593499c01dd6752569874f9de7e8efc7cedc77e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf98000021000000400100002b1c0000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=43), 28, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf98040106000101400100002c1e0000'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=44), 30, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040106000101000100004ffaccbf98ffff04181d070002'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=79), 250, -52, 0x98bf, 255, 255, b'\x18\x1d\x07\x00']
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=29 command_id=Command.Configure_Reporting_rsp>
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: reporting 'on_off' attr on 'on_off' cluster: 0/900/1: Result: '[[ConfigureReportingResponseRecord(status=0)]]'
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: finished channel configuration
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=31), 32, b'\x1f\xcfq\x8b\x02\x00\x8d\x15\x00\x02\x0c\x00\x03\xed\xe9\xf5\x11\x00o\r\x00\x01')
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'140a2157541e150dc1944a04aa5592099d4e27b4cdd878448c4d6189716b3fa5e7cddd82660ad6dbbadf698d491b7e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'420aa157541e159f04107e'
2021-03-09 17:37:44 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:44 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'002d'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'520ab1575415150dc1944a04aa5592099d4e2786cdce6721d77e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf98000021000000400100002d200000'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=45), 32, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'620ab157546f15b259b5ca25aa1593499c1ed46752569874ffd9638b3eb57e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000218000004001000050f3ccbf98ffff021f0002'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=80), 243, -52, 0x98bf, 255, 255, b'\x1f\x00']
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: bound 'analog_input' cluster: Status.SUCCESS
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=33), 34, b'\x00!\x06\x00U\x009\x1e\x00\x84\x03\x00\x00\x80?')
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'270b2157541e150dc1904b29aa5790099d4e278acfc167aafbc63689c5603f23e8cddeefb0cb867e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'730ba157541e159c8d1d7e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'002e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'030bb1575415150dc1904b29aa5790099d4e2785cfce67f6947e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010c000202400100002e220000'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=46), 34, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'130bb157546f15b658984a27a85593499c1fd26752569874face428e707e6aa7e9aa267e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c0002020001000051f5ccbf98ffff070821078c00550002'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=81), 245, -52, 0x98bf, 255, 255, b'\x08!\x07\x8c\x00U\x00']
2021-03-09 17:37:45 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=33 command_id=Command.Configure_Reporting_rsp>
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: reporting 'present_value' attr on 'analog_input' cluster: 30/900/1: Result: '[[ConfigureReportingResponseRecord(status=140, direction=0, attrid=85)]]'
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: finished channel configuration
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=35), 36, b'#\xcfq\x8b\x02\x00\x8d\x15\x00\x03\x0c\x00\x03\xed\xe9\xf5\x11\x00o\r\x00\x01')
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'32082157541e150dc1944a04aa5592099d4e2788c9d844448c4d6189716b3fa4e7cddd82660ad6dbbadf698df0f77e'
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: 'async_configure' stage succeeded
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2408a157541e159d49c27e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'002f'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3408b1575415150dc1944a04aa5592099d4e2784c9ce6724e07e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf98000021000000400100002f240000'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=47), 36, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4408b157546f15b259b5ca25aa1593499c1cd46752569874ffe5638b0d727e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000000218000004001000052f3ccbf98ffff02230002'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=82), 243, -52, 0x98bf, 255, 255, b'#\x00']
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: bound 'analog_input' cluster: Status.SUCCESS
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=3, destinationEndpoint=3, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=37), 38, b'\x00%\x06\x00U\x009\x1e\x00\x84\x03\x00\x00\x80?')
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'45092157541e150dc1904b29aa5691099d4e278ecbc167aefbc63689c5603f23e8cddeefb0f2907e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5509a157541e1582e5a27e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0030'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6509b1575415150dc1904b29aa5691099d4e279bcbce6723507e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010c0003034001000030260000'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=3, destinationEndpoint=3, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=48), 38, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7509b157546f15b658984a26a95593499c1dd26752569874face468e707e6aa7e9e7117e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c0003030001000053f5ccbf98ffff070825078c00550002'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=3, destinationEndpoint=3, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=83), 245, -52, 0x98bf, 255, 255, b'\x08%\x07\x8c\x00U\x00']
2021-03-09 17:37:45 DEBUG (MainThread) [zigpy.zcl] [0x98bf:3:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=37 command_id=Command.Configure_Reporting_rsp>
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: reporting 'present_value' attr on 'analog_input' cluster: 30/900/1: Result: '[[ConfigureReportingResponseRecord(status=140, direction=0, attrid=85)]]'
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: finished channel configuration
2021-03-09 17:37:45 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: 'async_configure' stage succeeded
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0509b157546f15b658924a24ab5593499c1ade6752569874fade1283fc7e2fa6e927537e'
2021-03-09 17:37:45 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000054f9ccbf98ffff0718710a0000100102'
2021-03-09 17:37:45 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=84), 249, -52, 0x98bf, 255, 255, b'\x18q\n\x00\x00\x10\x01']
2021-03-09 17:37:45 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=113 command_id=Command.Report_Attributes>
2021-03-09 17:37:45 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2021-03-09 17:37:45 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=1
2021-03-09 17:37:46 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:37:46 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'510e2157542f79de7e'
2021-03-09 17:37:46 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'160ea157542f06b77e'
2021-03-09 17:37:46 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:46 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:37:49 ERROR (stream_worker) [homeassistant.components.stream.worker] Error demuxing stream:
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'260eb157546f15b658924a24ab5593499c1bc96752569874f3de1683fc7e2fa7eb3dfd6f3067c4d9472f7e'
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:49 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000055eeccbf98ffff0e18750a0000100000f02300bf980302'
2021-03-09 17:37:49 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=85), 238, -52, 0x98bf, 255, 255, b'\x18u\n\x00\x00\x10\x00\x00\xf0#\x00\xbf\x98\x03']
2021-03-09 17:37:49 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=117 command_id=Command.Report_Attributes>
2021-03-09 17:37:49 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.false>), Attribute(attrid=61440, value=<TypeValue type=uint32_t, value=60342016>)]]
2021-03-09 17:37:49 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=0, 61440=60342016
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'360eb157546f15b658924a24ab5593499c18956752569874fade1583fc7e2fa7e95ec87e'
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:49 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000056b2ccbf98ffff0718760a0000100002'
2021-03-09 17:37:49 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=86), 178, -52, 0x98bf, 255, 255, b'\x18v\n\x00\x00\x10\x00']
2021-03-09 17:37:49 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=118 command_id=Command.Report_Attributes>
2021-03-09 17:37:49 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.false>)]]
2021-03-09 17:37:49 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=0
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0000]: Failed to bind 'basic' cluster:
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0000]: finished channel configuration
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0019]: Failed to bind 'ota' cluster:
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0019]: finished channel configuration
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: 'async_configure' stage succeeded
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0000]: 'async_configure' stage succeeded
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0019]: 'async_configure' stage succeeded
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): completed configuration
2021-03-09 17:37:49 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): stored in registry: ZhaDeviceEntry(name='LUMI lumi.plug', ieee='00:15:8d:00:02:8b:71:cf', last_seen=1615271869.7926986)
2021-03-09 17:37:49 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=39), 40, b"\x01'@\x02\x00")
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'640f2157541e150dc1904b26aa5493099d4e278cc5cb66acbdc463a8e57e'
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'470fa157541e1583e6b47e'
2021-03-09 17:37:49 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:49 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0031'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'570fb1575415150dc1904b26aa5493099d4e279ac5ce67a3967e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980401030001014001000031280000'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=49), 40, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'670fb157546f15b658974a24ab5593499c19d76752569874f8de4482bcff3d371b7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401030001010001000057f0ccbf98ffff0518270b408102'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=87), 240, -52, 0x98bf, 255, 255, b"\x18'\x0b@\x81"]
2021-03-09 17:37:50 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0003] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=39 command_id=Command.Default_Response>
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0003]: executed 'trigger_effect' command with args: '(2, 0)' kwargs: '{}' result: [64, <Status.UNSUP_CLUSTER_COMMAND: 129>]
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): started initialization
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:ZDO](lumi.plug): 'async_initialize' stage succeeded
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: initializing channel: from_cache: False
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=41), 42, b'\x00)\x00\x00\x00')
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0000]: initializing channel: from_cache: False
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0000]: finished channel configuration
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0019]: initializing channel: from_cache: False
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0019]: finished channel configuration
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: initializing channel: from_cache: False
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: initializing channel: from_cache: False
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'770c2157541e150dc1904b23aa5493099d4e2782c7cb67a2fdc663904c7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'700ca157541e15808f917e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0032'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=43), 44, b'\x00+\x00U\x00')
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'000d2157541e150dc1904b29aa5790099d4e2780c1cb67a0fd9363b4b27e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'010da157541e1581d00e7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0033'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=3, destinationEndpoint=3, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=45), 46, b'\x00-\x00U\x00')
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d31122157541e150dc1904b29aa5691099d4e2786c3cb67a6fd93633f957e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'110db1575415150dc1904b23aa5493099d4e2799c7ce670cf87e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010600010140010000322a0000'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=50), 42, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2212a557541e1586a8c77e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0034'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'320db157546f15b658924a24ab5593499c16d76752569874f5de4a88fc7e3fb7ebcf95887e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4212b1575415150dc1904b29aa5790099d4e2798c1ce67e55a7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5212b157546f15b658984a27a85593499c17d26752569874f6ce4888a97e3f9eebcdde6f8ddc2a7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000058f0ccbf98ffff08182901000000100002'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=88), 240, -52, 0x98bf, 255, 255, b'\x18)\x01\x00\x00\x00\x10\x00']
2021-03-09 17:37:50 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=41 command_id=Command.Read_Attributes_rsp>
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010c00020240010000332c0000'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=51), 44, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c0002020001000059f5ccbf98ffff0b082b01550000390000000002'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=2, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=89), 245, -52, 0x98bf, 255, 255, b'\x08+\x01U\x00\x009\x00\x00\x00\x00']
2021-03-09 17:37:50 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=43 command_id=Command.Read_Attributes_rsp>
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: finished channel configuration
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: 'async_initialize' stage succeeded
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0000]: 'async_initialize' stage succeeded
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0019]: 'async_initialize' stage succeeded
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: finished channel configuration
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:2:0x000c]: 'async_initialize' stage succeeded
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6212b1575415150dc1904b29aa5691099d4e279fc3ce673e9d7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010c00030340010000342e0000'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=3, destinationEndpoint=3, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=52), 46, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7212b157546f15b658984a26a95593499c14cd6752569874f6ce4e88a97e3f9ef00b62508d98907e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c000303000100005aeaccbf98ffff0b082d01550000391bc6bc3f02'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=3, destinationEndpoint=3, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=90), 234, -52, 0x98bf, 255, 255, b'\x08-\x01U\x00\x009\x1b\xc6\xbc?']
2021-03-09 17:37:50 DEBUG (MainThread) [zigpy.zcl] [0x98bf:3:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=45 command_id=Command.Read_Attributes_rsp>
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: finished channel configuration
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:3:0x000c]: 'async_initialize' stage succeeded
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): power source: Mains
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): completed initialization
2021-03-09 17:37:50 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:1:0x0006]: attempting to update onoff state - from cache: False
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=47), 48, b'\x00/\x00\x00\x00')
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'207d332157541e150dc1904b23aa5493099d4e2784ddcb67a4fdc663f7ce7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0313a157541e15873a9d7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0035'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1313b1575415150dc1904b23aa5493099d4e279eddce678c757e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf980401060001014001000035300000'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=53), 48, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2313b157546f15b658924a24ab5593499c15d36752569874f5de4c88fc7e3fb7ebcf05ec7e'
2021-03-09 17:37:50 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040106000101000100005bf4ccbf98ffff08182f01000000100002'
2021-03-09 17:37:50 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=91), 244, -52, 0x98bf, 255, 255, b'\x18/\x01\x00\x00\x00\x10\x00']
2021-03-09 17:37:50 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=47 command_id=Command.Read_Attributes_rsp>
2021-03-09 17:37:56 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:37:56 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'33102157542f66747e'
2021-03-09 17:37:56 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3410a157542f730d7e'
2021-03-09 17:37:56 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:37:56 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:37:59 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4410b157546f15b658924a24ab5593499c12dd6152569874f3dee383fc7e2fa6eb3dfd6f3067c4d9fa527e'
2021-03-09 17:37:59 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:37:59 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040106000101000100005cfacabf98ffff0e18800a0000100100f02300bf980302'
2021-03-09 17:37:59 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=92), 250, -54, 0x98bf, 255, 255, b'\x18\x80\n\x00\x00\x10\x01\x00\xf0#\x00\xbf\x98\x03']
2021-03-09 17:37:59 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=128 command_id=Command.Report_Attributes>
2021-03-09 17:37:59 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>), Attribute(attrid=61440, value=<TypeValue type=uint32_t, value=60342016>)]]
2021-03-09 17:37:59 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=1, 61440=60342016
2021-03-09 17:37:59 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5410b157546f15b658924a24ab5593499c13d46152569874fadee283fc7e2fa6e915757e'
2021-03-09 17:37:59 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:37:59 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040106000101000100005df3cabf98ffff0718810a0000100102'
2021-03-09 17:37:59 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=93), 243, -54, 0x98bf, 255, 255, b'\x18\x81\n\x00\x00\x10\x01']
2021-03-09 17:37:59 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=129 command_id=Command.Report_Attributes>
2021-03-09 17:37:59 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2021-03-09 17:37:59 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=1
2021-03-09 17:38:01 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6410b157546f15b658944a24ab5593499cdad863c3c29874dbde7583fd817db8eaec63648bde6fc8d0f36c8d4007abec763ba58e5c1b4bfd6d4a0bbbc170389a077e'
2021-03-09 17:38:01 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:38:01 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401000001010001000094ffc82e0cffff2618160a01ff421f0121bd0b0421a81305210501062402000000006429990a65216c180a210000'
2021-03-09 17:38:01 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=148), 255, -56, 0x0c2e, 255, 255, b'\x18\x16\n\x01\xffB\x1f\x01!\xbd\x0b\x04!\xa8\x13\x05!\x05\x01\x06$\x02\x00\x00\x00\x00d)\x99\ne!l\x18\n!\x00\x00']
2021-03-09 17:38:01 DEBUG (MainThread) [zigpy.zcl] [0x0c2e:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=22 command_id=Command.Report_Attributes>
2021-03-09 17:38:01 DEBUG (MainThread) [zigpy.zcl] [0x0c2e:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=65281, value=<TypeValue type=CharacterString, value=
!�!�!
$>)]]
2021-03-09 17:38:01 DEBUG (MainThread) [zigpy.zcl] [0x0c2e:1:0x0000] Attribute report received: 65281=
!�!�!
$
2021-03-09 17:38:01 DEBUG (MainThread) [zigpy.zcl] [0x0c2e:1:0x0001] Voltage mV: [Min]:2820 < [RAW]:3005 < [Max]:3100, Battery Percent: 66.0
2021-03-09 17:38:06 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:38:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'477d312157542f8f7d387e'
2021-03-09 17:38:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7511a157542ff6ec7e'
2021-03-09 17:38:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:38:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:38:13 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0511b157546f15b658924a24ab5593499c10d26052569874f3deec83fc7e2fa7eb3dfd6f3067c4d93c407e'
2021-03-09 17:38:13 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:38:13 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040106000101000100005ef5cbbf98ffff0e188f0a0000100000f02300bf980302'
2021-03-09 17:38:13 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=94), 245, -53, 0x98bf, 255, 255, b'\x18\x8f\n\x00\x00\x10\x00\x00\xf0#\x00\xbf\x98\x03']
2021-03-09 17:38:13 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=143 command_id=Command.Report_Attributes>
2021-03-09 17:38:13 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.false>), Attribute(attrid=61440, value=<TypeValue type=uint32_t, value=60342016>)]]
2021-03-09 17:38:13 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=0, 61440=60342016
2021-03-09 17:38:13 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1511b157546f15b658924a24ab5593499c11d16052569874fadef383fc7e2fa7e910837e'
2021-03-09 17:38:13 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:38:13 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040106000101000100005ff6cbbf98ffff0718900a0000100002'
2021-03-09 17:38:13 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=95), 246, -53, 0x98bf, 255, 255, b'\x18\x90\n\x00\x00\x10\x00']
2021-03-09 17:38:13 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=144 command_id=Command.Report_Attributes>
2021-03-09 17:38:13 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.false>)]]
2021-03-09 17:38:13 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=0
2021-03-09 17:38:15 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2511b157546f15b658924a24ab5593499c2ed36752569874f3def183fc7e2fa6eb3dfd6f3067c4d909c57e'
2021-03-09 17:38:15 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:38:15 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000060f4ccbf98ffff0e18920a0000100100f02300bf980302'
2021-03-09 17:38:15 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=96), 244, -52, 0x98bf, 255, 255, b'\x18\x92\n\x00\x00\x10\x01\x00\xf0#\x00\xbf\x98\x03']
2021-03-09 17:38:15 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=146 command_id=Command.Report_Attributes>
2021-03-09 17:38:15 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>), Attribute(attrid=61440, value=<TypeValue type=uint32_t, value=60342016>)]]
2021-03-09 17:38:15 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=1, 61440=60342016
2021-03-09 17:38:15 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3511b157546f15b658924a24ab5593499c2fd16752569874fadef083fc7e2fa6e986867e'
2021-03-09 17:38:15 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:38:15 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401060001010001000061f6ccbf98ffff0718930a0000100102'
2021-03-09 17:38:15 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=97), 246, -52, 0x98bf, 255, 255, b'\x18\x93\n\x00\x00\x10\x01']
2021-03-09 17:38:15 DEBUG (MainThread) [zigpy.zcl] [0x98bf: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=147 command_id=Command.Report_Attributes>
2021-03-09 17:38:15 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2021-03-09 17:38:15 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x0006] Attribute report received: on_off=1
2021-03-09 17:38:16 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:38:16 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'54162157542f3ca87e'
2021-03-09 17:38:16 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4616a157542f70547e'
2021-03-09 17:38:16 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:38:16 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:38:26 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:38:26 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'65172157542ffcd57e'
2021-03-09 17:38:26 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5717a157542f85217e'
2021-03-09 17:38:26 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:38:26 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:38:30 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6717b157546f15b658984a27ab5593499c2cdc6a52569874f7dec183a97e06a7ebcdde6df4797e'
2021-03-09 17:38:30 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:38:30 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c0002010001000062fbc1bf98ffff0a18a20a5500390000000002'
2021-03-09 17:38:30 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=98), 251, -63, 0x98bf, 255, 255, b'\x18\xa2\nU\x009\x00\x00\x00\x00']
2021-03-09 17:38:30 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=162 command_id=Command.Report_Attributes>
2021-03-09 17:38:30 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL request 0x000a: [[Attribute(attrid=85, value=<TypeValue type=Single, value=0.0>)]]
2021-03-09 17:38:30 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] Attribute report received: present_value=0.0
2021-03-09 17:38:36 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:38:36 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'77142157542f83c37e'
2021-03-09 17:38:36 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7014a157542f96ba7e'
2021-03-09 17:38:36 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:38:36 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:38:39 ERROR (stream_worker) [homeassistant.components.stream.worker] Error demuxing stream:
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0014b157546f15b6589e4a24ab5593499c2ddd6b52569874f8d6cc89fc7e3d325d7e'
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:38:41 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010a0001010001000063fac0bf98ffff0510af00000002'
2021-03-09 17:38:41 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=99), 250, -64, 0x98bf, 255, 255, b'\x10\xaf\x00\x00\x00']
2021-03-09 17:38:41 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x000a] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=175 command_id=Command.Read_Attributes>
2021-03-09 17:38:41 DEBUG (MainThread) [zigpy.zcl] [0x98bf:1:0x000a] ZCL request 0x0000: [[0]]
2021-03-09 17:38:41 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x98BF, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=175), 49, b"\x18\xaf\x01\x00\x00\x00\xe2q\xd4\xd9'")
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'01152157541e150dc1904b2faa5493099d4e2704dcc57f24fcc663891e0feb7d5eccb5517e'
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1115a157541e158419c97e'
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:38:41 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 52 (sendUnicast) received: b'0036'
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2115b1575415150dc1904b2faa5493099d4e279ddcce67d0987e'
2021-03-09 17:38:41 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:38:41 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 63 (messageSentHandler) received: b'00bf9804010a0001014001000036310000'
2021-03-09 17:38:41 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 39103, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=54), 49, <EmberStatus.SUCCESS: 0>, b'']
2021-03-09 17:38:46 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:38:46 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d337d3a2157542f15d27e'
2021-03-09 17:38:46 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'321aa157542fb8427e'
2021-03-09 17:38:46 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e'
2021-03-09 17:38:46 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:38:46 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'421ab157546f15b658984a27ab5593499c2adb6a52569874f7ded783a97e06a7ebcdde6d114e7e'
2021-03-09 17:38:46 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e'
2021-03-09 17:38:46 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c0002010001000064fcc1bf98ffff0a18b40a5500390000000002'
2021-03-09 17:38:46 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=100), 252, -63, 0x98bf, 255, 255, b'\x18\xb4\nU\x009\x00\x00\x00\x00']
2021-03-09 17:38:46 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=180 command_id=Command.Report_Attributes>
2021-03-09 17:38:46 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL request 0x000a: [[Attribute(attrid=85, value=<TypeValue type=Single, value=0.0>)]]
2021-03-09 17:38:46 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] Attribute report received: present_value=0.0
2021-03-09 17:38:56 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:38:56 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'251b2157542f1dee7e'
2021-03-09 17:38:56 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'531ba157542f08ab7e'
2021-03-09 17:38:56 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e'
2021-03-09 17:38:56 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''
2021-03-09 17:39:02 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'631bb157546f15b658984a27ab5593499c2bdc6952569874f7dea683a97e06a7ebcdde6d88977e'
2021-03-09 17:39:02 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e'
2021-03-09 17:39:02 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'0004010c0002010001000065fbc2bf98ffff0a18c50a5500390000000002'
2021-03-09 17:39:02 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=12, sourceEndpoint=2, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=101), 251, -62, 0x98bf, 255, 255, b'\x18\xc5\nU\x009\x00\x00\x00\x00']
2021-03-09 17:39:02 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=197 command_id=Command.Report_Attributes>
2021-03-09 17:39:02 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] ZCL request 0x000a: [[Attribute(attrid=85, value=<TypeValue type=Single, value=0.0>)]]
2021-03-09 17:39:02 DEBUG (MainThread) [zigpy.zcl] [0x98bf:2:0x000c] Attribute report received: present_value=0.0
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'731bb157546f15b658964e24ab5593499c11d863ff229874f5dec683fc7e16e6e1d4db7e'
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e'
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'00040102040101000100005fffc812ecffff0818a50a000029410a'
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=1026, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=95), 255, -56, 0xec12, 255, 255, b'\x18\xa5\n\x00\x00)A\n']
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=165 command_id=Command.Report_Attributes>
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=2625>)]]
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0402] Attribute report received: measured_value=2625
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'031bb157546f15b658914e24ab5593499c2ed863ff229874f5dec583fc7e1ebef30fc97e'
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e'
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'131bb157546f15b658974e24ab5593499c2fd863ff229874ecdec483fc7e164ae8d9de4770efc7f291f58e487e'
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e'
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401050401010001000060ffc812ecffff0818a60a0000211918'
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=1029, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=96), 255, -56, 0xec12, 255, 255, b'\x18\xa6\n\x00\x00!\x19\x18']
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0405] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=166 command_id=Command.Report_Attributes>
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0405] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=6169>)]]
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0405] Attribute report received: measured_value=6169
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 69 (incomingMessageHandler) received: b'000401030401010001000061ffc812ecffff1118a70a000029ed03140028ff1000294427'
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=1027, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=97), 255, -56, 0xec12, 255, 255, b"\x18\xa7\n\x00\x00)\xed\x03\x14\x00(\xff\x10\x00)D'"]
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0403] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=167 command_id=Command.Report_Attributes>
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0403] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=1005>), Attribute(attrid=20, value=<TypeValue type=int8s, value=-1>), Attribute(attrid=16, value=<TypeValue type=int16s, value=10052>)]]
2021-03-09 17:39:06 DEBUG (MainThread) [zigpy.zcl] [0xec12:1:0x0403] Attribute report received: measured_value=1005, 20=-1, 16=10052
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.ezsp.protocol] Send command nop: ()
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'327d382157542f21f97e'
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2418a157542f6ba47e'
2021-03-09 17:39:06 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e'
2021-03-09 17:39:06 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame 5 (nop) received: b''

pulled from the attached logs:

2021-03-09 17:37:44 INFO (MainThread) [zigpy.endpoint] [0x98bf:100] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=100, profile=260, device_type=263, device_version=2, input_clusters=[15], output_clusters=[15, 4])
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.plug (00:15:8d:00:02:8b:71:cf)
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.plug.Plug'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 3} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rwl021.PhilipsRWL021'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rwl020.PhilipsRWL020'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rom001.PhilipsROM001'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'bellows.zigbee.application.EZSPCoordinator'>
2021-03-09 17:37:44 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 2, 3, 100}
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x98BF:00:15:8d:00:02:8b:71:cf entering async_device_initialized - is_new_join: True
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x98BF:00:15:8d:00:02:8b:71:cf has joined the ZHA zigbee network
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x98BF](lumi.plug): started configuration
2021-03-09 17:37:44 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x98BF:ZDO](lumi.plug): 'async_configure' stage succeeded

From the device page:

Device info
lumi.plug
by LUMI
Zigbee Coordinator
Zigbee info
IEEE: 00:15:8d:00:02:8b:71:cf
Nwk: 0x98bf
Device Type: Router
LQI: 253
RSSI: -56
Last Seen: 2021-03-09T17:41:10
Power Source: Mains

Device signature:

{
  "node_descriptor": "NodeDescriptor(byte1=1, byte2=64, mac_capability_flags=142, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=0, maximum_outgoing_transfer_size=100, descriptor_capability_field=0)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x0051",
      "in_clusters": [
        "0x0000",
        "0x0001",
        "0x0002",
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x000a",
        "0x0010"
      ],
      "out_clusters": [
        "0x000a",
        "0x0019"
      ]
    },
    "2": {
      "profile_id": 260,
      "device_type": "0x0009",
      "in_clusters": [
        "0x000c"
      ],
      "out_clusters": [
        "0x0004",
        "0x000c"
      ]
    },
    "3": {
      "profile_id": 260,
      "device_type": "0x0053",
      "in_clusters": [
        "0x000c"
      ],
      "out_clusters": [
        "0x000c"
      ]
    },
    "100": {
      "profile_id": 260,
      "device_type": "0x0107",
      "in_clusters": [
        "0x000f"
      ],
      "out_clusters": [
        "0x0004",
        "0x000f"
      ]
    }
  },
  "manufacturer": "LUMI",
  "model": "lumi.plug",
  "class": "zigpy.device.Device"