Aqara Lumi Smart Plug EU missbehaving using ZHA #67644

larsemil · 2022-03-04T15:02:48Z

The problem

When I add the Aqara Lumi Smart plug EU to homeassistant using ZHA it doesnt work all out.
Here is some info

  "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=11264, maximum_outgoing_transfer_size=100, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x0051",
      "in_clusters": [
        "0x0000",
        "0x0002",
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0009",
        "0x0702",
        "0x0b04"
      ],
      "out_clusters": [
        "0x000a",
        "0x0019"
      ]
    },
    "21": {
      "profile_id": 260,
      "device_type": "0x0009",
      "in_clusters": [
        "0x000c"
      ],
      "out_clusters": [
        "0x0004",
        "0x000c"
      ]
    },
    "242": {
      "profile_id": 41440,
      "device_type": "0x0061",
      "in_clusters": [],
      "out_clusters": [
        "0x0021"
      ]
    }
  },
  "manufacturer": "LUMI",
  "model": "lumi.plug.maeu01",
  "class": "zhaquirks.xiaomi.aqara.plug_mmeu01.Plug"
}

And here is the result:

What version of Home Assistant Core has the issue?

core-2022.3.1

What was the last working version of Home Assistant Core?

No response

What type of installation are you running?

Home Assistant OS

Integration causing the issue

ZHA

Link to integration documentation on our website

No response

Diagnostics information

No response

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

Additional information

No response

The text was updated successfully, but these errors were encountered:

probot-home-assistant · 2022-03-04T15:15:58Z

Hey there @dmulcahey, @Adminiuga, mind taking a look at this issue as it has been labeled with an integration (zha) you are listed as a code owner for? Thanks!
_{^{(message by CodeOwnersMention)}}

zha documentation
zha source
_{^{(message by IssueLinks)}}

TekFan · 2022-03-13T15:55:14Z

Same problem here using a Conbee II.
But it's even worse than that: the plug doesn't update anything, even toggling the plug locally doesn't update HA.
But toggling from HA works (as long as the plug is still available)
After the configured ZHA timeout, the plug becomes unavailable.
So basically useless which is problematic for a routing device (BTW, the routing doesn't work either, which fortunately prevents it to become involved in the mesh and messing with other nodes)
The strange thing is that another plug with the same firmware (20) which was integrated before 2022.3.x is working perfectly.
So I guess ZHA integration. is broken since 2022.3.x and adding any new plug is now doomed to fail.
At least the integration of new plugs is broken, existing ones still work ok.
Another problem is that the plug cannot be deleted, it comes back almost immediately. After reboot with plug disconnected, it disappear, but as soon as the plug is back in the outlet, it comes back magically in HA even if the plug has been factory reset...

I don't know yet if this is a general problem in ZHA or only with this plug, maybe only this device "quirks" have been modified but this needs some attention.

jivemaster2005 · 2022-03-14T09:34:57Z

Same problem here with Conbee II and latest HA

oSourceSE · 2022-03-14T14:02:20Z

Hi, i got same issue with both of mine, have tried to reset one of them but same result, as seen in log information below it's like it do not poll it fast enough or something is not picking up the status correctly anymore.

The one that is the most broken do not show firmware version at all for me.

I run with the ConBee II in a Raspberry PI.

What version of Home Assistant Core has the issue?
core-2022.3.4

What was the last working version of Home Assistant Core?
2021.12.10

What type of installation are you running?
Home Assistant OS

Integration causing the issue
ZHA

Diagnostics information
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T19:51:54.801504+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T19:55:30.908021+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T19:58:19.805682+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:01:58.045415+01:00>>
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:04:44.814641+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:07:59.484045+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:11:09.819717+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:16:23.924023+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:18:51.818421+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:19:19.890286+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:25:16.828634+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:28:31.989299+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:31:41.836868+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:35:54.638932+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:38:06.836624+01:00
new_state=<state switch.smartplug_extra_on_off=on; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:41:04.763282+01:00
new_state=<state switch.smartplug_extra_on_off=unavailable; friendly_name=SmartPlug_Extra On_Off @ 2022-03-13T20:44:31.849235+01:00

TekFan · 2022-03-28T14:59:18Z

So, we are almost a month later and there is still no comment from the developers...
I'm a bit worried now because I'm left with unusable devices (or at least very limited) out of their return window.

In the meantime I ordered a basic Sonoff equivalent to see it works and I think a warning should be issued somewhere to prevent others to be caught.

jivemaster2005 · 2022-03-28T17:24:46Z

Yeah, not even a comment :-(

Sad.

dmulcahey · 2022-03-28T17:28:45Z

zigpy/zha-device-handlers#1337 Discussion can be found here for both the MAEU01 and the MMEU01. It would be cool if someone w/ dev experience that has one of these can help out. I am tied up with other things atm and I don’t have the devices in question.

Digipix · 2022-03-28T18:03:29Z

I have some (very small) dev experience, but with some guidance I might be able to help.

I bought a MAEU01 in december 2021, that has worked flawlessly. Now in march 2022 I bought two new MAEU01 and I have the same problems. The watt-measurement may display for a while, but the gets unavailable. I have tested on both a RPI3 and a RPI4

Old working MAEU01 :

New first:

New second:

husjon · 2022-03-29T11:58:28Z

I'm comming from zigpy/zha-device-handlers#1337

When initially adding these plugs I was running Homeassistant 2022.02.
I've seen a couple of people mentioning that these plugs seem to work with earlier versions of Homeassistant.

I've spun up a new instance of homeassistant:2021.12 and after adding the plugs again the plugs now shows electrical_measurement and smartenergy_metering_summation_delivered as expected.

I'm currently trying to narrow this down a bit more.

husjon · 2022-03-29T12:09:10Z

After a bit more prodding around the issue can be seen as soon as I'm spinning up an instance using homeassistant:2022.02.0.

Same plug but now using a more recent homeassistant version.
~~I'm not sure if the quirks were just hidden in previous versions of homeassistant, but they are now visible in 2022.~~

docker image version / dates hoping this helps narrow thing down even further.
2021.12

2022.02.0

TekFan · 2022-03-29T13:03:42Z

Very interesting observation about the Quirk.
So the solution would be to remove the recently added Quirk, at least until it's fixed, because January situation was way better.
The plug I installed back in January is still working perfectly today.
Or, because it seems to only be a problem at pairing time, is it possible to tweak the ZHA database to copy the working plug config to the new plug, less its address and network ID ?

husjon · 2022-03-29T13:23:10Z

So after my findings, I tried removing the signature lookup for the maeu01 (effectively reverting: zigpy/zha-device-handlers@b0bbbfb which was one of the changes in zha-device-handlers earlier this year) in my 2022.02 install, but it did still not register correctly.

The quirk assignment is no longer there, but still the device is not configured properly.

dmulcahey · 2022-03-29T13:37:18Z

So after my findings, I tried removing the signature lookup for the maeu01 (effectively reverting: zigpy/zha-device-handlers@b0bbbfb which was one of the changes in zha-device-handlers earlier this year) in my 2022.02 install, but it did still not register correctly.

The quirk assignment is no longer there, but still the device is not configured properly.

Did you remove and re-join the plug after the change?

husjon · 2022-03-29T14:15:23Z

Did you remove and re-join the plug after the change?

Thanks for reminding me, I might have forgotten.
I tried re-join a couple of my plugs after removing the signature lookup and restarting homeassistant (2022.02) and they now look like this, the same as 2021.12.
The wattage and cumulative sum are now updating automatically.

husjon · 2022-03-29T15:17:34Z

To add to this.
The only information form the plug that is currently wrong (which I forgot to add in the previous screenshots) is the Device temperature as seen in the image below

Querying the cluster, the actual temperature of the device is 29C

Digipix · 2022-03-29T19:08:47Z

Hi! I have struggled with the same problems with my lumi.plug.maeu01.
Before:

Now I downgraded ZHA-QUIRKS to try to get rid of the problems
First I had a look here and decided to downgrade to 0.0.65 https://pypi.org/project/zha-quirks/#history

How to downgrade:

go to Add-on Store and add repository https://github.com/home-assistant/hassio-addons-development
Install "Custom deps deployment" addon
Update config:

pypi:
  - zha-quirks==0.0.65
apk: []

Remove the lumi.plug.maeu01
Start the addon
Check the log
restart RPI
add lumi.plug.maeu01
Reconfigure Device
See if this helps. NO WARRANTY!!!

TekFan · 2022-03-30T11:50:11Z

Brilliant !
That seems to be the solution until a fixed quirk comes out.
Before I try this, I have a question: Is this downgrade canceled by a core upgrade ?
I would like to be sure that it will go back to the future updates and not be stuck to 0.0.65.

larsemil · 2022-03-30T12:07:16Z

But if that helps, we should file a bug with the zha-quirks package.

Also downgrading seems to be working.

Stefano0042 · 2022-03-31T09:51:05Z

DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.plug.maeu01 (04:cf:8c:df:3c:79:c7:87)
DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.plug_maeu01.Plug'>
DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 242, 21, 31} {1, 242}

@dmulcahey ...I'm on stuck modelling a dedicated quirk, why endpoints 21 and 31 (AnalogInput.cluster_id) are mismatching, they are both listed in the replacement area too.. ?

Stefano0042 · 2022-03-31T19:21:14Z

To add to this. The only information form the plug that is currently wrong (which I forgot to add in the previous screenshots) is the Device temperature as seen in the image below !

Querying the cluster, the actual temperature of the device is 29C !)

@husjon .. I did a try by removing lumi.plug.maeu01 model signature in parallel with post publication (zigpy/zha-device-handlers#1337 (comment)) 15 days ago, but only the on-off functionality was ok, tested nowadays (remove, reboot, add) same results. That's why I'm struggling trying to build a dedicated quirk.. (not such an easy task!), I was very prone to live without a correctly translated DeviceTemperature.

husjon · 2022-03-31T20:10:37Z

Hi @Stefano0042, that's wierd.

I'm running homeassistant as a docker instance and in my case I attached to the container and did the following:

Open /usr/local/lib/python3.9/site-packages/zhaquirks/xiaomi/aqara/plug_mmeu01.py
Commented out the lumi.plug.maeu01 on line 58.

        MODELS_INFO: [                            
            (LUMI, "lumi.plug.mmeu01"),           
        #    (LUMI, "lumi.plug.maeu01"),          
        ],

Restart the container
Remove the device from the ZHA integration
Re-join the device.

After these steps, the plug went from only allowing power switching to also reporting electrical_measurement and smartenergy_metering_summation_delivered, as seen in my previous comments.

Before: #67644 (comment)
After: #67644 (comment)

The after is without anu quirk assigned.

Hope this helps.

On a sidenote, I was thinking of trying to setup the zha-quirks environment so that I can debug this a bit closer, I might be able to take a look over the weekend, but I can't promise anything yet.

Stefano0042 · 2022-03-31T20:17:27Z

..yes I can see you commented out/removed, the model signature, that lead to 'no quirk' of course, I did it in the same way, but with a different end results, so weird.

andersaagaard · 2022-03-31T20:52:03Z

New quirk version has been released, 0.0.69
https://pypi.org/project/zha-quirks/

But i don't find any information on what is changed from previous version ?

Does anybody know how these new versions are updated in the ha version ?

Finally, does anybody know where to see running ha's quirk version ?

dmulcahey · 2022-03-31T21:07:48Z

This issue has not been addressed yet

torge70 · 2022-04-02T07:53:52Z

I can confirm that this worked on my newest Aqara LUMI EU plugs.
Thanks to @Digipix!

How to downgrade:

go to Add-on Store and add repository https://github.com/home-assistant/hassio-addons-development

Install "Custom deps deployment" addon

Update config:
pypi:

zha-quirks==0.0.65
apk: []

Remove the lumi.plug.maeu01

Start the addon

Check the log

restart RPI (i restarted the whole HomeAssistant VM)

add lumi.plug.maeu01

Reconfigure Device

See if this helps. NO WARRANTY!!!

jims-code · 2022-04-03T19:21:19Z

The file plug_mmeu01.py had been updated on Jan 6 2022. Is the smart plug misbehaviour seen using the old version too?

zigpy/zha-device-handlers#1252

husjon · 2022-04-03T21:44:29Z

Last working was homeassistant:2021.12 reference: #67644 (comment)

Stefano0042 · 2022-04-04T06:18:14Z

That’s fine, we have to consider too that through a well engineered new quirks, we can do much more.

MrGlad8 · 2022-04-06T12:17:18Z

Same here... doesnt work!
So the solution is to downgrade?

Inervo · 2022-04-06T14:52:06Z

I've tried downgrading on my side, I get the entities electrical_measurement and smartenergy_metering_summation_delivered, but the value of both stays on 0, no matter what i do, with.
I've tried to downgrade the zhaquirks to 0.0.65, i even tried to downgrade docker image to 2021.12, same behavior. When i try to check the cluster, I get the same values.
Any idea why it doesn't work on my side?

Update : If i try to:
Downgrade to 2021.12, add the plug, "reconfigure device", then upgrade to current version (2022.3.8), I get the electrical_measurement with a value, but it doesn't show the right value (187W instead of 1870W).
smartenergy_metering_summation_delivered seems to stay at 0

MrGlad8 · 2022-04-06T16:48:54Z

Cool. I did as Digipix said and it worked, kinda.. but the "identify" was still "unknown" the class said "zigpy.device.Device" in Signature at bottom.
After tips from Puddly on Discord he told me to now after this to uninstall the addon, and then remove the deps folder in /config and then reboot RPI.
After I've done that the class said "class": "zhaquirks.xiaomi.aqara.plug_mmeu01.Plug" and the identify worked , and everything else.

So thanks for all of you :)

andersaagaard · 2022-04-06T16:52:01Z

What does the identify entity do ?

MrGlad8 · 2022-04-06T16:52:55Z

What does the identify entity do ?

Good question, but now it says "2022-04-06T16:19:32.301496+00:00" instead of "Unknown" :P

andersaagaard · 2022-04-06T16:56:11Z

I was more curious about the function in general - what is the purpose of this function ?

husjon · 2022-04-06T22:10:58Z

I was more curious about the function in general - what is the purpose of this function ?

Identify sets the device in identification mode, f.ex a lamp/bulb will blink for a couple of seconds to indicate which physical lamp/bulb it is.
I guess on these plugs, the LED with blink a few times.

torge70 · 2022-04-08T13:32:23Z

I can confirm that this worked on my newest Aqara LUMI EU plugs. Thanks to @Digipix!

How to downgrade:

go to Add-on Store and add repository https://github.com/home-assistant/hassio-addons-development

Install "Custom deps deployment" addon

Update config:
pypi:

zha-quirks==0.0.65
apk: []

Remove the lumi.plug.maeu01

Start the addon

Check the log

restart RPI (i restarted the whole HomeAssistant VM)

add lumi.plug.maeu01

Reconfigure Device

See if this helps. NO WARRANTY!!!

The plug is partly working but without any measurement using procedure above. But this quirk does not work with release 2022.4 anymore. Had to remove 0.0.65.

torge70 · 2022-04-08T13:33:36Z

Can I produce any logs that will help debugging the issue?

dmulcahey · 2022-04-08T13:37:31Z

folks... PLEASE STOP MESSING WITH DEPENDENCIES. You are risking tanking your entire system for a single device. IMO that isn't worth the risk and it is killing us support wise trying to mitigate the fallout from users modifying things they shouldn't modify.

dmulcahey · 2022-04-09T13:23:53Z

zigpy/zha-device-handlers#1337 (comment)

If someone with this device can test this with a local quirk I would appreciate it. Again... Please do not use the custom deps add-on or modify your containers. Also, after testing remove the local quirk

SkywiperSolutions · 2022-04-10T15:50:30Z

Same issue here. But I have two devices. One was added some months ago and power/energy is working (temperature is Unavailable). The other one was added today and all sensors are Unavailable.
ZHA on Core 2022.4.1 and both devices have Firmware: 0x00000020.

Stefano0042 · 2022-04-10T21:35:15Z

@dmulcahey here is the whole pairing log debug.
2022-04-10 23:00:33 INFO (MainThread) [zigpy_znp.zigbee.application] Permitting joins for 60 seconds
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.MgmtPermitJoinReq.Req(AddrMode=<AddrMode.Broadcast: 15>, Dst=0xFFFC, Duration=60, TCSignificance=0)
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MgmtPermitJoinReq.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MgmtPermitJoinRsp.Callback(Src=0x0000, Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x0000, IsBroadcast=<Bool.false: 0>, ClusterId=32822, SecurityUse=0, TSN=4, MacDst=0x0000, Data=b'\x00')
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.Broadcast: 15>, address=0xFFFC), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=54, TSN=3, Options=<TransmitOptions.NONE: 0>, Radius=0, Data=b'\x03\x3C\x00')
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_rsp: [<Status.SUCCESS: 0>]
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy.zdo] [0x0000:zdo] No handler for ZDO request:ZDOCmd.Mgmt_Permit_Joining_rsp([<Status.SUCCESS: 0>])
2022-04-10 23:00:33 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SrcRtgInd.Callback(DstAddr=0xE249, Relays=[0xF9B5])
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0xE249, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=83, SecurityUse=<Bool.false: 0>, TimeStamp=11241965, TSN=0, Data=b'\x38\x43\x0A\x00\x00\x10\x01', MacSrcAddr=0xF9B5, MsgResultRadius=28)
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy.zcl] [0xE249:1:0x0006] Received ZCL frame: b'\x38\x43\x0A\x00\x00\x10\x01'
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy.zcl] [0xE249:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=1, *is_cluster=False, *is_general=True), tsn=67, command_id=10, *is_reply=True)
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy.zcl] [0xE249:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy.zcl] [0xE249:1:0x0006] Received command 0x0A (TSN 67): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.true: 1>))])
2022-04-10 23:00:34 DEBUG (MainThread) [zigpy.zcl] [0xE249:1:0x0006] Attribute report received: on_off=<Bool.true: 1>
2022-04-10 23:00:35 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.TCDevInd.Callback(SrcNwk=0x473F, SrcIEEE=04:cf:8c:df:3c:79:c7:87, ParentNwk=0x0000)
2022-04-10 23:00:35 INFO (MainThread) [zigpy_znp.zigbee.application] TC device join: ZDO.TCDevInd.Callback(SrcNwk=0x473F, SrcIEEE=04:cf:8c:df:3c:79:c7:87, ParentNwk=0x0000)
2022-04-10 23:00:35 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteDisc.Req(Dst=0x473F, Options=<RouteDiscoveryOptions.UNICAST: 0>, Radius=30)
2022-04-10 23:00:35 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteDisc.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.EndDeviceAnnceInd.Callback(Src=0x473F, NWK=0x473F, IEEE=04:cf:8c:df:3c:79:c7:87, Capabilities=<MACCapabilities.AllocateShortAddrDuringAssocNeeded|RXWhenIdle|MainsPowered|Router: 142>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.true: 1>, ClusterId=19, SecurityUse=0, TSN=211, MacDst=0xFFFF, Data=b'\x3F\x47\x87\xC7\x79\x3C\xDF\x8C\xCF\x04\x8E')
2022-04-10 23:00:36 INFO (MainThread) [zigpy_znp.zigbee.application] ZDO device announce: nwk=0x473F, ieee=04:cf:8c:df:3c:79:c7:87, capabilities=142
2022-04-10 23:00:36 INFO (MainThread) [zigpy.application] New device 0x473f (04:cf:8c:df:3c:79:c7:87) joined the network
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.device] [0x473f] Scheduling initialization
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device from ep 0 to ep 0, cluster 19: b'\xd3?G\x87\xc7y<\xdf\x8c\xcf\x04\x8e'
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.zdo] [0x473f:zdo] ZDO request ZDOCmd.Device_annce: [0x473F, 04:cf:8c:df:3c:79:c7:87, 142]
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Requesting 'Node Descriptor'
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.util] Tries remaining: 2
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.device] [0x473f] Extending timeout for 0x04 request
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=2, TSN=4, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x04\x3F\x47')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.NodeDescRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>, NWK=0x473F, NodeDescriptor=NullableNodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=11264, maximum_outgoing_transfer_size=100, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False))
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32770, SecurityUse=0, TSN=4, MacDst=0x0000, Data=b'\x00\x3F\x47\x01\x40\x8E\x5F\x11\x7F\x64\x00\x00\x2C\x64\x00\x00')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device from ep 0 to ep 0, cluster 32770: b'\x04\x00?G\x01@\x8e_\x11\x7fd\x00\x00,d\x00\x00'
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4447, maximum_buffer_size=127, maximum_incoming_transfer_size=100, server_mask=11264, maximum_outgoing_transfer_size=100, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Discovering endpoints
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=5, TSN=5, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x05\x3F\x47')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ActiveEpRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>, NWK=0x473F, ActiveEndpoints=[1, 242])
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32773, SecurityUse=0, TSN=5, MacDst=0x0000, Data=b'\x00\x3F\x47\x02\x01\xF2')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device from ep 0 to ep 0, cluster 32773: b'\x05\x00?G\x02\x01\xf2'
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Discovered endpoints: [1, 242]
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=242 in=[] out=[] status=<Status.NEW: 0>>]
2022-04-10 23:00:36 INFO (MainThread) [zigpy.endpoint] [0x473f:1] Discovering endpoint information
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=4, TSN=6, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x06\x3F\x47\x01')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SimpleDescRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>, NWK=0x473F, SimpleDescriptor=SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=81, device_version=1, input_clusters=[0, 2, 3, 4, 5, 6, 64704], output_clusters=[10, 25]))
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32772, SecurityUse=0, TSN=6, MacDst=0x0000, Data=b'\x00\x3F\x47\x1A\x01\x04\x01\x51\x00\x01\x07\x00\x00\x02\x00\x03\x00\x04\x00\x05\x00\x06\x00\xC0\xFC\x02\x0A\x00\x19\x00')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device from ep 0 to ep 0, cluster 32772: b'\x06\x00?G\x1a\x01\x04\x01Q\x00\x01\x07\x00\x00\x02\x00\x03\x00\x04\x00\x05\x00\x06\x00\xc0\xfc\x02\n\x00\x19\x00'
2022-04-10 23:00:36 INFO (MainThread) [zigpy.endpoint] [0x473f:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=81, device_version=1, input_clusters=[0, 2, 3, 4, 5, 6, 64704], output_clusters=[10, 25])
2022-04-10 23:00:36 INFO (MainThread) [zigpy.endpoint] [0x473f:242] Discovering endpoint information
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.util] Tries remaining: 3
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=4, TSN=7, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x07\x3F\x47\xF2')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.SimpleDescRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>, NWK=0x473F, SimpleDescriptor=SizePrefixedSimpleDescriptor(endpoint=242, profile=41440, device_type=97, device_version=0, input_clusters=[], output_clusters=[33]))
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32772, SecurityUse=0, TSN=7, MacDst=0x0000, Data=b'\x00\x3F\x47\x0A\xF2\xE0\xA1\x61\x00\x00\x00\x01\x21\x00')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device from ep 0 to ep 0, cluster 32772: b'\x07\x00?G\n\xf2\xe0\xa1a\x00\x00\x00\x01!\x00'
2022-04-10 23:00:36 INFO (MainThread) [zigpy.endpoint] [0x473f:242] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=242, profile=41440, device_type=97, device_version=0, input_clusters=[], output_clusters=[33])
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=8, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5])
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=0, TSN=8, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x08\x00\x04\x00\x05\x00')
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=8)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=0, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11398848, TSN=0, Data=b'\x18\x08\x01\x04\x00\x00\x42\x04\x4C\x55\x4D\x49\x05\x00\x00\x42\x10\x6C\x75\x6D\x69\x2E\x70\x6C\x75\x67\x2E\x6D\x61\x65\x75\x30\x31', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Received ZCL frame: b'\x18\x08\x01\x04\x00\x00\x42\x04\x4C\x55\x4D\x49\x05\x00\x00\x42\x10\x6C\x75\x6D\x69\x2E\x70\x6C\x75\x67\x2E\x6D\x61\x65\x75\x30\x31'
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=8, command_id=1, *is_reply=True)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='LUMI')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='lumi.plug.maeu01'))])
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Read model 'lumi.plug.maeu01' and manufacturer 'LUMI' from <Endpoint id=1 in=[basic:0x0000, device_temperature:0x0002, identify:0x0003, groups:0x0004, scenes:0x0005, on_off:0x0006, manufacturer_specific:0xFCC0] out=[time:0x000A, ota:0x0019] status=<Status.ZDO_INIT: 1>>
2022-04-10 23:00:36 INFO (MainThread) [zigpy.device] [0x473f] Discovered basic device information for
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.application] Device is initialized
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.plug.maeu01 (04:cf:8c:df:3c:79:c7:87)
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'plug_mmeu01.PlugMAEU01'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because input cluster mismatch on at least one endpoint
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.plug_mmeu01.Plug'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because input cluster mismatch on at least one endpoint
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'bellows.zigbee.application.EZSPCoordinator'>
2022-04-10 23:00:36 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1} {1, 242}
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'button' component -> 'ZHAIdentifyButton' using ['identify']
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'DeviceTemperature' using ['device_temperature']
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'RSSISensor' using ['basic']
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'LQISensor' using ['basic']
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x473F:04:cf:8c:df:3c:79:c7:87 entering async_device_initialized - is_new_join: True
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x473F:04:cf:8c:df:3c:79:c7:87 has joined the ZHA zigbee network
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.device] 0x473F: started configuration
2022-04-10 23:00:36 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] 0x473F:ZDO: 'async_configure' stage succeeded
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (04:cf:8c:df:3c:79:c7:87, 1, 0, 4, 'LUMI') params: FOREIGN KEY constraint failed
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0003]: finished channel configuration
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0000]: finished channel configuration
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (04:cf:8c:df:3c:79:c7:87, 1, 0, 5, 'lumi.plug.maeu01') params: FOREIGN KEY constraint failed
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: ZDO.ExtRouteChk.Req(Dst=0x473F, RtStatus=<RouteStatus.ACTIVE: 1>, Options=<RouteOptions.NO_ROUTE_CACHE|MTO_ROUTE: 3>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.ExtRouteChk.Rsp(Status=<RoutingStatus.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=33, TSN=9, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x09\x87\xC7\x79\x3C\xDF\x8C\xCF\x04\x01\x06\x00\x03\xBE\xF5\xC9\x24\x00\x4B\x12\x00\x01')
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=33, TSN=10, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x0A\x87\xC7\x79\x3C\xDF\x8C\xCF\x04\x01\x02\x00\x03\xBE\xF5\xC9\x24\x00\x4B\x12\x00\x01')
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=0, DstPanId=0x0000, SrcEndpoint=0, ClusterId=33, TSN=11, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x0B\x87\xC7\x79\x3C\xDF\x8C\xCF\x04\x01\xC0\xFC\x03\xBE\xF5\xC9\x24\x00\x4B\x12\x00\x01')
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.BindRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32801, SecurityUse=0, TSN=9, MacDst=0x0000, Data=b'\x00')
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: bound 'on_off' cluster: Status.SUCCESS
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=12, command_id=<GeneralCommand.Configure_Reporting: 6>, *is_reply=False)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Sending request: Configure_Reporting(config_records=[AttributeReportingConfig(direction=0, attrid=0x0000, datatype=16, min_interval=0, max_interval=900, reportable_change=1)])
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=12, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x0C\x06\x00\x00\x00\x10\x00\x00\x84\x03')
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.BindRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32801, SecurityUse=0, TSN=10, MacDst=0x0000, Data=b'\x00')
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: bound 'device_temperature' cluster: Status.SUCCESS
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=13, command_id=<GeneralCommand.Configure_Reporting: 6>, *is_reply=False)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Sending request: Configure_Reporting(config_records=[AttributeReportingConfig(direction=0, attrid=0x0000, datatype=41, min_interval=30, max_interval=900, reportable_change=50)])
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=2, TSN=13, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x0D\x06\x00\x00\x00\x29\x1E\x00\x84\x03\x32\x00')
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=12)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11418285, TSN=0, Data=b'\x18\x0C\x07\x00', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Received ZCL frame: b'\x18\x0C\x07\x00'
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=12, command_id=7, *is_reply=True)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame: OnOff:Configure_Reporting_rsp(status_records=[ConfigureReportingResponseRecord(status=0)])
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=13)
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: Successfully configured reporting for '{'on_off': (0, 900, 1)}' on 'on_off' cluster: [ConfigureReportingResponseRecord(status=0)]
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: finished channel configuration
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0019]: finished channel configuration
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=2, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11419855, TSN=0, Data=b'\x18\x0D\x07\x8C\x00\x00\x00', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Received ZCL frame: b'\x18\x0D\x07\x8C\x00\x00\x00'
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=13, command_id=7, *is_reply=True)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Decoded ZCL frame: DeviceTemperature:Configure_Reporting_rsp(status_records=[ConfigureReportingResponseRecord(status=140, direction=0, attrid=0)])
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: Successfully configured reporting for 'set()' on 'device_temperature' cluster
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: Failed to configure reporting for '['current_temperature']' on 'device_temperature' cluster: [ConfigureReportingResponseRecord(status=140, direction=0, attrid=0)]
2022-04-10 23:00:37 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: finished channel configuration
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=64704, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=11448628, TSN=0, Data=b'\x1C\x5F\x11\x00\x0A\xF7\x00\x41\x39\x64\x10\x00\x03\x28\x13\x98\x39\x00\x00\x00\x00\x95\x39\x00\x00\x00\x00\x96\x39\x00\xE0\x17\x45\x97\x39\x00\x00\x00\x00\x05\x21\x01\x00\x9A\x20\x10\x08\x21\x20\x01\x09\x21\x00\x12\x0B\x20\x00\x9B\x10\x01\x0F\x23\x00\x00\x2A\xAC', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Received ZCL frame: b'\x1C\x5F\x11\x00\x0A\xF7\x00\x41\x39\x64\x10\x00\x03\x28\x13\x98\x39\x00\x00\x00\x00\x95\x39\x00\x00\x00\x00\x96\x39\x00\xE0\x17\x45\x97\x39\x00\x00\x00\x00\x05\x21\x01\x00\x9A\x20\x10\x08\x21\x20\x01\x09\x21\x00\x12\x0B\x20\x00\x9B\x10\x01\x0F\x23\x00\x00\x2A\xAC'
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, is_cluster=False, is_general=True), manufacturer=4447, tsn=0, command_id=10, is_reply=True)
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Decoded ZCL frame: ManufacturerSpecificCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x13\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x00\xe0\x17E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x08! \x01\t!\x00\x12\x0b \x00\x9b\x10\x01\x0f#\x00\x00\xac'))])
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Received command 0x0A (TSN 0): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x13\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x00\xe0\x17E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x08! \x01\t!\x00\x12\x0b \x00\x9b\x10\x01\x0f#\x00\x00\xac'))])
2022-04-10 23:00:37 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Attribute report received: 0x00F7=b'd\x10\x00\x03(\x13\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x00\xe0\x17E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x10\x08! \x01\t!\x00\x12\x0b \x00\x9b\x10\x01\x0f#\x00\x00\xac'
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=0, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11468685, TSN=0, Data=b'\x1C\x5F\x11\x01\x0A\x05\x00\x42\x10\x6C\x75\x6D\x69\x2E\x70\x6C\x75\x67\x2E\x6D\x61\x65\x75\x30\x31\x01\x00\x20\x20', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Received ZCL frame: b'\x1C\x5F\x11\x01\x0A\x05\x00\x42\x10\x6C\x75\x6D\x69\x2E\x70\x6C\x75\x67\x2E\x6D\x61\x65\x75\x30\x31\x01\x00\x20\x20'
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=1, command_id=10, *is_reply=True)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0005, value=TypeValue(type=CharacterString, value='lumi.plug.maeu01')), Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=32))])
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Received command 0x0A (TSN 1): Report_Attributes(attribute_reports=[Attribute(attrid=0x0005, value=TypeValue(type=CharacterString, value='lumi.plug.maeu01')), Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=32))])
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Attribute report received: model='lumi.plug.maeu01', app_version=32
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=10, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=11482622, TSN=0, Data=b'\x10\x02\x00\x00\x00\x02\x00', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x000a] Received ZCL frame: b'\x10\x02\x00\x00\x00\x02\x00'
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=0, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=2, command_id=0, *is_reply=False)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[0, 2])
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x000a] Received command 0x00 (TSN 2): Read_Attributes(attribute_ids=[0, 2])
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=2, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *is_reply=True)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=UTCTime, value=702939638)), ReadAttributeRecord(attrid=0x0002, status=<Status.SUCCESS: 0>, value=TypeValue(type=int32s, value=3600))])
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=10, TSN=2, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK: 32>, Radius=30, Data=b'\x18\x02\x01\x00\x00\x00\xE2\xF6\x01\xE6\x29\x02\x00\x00\x2B\x10\x0E\x00\x00')
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=2)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.BindRsp.Callback(Src=0x473F, Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Command was not handled
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: ZDO.MsgCbIncoming.Callback(Src=0x473F, IsBroadcast=<Bool.false: 0>, ClusterId=32801, SecurityUse=0, TSN=11, MacDst=0x0000, Data=b'\x00')
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0xfcc0]: bound 'manufacturer_specific' cluster: Status.SUCCESS
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0xfcc0]: finished channel configuration
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: 'async_configure' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0003]: 'async_configure' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0000]: 'async_configure' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: 'async_configure' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0xfcc0]: 'async_configure' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0019]: 'async_configure' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.device] 0x473F: completed configuration
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.device] 0x473F: stored in registry: ZhaDeviceEntry(name='LUMI lumi.plug.maeu01', ieee='04:cf:8c:df:3c:79:c7:87', last_seen=1649624438.8121629)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0003] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=14, command_id=64, *is_reply=False)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0003] Sending request: trigger_effect(effect_id=<EffectIdentifier.Okay: 2>, effect_variant=<EffectVariant.Default: 0>)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=3, TSN=14, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x01\x0E\x40\x02\x00')
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=14)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=3, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=11522182, TSN=0, Data=b'\x18\x0E\x0B\x40\x81', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0003] Received ZCL frame: b'\x18\x0E\x0B\x40\x81'
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0003] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=14, command_id=11, *is_reply=True)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0003] Decoded ZCL frame: Identify:Default_Response(command_id=64, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0003]: executed 'trigger_effect' command with args: '(2, 0)' kwargs: '{}' result: Default_Response(command_id=64, status=<Status.UNSUP_CLUSTER_COMMAND: 129>)
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.device] 0x473F: started initialization
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] 0x473F:ZDO: 'async_initialize' stage succeeded
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: initializing channel: from_cache: False
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=15, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Sending request: Read_Attributes(attribute_ids=[0])
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0003]: initializing channel: from_cache: False
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0003]: finished channel initialization
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0000]: initializing channel: from_cache: False
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0000]: finished channel initialization
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: initializing channel: from_cache: False
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=16, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Sending request: Read_Attributes(attribute_ids=[0])
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0xfcc0]: initializing channel: from_cache: False
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0xfcc0]: finished channel initialization
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0019]: initializing channel: from_cache: False
2022-04-10 23:00:38 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0019]: finished channel initialization
2022-04-10 23:00:38 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=15, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x0F\x00\x00\x00')
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=2, TSN=16, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x10\x00\x00\x00')
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=15)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11531571, TSN=0, Data=b'\x18\x0F\x01\x00\x00\x00\x10\x00', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=16)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Received ZCL frame: b'\x18\x0F\x01\x00\x00\x00\x10\x00'
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=15, command_id=1, *is_reply=True)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame: OnOff:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: finished channel initialization
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=2, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=11532867, TSN=0, Data=b'\x18\x10\x01\x00\x00\x00\x29\x13\x00', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Received ZCL frame: b'\x18\x10\x01\x00\x00\x00\x29\x13\x00'
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=16, command_id=1, *is_reply=True)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0002] Decoded ZCL frame: DeviceTemperature:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=int16s, value=19))])
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: finished channel initialization
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: 'async_initialize' stage succeeded
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0003]: 'async_initialize' stage succeeded
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0000]: 'async_initialize' stage succeeded
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0002]: 'async_initialize' stage succeeded
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0xfcc0]: 'async_initialize' stage succeeded
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0019]: 'async_initialize' stage succeeded
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.device] 0x473F: power source: Mains
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.device] 0x473F: completed initialization
2022-04-10 23:00:39 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x473F:1:0x0006]: attempting to update onoff state - from cache: False
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, is_reply=0, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=17, command_id=<GeneralCommand.Read_Attributes: 0>, *is_reply=False)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Sending request: Read_Attributes(attribute_ids=[0])
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Sending request: AF.DataRequestExt.Req(DstAddrModeAddress=AddrModeAddress(mode=<AddrMode.NWK: 2>, address=0x473F), DstEndpoint=1, DstPanId=0x0000, SrcEndpoint=1, ClusterId=6, TSN=17, Options=<TransmitOptions.SUPPRESS_ROUTE_DISC_NETWORK|ACK_REQUEST: 48>, Radius=30, Data=b'\x00\x11\x00\x00\x00')
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataRequestExt.Rsp(Status=<Status.SUCCESS: 0>)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.DataConfirm.Callback(Status=<Status.SUCCESS: 0>, Endpoint=1, TSN=17)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11543005, TSN=0, Data=b'\x18\x11\x01\x00\x00\x00\x10\x00', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Received ZCL frame: b'\x18\x11\x01\x00\x00\x00\x10\x00'
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=17, command_id=1, *is_reply=True)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame: OnOff:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0000, status=<Status.SUCCESS: 0>, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=0, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=25, SecurityUse=<Bool.false: 0>, TimeStamp=11557933, TSN=0, Data=b'\x1C\x5F\x11\x03\x0A\x01\x00\x20\x20', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Received ZCL frame: b'\x1C\x5F\x11\x03\x0A\x01\x00\x20\x20'
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), manufacturer=4447, tsn=3, command_id=10, *is_reply=True)
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=32))])
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Received command 0x0A (TSN 3): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=32))])
2022-04-10 23:00:39 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0000] Attribute report received: app_version=32
2022-04-10 23:00:40 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=64704, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=29, SecurityUse=<Bool.false: 0>, TimeStamp=11631214, TSN=0, Data=b'\x1C\x5F\x11\x04\x0A\xF7\x00\x41\x39\x64\x10\x00\x03\x28\x13\x98\x39\x00\x00\x00\x00\x95\x39\x00\x00\x00\x00\x96\x39\x00\xE0\x17\x45\x97\x39\x00\x00\x00\x00\x05\x21\x01\x00\x9A\x20\x00\x08\x21\x20\x01\x09\x21\x00\x12\x0B\x20\x00\x9B\x10\x01\x0F\x23\x00\x00\x2A\xAC', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:40 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Received ZCL frame: b'\x1C\x5F\x11\x04\x0A\xF7\x00\x41\x39\x64\x10\x00\x03\x28\x13\x98\x39\x00\x00\x00\x00\x95\x39\x00\x00\x00\x00\x96\x39\x00\xE0\x17\x45\x97\x39\x00\x00\x00\x00\x05\x21\x01\x00\x9A\x20\x00\x08\x21\x20\x01\x09\x21\x00\x12\x0B\x20\x00\x9B\x10\x01\x0F\x23\x00\x00\x2A\xAC'
2022-04-10 23:00:40 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=True, is_reply=1, disable_default_response=1, reserved=0, is_cluster=False, is_general=True), manufacturer=4447, tsn=4, command_id=10, is_reply=True)
2022-04-10 23:00:40 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Decoded ZCL frame: ManufacturerSpecificCluster:Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x13\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x00\xe0\x17E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x08! \x01\t!\x00\x12\x0b \x00\x9b\x10\x01\x0f#\x00\x00\xac'))])
2022-04-10 23:00:40 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Received command 0x0A (TSN 4): Report_Attributes(attribute_reports=[Attribute(attrid=0x00F7, value=TypeValue(type=LVBytes, value=b'd\x10\x00\x03(\x13\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x00\xe0\x17E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x08! \x01\t!\x00\x12\x0b \x00\x9b\x10\x01\x0f#\x00\x00\xac'))])
2022-04-10 23:00:40 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0xfcc0] Attribute report received: 0x00F7=b'd\x10\x00\x03(\x13\x989\x00\x00\x00\x00\x959\x00\x00\x00\x00\x969\x00\xe0\x17E\x979\x00\x00\x00\x00\x05!\x01\x00\x9a \x00\x08! \x01\t!\x00\x12\x0b \x00\x9b\x10\x01\x0f#\x00\x00\xac'
2022-04-10 23:00:41 DEBUG (MainThread) [zigpy_znp.api] Received command: AF.IncomingMsg.Callback(GroupId=0x0000, ClusterId=6, SrcAddr=0x473F, SrcEndpoint=1, DstEndpoint=1, WasBroadcast=<Bool.false: 0>, LQI=21, SecurityUse=<Bool.false: 0>, TimeStamp=11705490, TSN=0, Data=b'\x18\x05\x0A\x00\x00\x10\x00\xF5\x00\x23\x00\xFF\xFF\x02', MacSrcAddr=0x473F, MsgResultRadius=29)
2022-04-10 23:00:41 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Received ZCL frame: b'\x18\x05\x0A\x00\x00\x10\x00\xF5\x00\x23\x00\xFF\xFF\x02'
2022-04-10 23:00:41 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, is_reply=1, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=5, command_id=10, *is_reply=True)
2022-04-10 23:00:41 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>)), Attribute(attrid=0x00F5, value=TypeValue(type=uint32_t, value=50331392))])
2022-04-10 23:00:41 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Received command 0x0A (TSN 5): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>)), Attribute(attrid=0x00F5, value=TypeValue(type=uint32_t, value=50331392))])
2022-04-10 23:00:41 DEBUG (MainThread) [zigpy.zcl] [0x473F:1:0x0006] Attribute report received: on_off=<Bool.false: 0>, 0x00F5=50331392

Stefano0042 · 2022-04-10T21:45:55Z

zeekens · 2022-04-13T10:58:20Z

I have the same problems here, is there anything I can do to help?

flogitgeek · 2022-04-20T08:16:17Z

Same problem here. Running ZHA, everything Vanilla. Just purchased 2 of those plugs to extend my Zigbee network and monitor power consumption of my water heater and other devices but no power metering working since day 1 (running the latest version of Home Assistant).

If there is anything I can do to help, please let me know

Stefano0042 · 2022-04-20T08:33:02Z

..well, in this case that it appear to be particularly harsh on my perception, it make perfect sense to try help the developers, by rising up a certain fund, everybody in the community of Aqara plug users might contribute with a coffee, so that they can "at least" order one sample by their own, but why not even more..., it is obvious the union that make the difference. @dmulcahey Hi David please how can I offer a coffee to you?

@aqara_community, please rise up your thumb if you agree with me.

torge70 · 2022-04-20T10:28:21Z

I have 3 plugs. I can donate one to the project if it will help.

Stefano0042 · 2022-04-20T11:28:10Z

..wow this is a "Beau Geste" not outrageous to anybody, hopefully :)

I have spent 2 complete dedicated days, before give up, on this issue, half a day just digging the quirks knowledge and the remaining time struggling without success on finding a solution.

chemelli74 added the integration: zha label Mar 4, 2022

probot-home-assistant bot assigned Adminiuga and dmulcahey Mar 4, 2022

jims-code mentioned this issue Apr 3, 2022

[BUG] LLKZMK11LM: power metering disappears after restart zigpy/zha-device-handlers#1382

Closed

dmulcahey mentioned this issue Apr 9, 2022

Aqara Lumi Smart Plug EU no power consumption with ZHA #65292

Closed

dmulcahey mentioned this issue Apr 22, 2022

Fix Xiaomi MAEU01 plug zigpy/zha-device-handlers#1479

Merged

dmulcahey closed this as completed in zigpy/zha-device-handlers#1479 Apr 22, 2022

github-actions bot locked and limited conversation to collaborators May 22, 2022

Aqara Lumi Smart Plug EU missbehaving using ZHA #67644

Aqara Lumi Smart Plug EU missbehaving using ZHA #67644

Comments

larsemil commented Mar 4, 2022

The problem

What version of Home Assistant Core has the issue?

What was the last working version of Home Assistant Core?

What type of installation are you running?

Integration causing the issue

Link to integration documentation on our website

Diagnostics information

Example YAML snippet

Anything in the logs that might be useful for us?

Additional information

probot-home-assistant bot commented Mar 4, 2022

TekFan commented Mar 13, 2022 • edited Loading

jivemaster2005 commented Mar 14, 2022

oSourceSE commented Mar 14, 2022 • edited Loading

TekFan commented Mar 28, 2022

jivemaster2005 commented Mar 28, 2022

dmulcahey commented Mar 28, 2022

Digipix commented Mar 28, 2022 • edited Loading

husjon commented Mar 29, 2022

husjon commented Mar 29, 2022 • edited Loading

TekFan commented Mar 29, 2022

husjon commented Mar 29, 2022 • edited Loading

dmulcahey commented Mar 29, 2022

husjon commented Mar 29, 2022

husjon commented Mar 29, 2022

Digipix commented Mar 29, 2022 • edited Loading

How to downgrade:

TekFan commented Mar 30, 2022

larsemil commented Mar 30, 2022 • edited Loading

Stefano0042 commented Mar 31, 2022 • edited Loading

Stefano0042 commented Mar 31, 2022 • edited Loading

husjon commented Mar 31, 2022 • edited Loading

Stefano0042 commented Mar 31, 2022 • edited Loading

andersaagaard commented Mar 31, 2022 • edited Loading

dmulcahey commented Mar 31, 2022

torge70 commented Apr 2, 2022 • edited Loading

How to downgrade:

jims-code commented Apr 3, 2022

husjon commented Apr 3, 2022 • edited Loading

Stefano0042 commented Apr 4, 2022 • edited Loading

MrGlad8 commented Apr 6, 2022

Inervo commented Apr 6, 2022 • edited Loading

MrGlad8 commented Apr 6, 2022

andersaagaard commented Apr 6, 2022

MrGlad8 commented Apr 6, 2022

andersaagaard commented Apr 6, 2022

husjon commented Apr 6, 2022

torge70 commented Apr 8, 2022

How to downgrade:

torge70 commented Apr 8, 2022

dmulcahey commented Apr 8, 2022

dmulcahey commented Apr 9, 2022 • edited Loading

SkywiperSolutions commented Apr 10, 2022 • edited Loading

Stefano0042 commented Apr 10, 2022 • edited Loading

Stefano0042 commented Apr 10, 2022

zeekens commented Apr 13, 2022

flogitgeek commented Apr 20, 2022

Stefano0042 commented Apr 20, 2022 • edited Loading

torge70 commented Apr 20, 2022

Stefano0042 commented Apr 20, 2022 • edited Loading

TekFan commented Mar 13, 2022 •

edited

Loading

oSourceSE commented Mar 14, 2022 •

edited

Loading

Digipix commented Mar 28, 2022 •

edited

Loading

husjon commented Mar 29, 2022 •

edited

Loading

husjon commented Mar 29, 2022 •

edited

Loading

Digipix commented Mar 29, 2022 •

edited

Loading

larsemil commented Mar 30, 2022 •

edited

Loading

Stefano0042 commented Mar 31, 2022 •

edited

Loading

Stefano0042 commented Mar 31, 2022 •

edited

Loading

husjon commented Mar 31, 2022 •

edited

Loading

Stefano0042 commented Mar 31, 2022 •

edited

Loading

andersaagaard commented Mar 31, 2022 •

edited

Loading

torge70 commented Apr 2, 2022 •

edited

Loading

husjon commented Apr 3, 2022 •

edited

Loading

Stefano0042 commented Apr 4, 2022 •

edited

Loading

Inervo commented Apr 6, 2022 •

edited

Loading

dmulcahey commented Apr 9, 2022 •

edited

Loading

SkywiperSolutions commented Apr 10, 2022 •

edited

Loading

Stefano0042 commented Apr 10, 2022 •

edited

Loading

Stefano0042 commented Apr 20, 2022 •

edited

Loading

Stefano0042 commented Apr 20, 2022 •

edited

Loading