search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
73.61k stars 30.77k forks source link

ZHA: Binding a zigbee switch to a ZHA light group doesn't work #59800

Closed andrebk closed 2 years ago

andrebk commented 2 years ago

The problem

I have a number of IKEA bulbs in my home, with assorted IKEA remotes/switches to control them. The bulbs are organized in ZHA groups, so all bulbs in a fixture is in the same group. I control the lights by binding the remote directly to the group of lights, so the on/off and dimming events control the lights directly. I have had all my lights working like this for several months up to this point.

Recently a number of my remotes (but not all) stopped working after a HA crash. I didn't know if there was any relation, so to be sure I changed batteries and reset a remote. It shows up in HA with a battery sensor, and I can see zha_event events from it when I press a button. However, binding it to the light group doesn't work. The UI doesn't give any feedback on success/failure (but I don't think it did this before either), but as far as I can tell from the logs it should work, see <Status.SUCCESS: 0> at the end here :

2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 8 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 4096 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 6 to group: 0x0002 completed: [<Status.SUCCESS: 0>]

Pressing on/off or dimming buttons still does nothing after this.

What version of Home Assistant Core has the issue?

2021.11.3

What was the last working version of Home Assistant Core?

2021.10

What type of installation are you running?

Home Assistant Container

Integration causing the issue

ZHA

Link to integration documentation on our website

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

Example YAML snippet

No response

Anything in the logs that might be useful for us?

Remote device signature:

{
  "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 128>, manufacturer_code=4476, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=True, *is_full_function_device=False, *is_mains_powered=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x0820",
      "in_clusters": [
        "0x0000",
        "0x0001",
        "0x0003",
        "0x0020",
        "0x1000",
        "0xfc7c"
      ],
      "out_clusters": [
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0x0019",
        "0x1000"
      ]
    }
  },
  "manufacturer": "IKEA of Sweden",
  "model": "TRADFRI remote control",
  "class": "zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote"
}

Device signature for one of the bulbs in the group. All bulbs are identical.

{
  "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=4476, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, 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": "0x010c",
      "in_clusters": [
        "0x0000",
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0008",
        "0x0300",
        "0x1000",
        "0xfc7c"
      ],
      "out_clusters": [
        "0x0005",
        "0x0019",
        "0x0020",
        "0x1000"
      ]
    },
    "242": {
      "profile_id": 41440,
      "device_type": "0x0061",
      "in_clusters": [
        "0x0021"
      ],
      "out_clusters": [
        "0x0021"
      ]
    }
  },
  "manufacturer": "IKEA of Sweden",
  "model": "TRADFRI bulb GU10 WS 400lm",
  "class": "zigpy.device.Device"
}

IDs of the remote:

"device_ieee": "84:2e:14:ff:fe:16:34:c9",
"unique_id": "84:2e:14:ff:fe:16:34:c9:1:0x0006",
"device_id": "c3c65e9b1e47e38ca5978e35f80ad321",

Logs. For context, I pressed the on button on a IKEA five button remote to ensure it was "awake", and used the manage clusters UI for the remote to bind it to a light group. Repeated this 2-3 times in quick succession (have experienced before that it doesn't "take" on the first go). Tried to bind the LevelControl (0x0008), LightLink (0x1000), OnOff (0x0006) clusters.

2021-11-16 20:23:02 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.weekly_energy, old_state=<state sensor.weekly_energy=74.17; state_class=total_increasing, last_reset=2021-11-14T23:00:00.008774+00:00, source=sensor.energy_used, status=collecting, last_period=361.65, meter_period=weekly, cron pattern=0 0 * * 1, unit_of_measurement=kWh, friendly_name=Weekly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:22:32.792373+01:00>, new_state=<state sensor.weekly_energy=74.18; state_class=total_increasing, last_reset=2021-11-14T23:00:00.008774+00:00, source=sensor.energy_used, status=collecting, last_period=361.65, meter_period=weekly, cron pattern=0 0 * * 1, unit_of_measurement=kWh, friendly_name=Weekly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:02.853562+01:00>>
2021-11-16 20:23:02 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.monthly_energy, old_state=<state sensor.monthly_energy=1621.62; state_class=total_increasing, last_reset=2021-10-02T18:54:32.076895+00:00, source=sensor.energy_used, status=collecting, last_period=0, meter_period=monthly, cron pattern=0 0 1 * *, unit_of_measurement=kWh, friendly_name=Monthly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:22:32.792789+01:00>, new_state=<state sensor.monthly_energy=1621.63; state_class=total_increasing, last_reset=2021-10-02T18:54:32.076895+00:00, source=sensor.energy_used, status=collecting, last_period=0, meter_period=monthly, cron pattern=0 0 1 * *, unit_of_measurement=kWh, friendly_name=Monthly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:02.853782+01:00>>
2021-11-16 20:23:02 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [56, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, 2, 260, 2820, b'\x18\xf3\n\x05\x05!\xee\x00\x08\x05!\x1b\x00\x05\t!\xed\x00\x08\t!p\x00\x05\n!\xef\x00\x08\n!?\x00', 0, 175, 255, 162, 28, 95, 0, -50]
2021-11-16 20:23:02 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=243 command_id=Command.Report_Attributes>
2021-11-16 20:23:02 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1285, value=<TypeValue type=uint16_t, value=238>), Attribute(attrid=1288, value=<TypeValue type=uint16_t, value=27>), Attribute(attrid=2309, value=<TypeValue type=uint16_t, value=237>), Attribute(attrid=2312, value=<TypeValue type=uint16_t, value=112>), Attribute(attrid=2565, value=<TypeValue type=uint16_t, value=239>), Attribute(attrid=2568, value=<TypeValue type=uint16_t, value=63>)]]
2021-11-16 20:23:02 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] Attribute report received: rms_voltage=238, rms_current=27, rms_voltage_ph_b=237, rms_current_ph_b=112, rms_voltage_ph_c=239, rms_current_ph_c=63
2021-11-16 20:23:02 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current, old_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current=468; state_class=measurement, unit_of_measurement=A, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_current, device_class=current @ 2021-11-16T20:22:52.896472+01:00>, new_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current=27; state_class=measurement, unit_of_measurement=A, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_current, device_class=current @ 2021-11-16T20:23:02.862814+01:00>>
2021-11-16 20:23:02 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, ep: 2, profile: 0x0104, cluster_id: 0x0b04, data: b'18f30a050521ee000805211b00050921ed000809217000050a21ef00080a213f00'
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 1, 260, 6, b'\x01\x17\x02', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.zcl] [0xda99:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=23 command_id=2>
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.zcl] [0xda99:1:0x0006] ZCL request 0x0002: []
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.zcl] [0xda99:1:0x0006] No handler for cluster command 2
2021-11-16 20:23:03 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=84:2e:14:ff:fe:16:34:c9, unique_id=84:2e:14:ff:fe:16:34:c9:1:0x0006, device_id=c3c65e9b1e47e38ca5978e35f80ad321, endpoint_id=1, cluster_id=6, command=toggle, args=[]>
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'011702'
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] processing 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 8 to group: 0x0002
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] processing 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 4096 to group: 0x0002
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] processing 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 6 to group: 0x0002
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.device] [0xda99] Extending timeout for 0x9b request
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 155 under 158 request id, data: b'9bc93416feff142e84010800010200'
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (30, 158, 0, <DeconzAddressEndpoint address_mode=2 address=0xDA99 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\x9b\xc94\x16\xfe\xff\x14.\x84\x01\x08\x00\x01\x02\x00', 2, 0)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.device] [0xda99] Extending timeout for 0x9c request
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 156 under 159 request id, data: b'9cc93416feff142e84010010010200'
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy.device] [0xda99] Extending timeout for 0x9d request
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 157 under 160 request id, data: b'9dc93416feff142e84010600010200'
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 158]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (30, 159, 0, <DeconzAddressEndpoint address_mode=2 address=0xDA99 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\x9c\xc94\x16\xfe\xff\x14.\x84\x01\x00\x10\x01\x02\x00', 2, 0)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 159]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (30, 160, 0, <DeconzAddressEndpoint address_mode=2 address=0xDA99 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\x9d\xc94\x16\xfe\xff\x14.\x84\x01\x06\x00\x01\x02\x00', 2, 0)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 160]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 158: 00
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x9e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xda99 endpoint=0>, status: 0x00
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 159: 00
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x9f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xda99 endpoint=0>, status: 0x00
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 160: 00
2021-11-16 20:23:03 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xa0 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xda99 endpoint=0>, status: 0x00
2021-11-16 20:23:04 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xF5D0:1:0x0b04]: failed to get attributes '['active_power']' on 'electrical_measurement' cluster:
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\x95\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'9500'
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\x96\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'9600'
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\x97\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'9700'
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 8 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 4096 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 6 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\x9b\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'9b00'
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\x9c\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'9c00'
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\x9d\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'9d00'
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 8 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 4096 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 6 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 1, 260, 6, b'\x01\x18\x02', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zcl] [0xda99:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=24 command_id=2>
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zcl] [0xda99:1:0x0006] ZCL request 0x0002: []
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy.zcl] [0xda99:1:0x0006] No handler for cluster command 2
2021-11-16 20:23:04 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=84:2e:14:ff:fe:16:34:c9, unique_id=84:2e:14:ff:fe:16:34:c9:1:0x0006, device_id=c3c65e9b1e47e38ca5978e35f80ad321, endpoint_id=1, cluster_id=6, command=toggle, args=[]>
2021-11-16 20:23:04 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 1, profile: 0x0104, cluster_id: 0x0006, data: b'011802'
2021-11-16 20:23:05 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.load_5m, old_state=<state sensor.load_5m=0.5; state_class=measurement, friendly_name=Load (5m), icon=mdi:cpu-64-bit @ 2021-11-16T20:22:50.219003+01:00>, new_state=<state sensor.load_5m=0.47; state_class=measurement, friendly_name=Load (5m), icon=mdi:cpu-64-bit @ 2021-11-16T20:23:05.224756+01:00>>
2021-11-16 20:23:05 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.processor_use_percent, old_state=<state sensor.processor_use_percent=5; state_class=measurement, unit_of_measurement=%, friendly_name=Processor use, icon=mdi:cpu-64-bit @ 2021-11-16T20:22:35.222889+01:00>, new_state=<state sensor.processor_use_percent=4; state_class=measurement, unit_of_measurement=%, friendly_name=Processor use, icon=mdi:cpu-64-bit @ 2021-11-16T20:23:05.225477+01:00>>
2021-11-16 20:23:05 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.processor_temperature, old_state=<state sensor.processor_temperature=51.0; state_class=measurement, unit_of_measurement=°C, friendly_name=Processor temperature, device_class=temperature @ 2021-11-16T20:22:50.219383+01:00>, new_state=<state sensor.processor_temperature=50.0; state_class=measurement, unit_of_measurement=°C, friendly_name=Processor temperature, device_class=temperature @ 2021-11-16T20:23:05.226046+01:00>>
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] processing 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 8 to group: 0x0002
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] processing 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 4096 to group: 0x0002
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] processing 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 6 to group: 0x0002
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy.device] [0xda99] Extending timeout for 0xa1 request
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 161 under 164 request id, data: b'a1c93416feff142e84010800010200'
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (30, 164, 0, <DeconzAddressEndpoint address_mode=2 address=0xDA99 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\xa1\xc94\x16\xfe\xff\x14.\x84\x01\x08\x00\x01\x02\x00', 2, 0)
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy.device] [0xda99] Extending timeout for 0xa2 request
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 162 under 165 request id, data: b'a2c93416feff142e84010010010200'
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy.device] [0xda99] Extending timeout for 0xa3 request
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 163 under 166 request id, data: b'a3c93416feff142e84010600010200'
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 164]
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (30, 165, 0, <DeconzAddressEndpoint address_mode=2 address=0xDA99 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\xa2\xc94\x16\xfe\xff\x14.\x84\x01\x00\x10\x01\x02\x00', 2, 0)
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 165]
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (30, 166, 0, <DeconzAddressEndpoint address_mode=2 address=0xDA99 endpoint=0>, 0, <ZDOCmd.Bind_req: 0x0021>, 0, b'\xa3\xc94\x16\xfe\xff\x14.\x84\x01\x06\x00\x01\x02\x00', 2, 0)
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 166]
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 164: 00
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xa4 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xda99 endpoint=0>, status: 0x00
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 165: 00
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xa5 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xda99 endpoint=0>, status: 0x00
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 166: 00
2021-11-16 20:23:05 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xa6 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xda99 endpoint=0>, status: 0x00
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\xa1\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'a100'
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\xa2\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'a200'
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [25, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, 0, 0, 32801, b'\xa3\x00', 0, 175, 255, 162, 28, 95, 0, -52]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xda99>, ep: 0, profile: 0x0000, cluster_id: 0x8021, data: b'a300'
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 8 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 4096 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:06 DEBUG (MainThread) [zigpy.zdo] [0xda99:zdo] 0xda99: Bind_req 84:2e:14:ff:fe:16:34:c9, ep: 1, cluster: 6 to group: 0x0002 completed: [<Status.SUCCESS: 0>]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x180\n\x00\x00%S\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*\xfe\x01\x00', 0, 175, 255, 162, 28, 95, 0, -54]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=48 command_id=Command.Report_Attributes>
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430419>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=510>)]]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430419, status=0, instantaneous_demand=510
2021-11-16 20:23:07 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=0.0; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:22:57.484158+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=510; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:07.197297+01:00>>
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18300a0000255391060000000002180000042afe0100'
2021-11-16 20:23:07 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.hallway_heater_on, old_state=<state binary_sensor.hallway_heater_on=off; friendly_name=Hallway heater on, icon=mdi:radiator-disabled @ 2021-11-16T20:22:57.486676+01:00>, new_state=<state binary_sensor.hallway_heater_on=on; friendly_name=Hallway heater on, icon=mdi:radiator-disabled @ 2021-11-16T20:23:07.199985+01:00>>
2021-11-16 20:23:07 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.hallway_heater_on, old_state=<state binary_sensor.hallway_heater_on=on; friendly_name=Hallway heater on, icon=mdi:radiator-disabled @ 2021-11-16T20:23:07.199985+01:00>, new_state=<state binary_sensor.hallway_heater_on=on; friendly_name=Hallway heater on, icon=mdi:radiator @ 2021-11-16T20:23:07.199985+01:00>>
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x181\n\x00\x00%S\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*8\x04\x00', 0, 175, 255, 162, 28, 95, 0, -54]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=49 command_id=Command.Report_Attributes>
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430419>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1080>)]]
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430419, status=0, instantaneous_demand=1080
2021-11-16 20:23:07 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=510; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:07.197297+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1080; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:07.579238+01:00>>
2021-11-16 20:23:07 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18310a0000255391060000000002180000042a380400'
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x182\n\x00\x00%S\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*2\x04\x00', 0, 175, 255, 162, 28, 95, 0, -53]
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=50 command_id=Command.Report_Attributes>
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430419>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1074>)]]
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430419, status=0, instantaneous_demand=1074
2021-11-16 20:23:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1080; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:07.579238+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1074; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:08.501193+01:00>>
2021-11-16 20:23:08 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18320a0000255391060000000002180000042a320400'
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x183\n\x00\x00%T\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*4\x04\x00', 0, 175, 255, 162, 28, 95, 0, -54]
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=51 command_id=Command.Report_Attributes>
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430420>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1076>)]]
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430420, status=0, instantaneous_demand=1076
2021-11-16 20:23:09 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1074; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:08.501193+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1076; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:09.496646+01:00>>
2021-11-16 20:23:09 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.419; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:22:55.484379+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.42; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:09.497488+01:00>>
2021-11-16 20:23:09 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18330a0000255491060000000002180000042a340400'
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x184\n\x00\x00%T\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*.\x04\x00', 0, 175, 255, 162, 28, 95, 0, -53]
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=52 command_id=Command.Report_Attributes>
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430420>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1070>)]]
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430420, status=0, instantaneous_demand=1070
2021-11-16 20:23:10 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1076; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:09.496646+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1070; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:10.508316+01:00>>
2021-11-16 20:23:10 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18340a0000255491060000000002180000042a2e0400'
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x185\n\x00\x00%T\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*/\x04\x00', 0, 175, 255, 162, 28, 95, 0, -54]
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=53 command_id=Command.Report_Attributes>
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430420>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1071>)]]
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430420, status=0, instantaneous_demand=1071
2021-11-16 20:23:11 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1070; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:10.508316+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:11.509812+01:00>>
2021-11-16 20:23:11 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18350a0000255491060000000002180000042a2f0400'
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x186\n\x00\x00%T\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*3\x04\x00', 0, 175, 255, 162, 28, 95, 0, -53]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=54 command_id=Command.Report_Attributes>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430420>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1075>)]]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430420, status=0, instantaneous_demand=1075
2021-11-16 20:23:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:11.509812+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1075; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:12.511185+01:00>>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18360a0000255491060000000002180000042a330400'
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, 2, 260, 1794, b'\x18\xf4\n\x00\x00%\xc0wb\x01\x00\x00\x00\x04*\xec\x04\x00\x00\x02\x18\x00', 0, 175, 255, 162, 28, 95, 0, -50]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=244 command_id=Command.Report_Attributes>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=23230400>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1260>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>)]]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0702] Attribute report received: current_summ_delivered=23230400, instantaneous_demand=1260, status=0
2021-11-16 20:23:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.energy_metering, old_state=<state sensor.energy_metering=338; state_class=measurement, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Energy metering, device_class=power @ 2021-11-16T20:23:02.848461+01:00>, new_state=<state sensor.energy_metering=2461; state_class=measurement, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Energy metering, device_class=power @ 2021-11-16T20:23:12.797485+01:00>>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18f40a000025c0776201000000042aec040000021800'
2021-11-16 20:23:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.power_meter, old_state=<state sensor.power_meter=169.0; state_class=measurement, unit_of_measurement=W, friendly_name=Power meter, device_class=power @ 2021-11-16T20:23:02.852701+01:00>, new_state=<state sensor.power_meter=1230.5; state_class=measurement, unit_of_measurement=W, friendly_name=Power meter, device_class=power @ 2021-11-16T20:23:12.800356+01:00>>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [56, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, 2, 260, 2820, b'\x18\xf5\n\x05\x05!\xed\x00\x08\x05!\xd5\x01\x05\t!\xee\x00\x08\t!q\x00\x05\n!\xef\x00\x08\n!?\x00', 0, 175, 255, 162, 28, 95, 0, -50]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=245 command_id=Command.Report_Attributes>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1285, value=<TypeValue type=uint16_t, value=237>), Attribute(attrid=1288, value=<TypeValue type=uint16_t, value=469>), Attribute(attrid=2309, value=<TypeValue type=uint16_t, value=238>), Attribute(attrid=2312, value=<TypeValue type=uint16_t, value=113>), Attribute(attrid=2565, value=<TypeValue type=uint16_t, value=239>), Attribute(attrid=2568, value=<TypeValue type=uint16_t, value=63>)]]
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] Attribute report received: rms_voltage=237, rms_current=469, rms_voltage_ph_b=238, rms_current_ph_b=113, rms_voltage_ph_c=239, rms_current_ph_c=63
2021-11-16 20:23:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current, old_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current=27; state_class=measurement, unit_of_measurement=A, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_current, device_class=current @ 2021-11-16T20:23:02.862814+01:00>, new_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current=469; state_class=measurement, unit_of_measurement=A, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_current, device_class=current @ 2021-11-16T20:23:12.809966+01:00>>
2021-11-16 20:23:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_voltage, old_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_voltage=238; state_class=measurement, unit_of_measurement=V, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_voltage, device_class=current @ 2021-11-16T20:22:52.896759+01:00>, new_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_voltage=237; state_class=measurement, unit_of_measurement=V, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_voltage, device_class=current @ 2021-11-16T20:23:12.810221+01:00>>
2021-11-16 20:23:12 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, ep: 2, profile: 0x0104, cluster_id: 0x0b04, data: b'18f50a050521ed00080521d501050921ee000809217100050a21ef00080a213f00'
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x187\n\x00\x00%U\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*/\x04\x00', 0, 175, 255, 162, 28, 95, 0, -54]
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=55 command_id=Command.Report_Attributes>
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430421>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1071>)]]
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430421, status=0, instantaneous_demand=1071
2021-11-16 20:23:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1075; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:12.511185+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:13.519076+01:00>>
2021-11-16 20:23:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.42; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:09.497488+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.421; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:13.519894+01:00>>
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18370a0000255591060000000002180000042a2f0400'
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [51, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 2820, b'\x188\n\x05\x05!\xe0[\x07\x05!1^\x08\x05!\xd5\x11\n\x05!,\x12\x0b\x05)/\x04', 0, 175, 255, 162, 28, 95, 0, -53]
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=56 command_id=Command.Report_Attributes>
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1285, value=<TypeValue type=uint16_t, value=23520>), Attribute(attrid=1287, value=<TypeValue type=uint16_t, value=24113>), Attribute(attrid=1288, value=<TypeValue type=uint16_t, value=4565>), Attribute(attrid=1290, value=<TypeValue type=uint16_t, value=4652>), Attribute(attrid=1291, value=<TypeValue type=int16s, value=1071>)]]
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0b04] Attribute report received: rms_voltage=23520, rms_voltage_max=24113, rms_current=4565, rms_current_max=4652, active_power=1071
2021-11-16 20:23:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_electrical_measurement, old_state=<state sensor.hallway_heater_smartplug_electrical_measurement=0; state_class=measurement, measurement_type=ACTIVE_MEASUREMENT, REACTIVE_MEASUREMENT, PHASE_A_MEASUREMENT, unit_of_measurement=W, friendly_name=Hallway heater smartplug electrical measurement, device_class=power @ 2021-11-16T20:22:59.152948+01:00>, new_state=<state sensor.hallway_heater_smartplug_electrical_measurement=1071; state_class=measurement, measurement_type=ACTIVE_MEASUREMENT, REACTIVE_MEASUREMENT, PHASE_A_MEASUREMENT, unit_of_measurement=W, friendly_name=Hallway heater smartplug electrical measurement, device_class=power @ 2021-11-16T20:23:13.530688+01:00>>
2021-11-16 20:23:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_electrical_measurement_rms_current, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_electrical_measurement_rms_current=0.0; state_class=measurement, measurement_type=ACTIVE_MEASUREMENT, REACTIVE_MEASUREMENT, PHASE_A_MEASUREMENT, rms_current_max=4.7, unit_of_measurement=A, friendly_name=Develco Products A/S SPLZB-131 5e71002f electrical_measurement rms_current, device_class=current @ 2021-11-16T20:22:59.153568+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_electrical_measurement_rms_current=4.6; state_class=measurement, measurement_type=ACTIVE_MEASUREMENT, REACTIVE_MEASUREMENT, PHASE_A_MEASUREMENT, rms_current_max=4.7, unit_of_measurement=A, friendly_name=Develco Products A/S SPLZB-131 5e71002f electrical_measurement rms_current, device_class=current @ 2021-11-16T20:23:13.531006+01:00>>
2021-11-16 20:23:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_electrical_measurement_rms_voltage, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_electrical_measurement_rms_voltage=237; state_class=measurement, measurement_type=ACTIVE_MEASUREMENT, REACTIVE_MEASUREMENT, PHASE_A_MEASUREMENT, rms_voltage_max=241, unit_of_measurement=V, friendly_name=Develco Products A/S SPLZB-131 5e71002f electrical_measurement rms_voltage, device_class=current @ 2021-11-16T20:22:59.154107+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_electrical_measurement_rms_voltage=235; state_class=measurement, measurement_type=ACTIVE_MEASUREMENT, REACTIVE_MEASUREMENT, PHASE_A_MEASUREMENT, rms_voltage_max=241, unit_of_measurement=V, friendly_name=Develco Products A/S SPLZB-131 5e71002f electrical_measurement rms_voltage, device_class=current @ 2021-11-16T20:23:13.531273+01:00>>
2021-11-16 20:23:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0b04, data: b'18380a050521e05b070521315e080521d5110a05212c120b05292f04'
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x189\n\x00\x00%U\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*,\x04\x00', 0, 175, 255, 162, 28, 95, 0, -53]
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=57 command_id=Command.Report_Attributes>
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430421>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1068>)]]
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430421, status=0, instantaneous_demand=1068
2021-11-16 20:23:14 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:13.519076+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:14.511839+01:00>>
2021-11-16 20:23:14 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18390a0000255591060000000002180000042a2c0400'
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18:\n\x00\x00%U\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*/\x04\x00', 0, 175, 255, 162, 28, 95, 0, -54]
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=58 command_id=Command.Report_Attributes>
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430421>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1071>)]]
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430421, status=0, instantaneous_demand=1071
2021-11-16 20:23:15 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:14.511839+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:15.512177+01:00>>
2021-11-16 20:23:15 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'183a0a0000255591060000000002180000042a2f0400'
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18;\n\x00\x00%V\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*,\x04\x00', 0, 175, 255, 162, 28, 95, 0, -53]
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=59 command_id=Command.Report_Attributes>
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430422>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1068>)]]
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430422, status=0, instantaneous_demand=1068
2021-11-16 20:23:16 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:15.512177+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:16.519166+01:00>>
2021-11-16 20:23:16 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.421; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:13.519894+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.422; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:16.520363+01:00>>
2021-11-16 20:23:16 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'183b0a0000255691060000000002180000042a2c0400'
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18<\n\x00\x00%V\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*/\x04\x00', 0, 175, 255, 163, 27, 95, 0, -53]
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=60 command_id=Command.Report_Attributes>
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430422>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1071>)]]
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430422, status=0, instantaneous_demand=1071
2021-11-16 20:23:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:16.519166+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:17.513351+01:00>>
2021-11-16 20:23:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'183c0a0000255691060000000002180000042a2f0400'
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18=\n\x00\x00%V\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*+\x04\x00', 0, 175, 255, 169, 28, 95, 0, -54]
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=61 command_id=Command.Report_Attributes>
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430422>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1067>)]]
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430422, status=0, instantaneous_demand=1067
2021-11-16 20:23:18 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1071; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:17.513351+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1067; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:18.534532+01:00>>
2021-11-16 20:23:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'183d0a0000255691060000000002180000042a2b0400'
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18>\n\x00\x00%V\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*.\x04\x00', 0, 175, 255, 169, 28, 95, 0, -53]
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=62 command_id=Command.Report_Attributes>
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430422>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1070>)]]
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430422, status=0, instantaneous_demand=1070
2021-11-16 20:23:19 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1067; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:18.534532+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1070; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:19.512284+01:00>>
2021-11-16 20:23:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'183e0a0000255691060000000002180000042a2e0400'
2021-11-16 20:23:20 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.heater_on_last_24_hours, old_state=<state sensor.heater_on_last_24_hours=8.32; value=8h 19m, unit_of_measurement=h, friendly_name=Heater ON last 24 hours, icon=mdi:chart-line @ 2021-11-16T20:22:27.483430+01:00>, new_state=<state sensor.heater_on_last_24_hours=8.33; value=8h 19m, unit_of_measurement=h, friendly_name=Heater ON last 24 hours, icon=mdi:chart-line @ 2021-11-16T20:23:20.207882+01:00>>
2021-11-16 20:23:20 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.load_5m, old_state=<state sensor.load_5m=0.47; state_class=measurement, friendly_name=Load (5m), icon=mdi:cpu-64-bit @ 2021-11-16T20:23:05.224756+01:00>, new_state=<state sensor.load_5m=0.45; state_class=measurement, friendly_name=Load (5m), icon=mdi:cpu-64-bit @ 2021-11-16T20:23:20.219595+01:00>>
2021-11-16 20:23:20 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.processor_use_percent, old_state=<state sensor.processor_use_percent=4; state_class=measurement, unit_of_measurement=%, friendly_name=Processor use, icon=mdi:cpu-64-bit @ 2021-11-16T20:23:05.225477+01:00>, new_state=<state sensor.processor_use_percent=5; state_class=measurement, unit_of_measurement=%, friendly_name=Processor use, icon=mdi:cpu-64-bit @ 2021-11-16T20:23:20.219887+01:00>>
2021-11-16 20:23:20 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.server_battery, old_state=<state sensor.server_battery=100; name=BAT0, path=/sys/class/power_supply/BAT0, alarm=0, capacity_level=Full, cycle_count=0, energy_full=, energy_full_design=, energy_now=, manufacturer=Notebook, model_name=BAT, power_now=, serial_number=1, status=Full, voltage_min_design=11100000, voltage_now=12291000, unit_of_measurement=%, friendly_name=Server Battery, device_class=battery @ 2021-11-16T20:11:50.253021+01:00>, new_state=<state sensor.server_battery=100; name=BAT0, path=/sys/class/power_supply/BAT0, alarm=0, capacity_level=Full, cycle_count=0, energy_full=, energy_full_design=, energy_now=, manufacturer=Notebook, model_name=BAT, power_now=, serial_number=1, status=Full, voltage_min_design=11100000, voltage_now=12288000, unit_of_measurement=%, friendly_name=Server Battery, device_class=battery @ 2021-11-16T20:11:50.253021+01:00>>
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18?\n\x00\x00%W\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*,\x04\x00', 0, 175, 255, 169, 28, 95, 0, -54]
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=63 command_id=Command.Report_Attributes>
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430423>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1068>)]]
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430423, status=0, instantaneous_demand=1068
2021-11-16 20:23:20 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1070; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:19.512284+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:20.520655+01:00>>
2021-11-16 20:23:20 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.422; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:16.520363+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.423; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:20.520989+01:00>>
2021-11-16 20:23:20 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'183f0a0000255791060000000002180000042a2c0400'
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18@\n\x00\x00%W\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*-\x04\x00', 0, 175, 255, 169, 28, 95, 0, -54]
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=64 command_id=Command.Report_Attributes>
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430423>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1069>)]]
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430423, status=0, instantaneous_demand=1069
2021-11-16 20:23:21 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:20.520655+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1069; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:21.522309+01:00>>
2021-11-16 20:23:21 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18400a0000255791060000000002180000042a2d0400'
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18A\n\x00\x00%W\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*,\x04\x00', 0, 175, 255, 169, 28, 95, 0, -53]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=65 command_id=Command.Report_Attributes>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430423>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1068>)]]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430423, status=0, instantaneous_demand=1068
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1069; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:21.522309+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:22.538611+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18410a0000255791060000000002180000042a2c0400'
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, 2, 260, 1794, b'\x18\xf6\n\x00\x00%\xc0wb\x01\x00\x00\x00\x04*\xed\x04\x00\x00\x02\x18\x00', 0, 175, 255, 169, 28, 95, 0, -50]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=246 command_id=Command.Report_Attributes>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=23230400>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1261>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>)]]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0702] Attribute report received: current_summ_delivered=23230400, instantaneous_demand=1261, status=0
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.energy_metering, old_state=<state sensor.energy_metering=2461; state_class=measurement, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Energy metering, device_class=power @ 2021-11-16T20:23:12.797485+01:00>, new_state=<state sensor.energy_metering=2463; state_class=measurement, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Energy metering, device_class=power @ 2021-11-16T20:23:22.951317+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18f60a000025c0776201000000042aed040000021800'
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.energy_used, old_state=<state sensor.energy_used=1617.84; state_class=total, source=sensor.energy_metering, unit_of_measurement=kWh, friendly_name=Energy used, icon=mdi:chart-histogram, device_class=energy @ 2021-11-16T20:23:02.851514+01:00>, new_state=<state sensor.energy_used=1617.85; state_class=total, source=sensor.energy_metering, unit_of_measurement=kWh, friendly_name=Energy used, icon=mdi:chart-histogram, device_class=energy @ 2021-11-16T20:23:22.954414+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.power_meter, old_state=<state sensor.power_meter=1230.5; state_class=measurement, unit_of_measurement=W, friendly_name=Power meter, device_class=power @ 2021-11-16T20:23:12.800356+01:00>, new_state=<state sensor.power_meter=1231.5; state_class=measurement, unit_of_measurement=W, friendly_name=Power meter, device_class=power @ 2021-11-16T20:23:22.955639+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.daily_energy, old_state=<state sensor.daily_energy=28.02; state_class=total_increasing, last_reset=2021-11-15T23:00:00.007915+00:00, source=sensor.energy_used, status=collecting, last_period=46.16, meter_period=daily, cron pattern=0 0 * * *, unit_of_measurement=kWh, friendly_name=Daily Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:02.853327+01:00>, new_state=<state sensor.daily_energy=28.03; state_class=total_increasing, last_reset=2021-11-15T23:00:00.007915+00:00, source=sensor.energy_used, status=collecting, last_period=46.16, meter_period=daily, cron pattern=0 0 * * *, unit_of_measurement=kWh, friendly_name=Daily Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:22.957096+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.weekly_energy, old_state=<state sensor.weekly_energy=74.18; state_class=total_increasing, last_reset=2021-11-14T23:00:00.008774+00:00, source=sensor.energy_used, status=collecting, last_period=361.65, meter_period=weekly, cron pattern=0 0 * * 1, unit_of_measurement=kWh, friendly_name=Weekly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:02.853562+01:00>, new_state=<state sensor.weekly_energy=74.19; state_class=total_increasing, last_reset=2021-11-14T23:00:00.008774+00:00, source=sensor.energy_used, status=collecting, last_period=361.65, meter_period=weekly, cron pattern=0 0 * * 1, unit_of_measurement=kWh, friendly_name=Weekly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:22.957366+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.monthly_energy, old_state=<state sensor.monthly_energy=1621.63; state_class=total_increasing, last_reset=2021-10-02T18:54:32.076895+00:00, source=sensor.energy_used, status=collecting, last_period=0, meter_period=monthly, cron pattern=0 0 1 * *, unit_of_measurement=kWh, friendly_name=Monthly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:02.853782+01:00>, new_state=<state sensor.monthly_energy=1621.64; state_class=total_increasing, last_reset=2021-10-02T18:54:32.076895+00:00, source=sensor.energy_used, status=collecting, last_period=0, meter_period=monthly, cron pattern=0 0 1 * *, unit_of_measurement=kWh, friendly_name=Monthly Energy, icon=mdi:counter, device_class=energy @ 2021-11-16T20:23:22.957587+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [56, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, 2, 260, 2820, b'\x18\xf7\n\x05\x05!\xee\x00\x08\x05!\xd4\x01\x05\t!\xed\x00\x08\t!t\x00\x05\n!\xef\x00\x08\n!?\x00', 0, 175, 255, 169, 28, 95, 0, -50]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=247 command_id=Command.Report_Attributes>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] ZCL request 0x000a: [[Attribute(attrid=1285, value=<TypeValue type=uint16_t, value=238>), Attribute(attrid=1288, value=<TypeValue type=uint16_t, value=468>), Attribute(attrid=2309, value=<TypeValue type=uint16_t, value=237>), Attribute(attrid=2312, value=<TypeValue type=uint16_t, value=116>), Attribute(attrid=2565, value=<TypeValue type=uint16_t, value=239>), Attribute(attrid=2568, value=<TypeValue type=uint16_t, value=63>)]]
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy.zcl] [0x846e:2:0x0b04] Attribute report received: rms_voltage=238, rms_current=468, rms_voltage_ph_b=237, rms_current_ph_b=116, rms_voltage_ph_c=239, rms_current_ph_c=63
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current, old_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current=469; state_class=measurement, unit_of_measurement=A, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_current, device_class=current @ 2021-11-16T20:23:12.809966+01:00>, new_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_current=468; state_class=measurement, unit_of_measurement=A, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_current, device_class=current @ 2021-11-16T20:23:22.968344+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_voltage, old_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_voltage=237; state_class=measurement, unit_of_measurement=V, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_voltage, device_class=current @ 2021-11-16T20:23:12.810221+01:00>, new_state=<state sensor.develco_products_a_s_emizb_132_eb52021b_electrical_measurement_rms_voltage=238; state_class=measurement, unit_of_measurement=V, friendly_name=Develco Products A/S EMIZB-132 eb52021b electrical_measurement rms_voltage, device_class=current @ 2021-11-16T20:23:22.968617+01:00>>
2021-11-16 20:23:22 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x846e>, ep: 2, profile: 0x0104, cluster_id: 0x0b04, data: b'18f70a050521ee00080521d401050921ed000809217400050a21ef00080a213f00'
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18B\n\x00\x00%X\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*+\x04\x00', 0, 175, 255, 169, 28, 95, 0, -53]
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=66 command_id=Command.Report_Attributes>
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430424>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1067>)]]
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430424, status=0, instantaneous_demand=1067
2021-11-16 20:23:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1068; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:22.538611+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1067; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:23.526639+01:00>>
2021-11-16 20:23:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.423; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:20.520989+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.424; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:23.527481+01:00>>
2021-11-16 20:23:23 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18420a0000255891060000000002180000042a2b0400'
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18C\n\x00\x00%X\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04**\x04\x00', 0, 175, 255, 169, 28, 95, 0, -53]
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=67 command_id=Command.Report_Attributes>
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430424>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1066>)]]
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430424, status=0, instantaneous_demand=1066
2021-11-16 20:23:24 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1067; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:23.526639+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1066; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:24.533479+01:00>>
2021-11-16 20:23:24 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18430a0000255891060000000002180000042a2a0400'
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18D\n\x00\x00%X\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*(\x04\x00', 0, 175, 255, 169, 28, 95, 0, -54]
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=68 command_id=Command.Report_Attributes>
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430424>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1064>)]]
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430424, status=0, instantaneous_demand=1064
2021-11-16 20:23:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1066; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:24.533479+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1064; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:25.546211+01:00>>
2021-11-16 20:23:25 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18440a0000255891060000000002180000042a280400'
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [45, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, 2, 260, 1794, b'\x18E\n\x00\x00%Y\x91\x06\x00\x00\x00\x00\x02\x18\x00\x00\x04*)\x04\x00', 0, 175, 255, 169, 28, 95, 0, -53]
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=69 command_id=Command.Report_Attributes>
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint48_t, value=430425>), Attribute(attrid=512, value=<TypeValue type=bitmap8, value=bitmap8.0>), Attribute(attrid=1024, value=<TypeValue type=int24s, value=1065>)]]
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy.zcl] [0x7b43:2:0x0702] Attribute report received: current_summ_delivered=430425, status=0, instantaneous_demand=1065
2021-11-16 20:23:26 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hallway_heater_smartplug_energy_metering, old_state=<state sensor.hallway_heater_smartplug_energy_metering=1064; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:25.546211+01:00>, new_state=<state sensor.hallway_heater_smartplug_energy_metering=1065; state_class=measurement, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=W, friendly_name=Hallway heater smartplug energy metering, device_class=power @ 2021-11-16T20:23:26.541309+01:00>>
2021-11-16 20:23:26 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered, old_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.424; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:23.527481+01:00>, new_state=<state sensor.develco_products_a_s_splzb_131_5e71002f_smartenergy_metering_summation_delivered=430.425; state_class=total_increasing, device_type=Electric Metering, status=NO_ALARMS, unit_of_measurement=kWh, friendly_name=Develco Products A/S SPLZB-131 5e71002f smartenergy_metering summation_delivered, device_class=energy @ 2021-11-16T20:23:26.542157+01:00>>
2021-11-16 20:23:26 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x7b43>, ep: 2, profile: 0x0104, cluster_id: 0x0702, data: b'18450a0000255991060000000002180000042a290400'

Additional information

The crash that seemingly caused the binding to stop working happened while I was away, so I am not sure what happened. But the timing did coincide with when we switched to winter time where I live, so I suspect it is related to the daylight saving time bug mentioned in the 2021.11 release notes. Not sure it matters, but thought I'd mention it.

Also, this has affected a number of different device types. I only have the IKEA GU10 bulbs, but both the TRADFRI remote control and TRADFRI on/off switch type of remotes have been affected. I also have FYRTUR blinds with TRADFRI open/close remote remotes that are not working either. I only included the five button remote control and the lights above for brevity.

probot-home-assistant[bot] commented 2 years ago

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)

Adminiuga commented 2 years ago

DST has nothing to do with binding. But if you have updated Ikea remotes, the new firmware does not support group binding and you have to bind individual devices.

andrebk commented 2 years ago

I have OTA updates turned on for IKEA, but have always had warnings in the logs that I interpreted as meaning the IKEA devices were not actually updated:

Logger: zigpy.ota.image
Source: /usr/local/lib/python3.9/site-packages/zigpy/ota/image.py:244
First occurred: 16 November 2021, 22:11:45 (3 occurrences)
Last logged: 18:35:19

Fixing IKEA OTA image with trailing data (1100 bytes)
Fixing IKEA OTA image with trailing data (1080 bytes)

Looking at the remote device page it says "Firmware: 0x23080631". I've found the version_info.json page for IKEA where it looks like the newest version for the remote is 2.3.080 (?), but I have no idea how this relates to the firmware number given in HA.

If this is in fact caused by the firmware, do you know if it is a bug that will be fixed at some point, or have IKEA just removed support for group binding?

Adminiuga commented 2 years ago

"Firmware: 0x23080631 is the 2.3.80 firmware.

Not sure if it's a bug or a deliberate change on Ikea side. You either need to use direct device binding or take to Ikea to bring group binding back

Adminiuga commented 2 years ago

This is not a zha bug. Zha sends the correct bind command, but the Ikea remote with the updated firmware does es not support group binding.

andrebk commented 2 years ago

Did some testing now, and it looks like you are right. Binding to each bulb in the group separatly worked, and the one remote that never stopped working is on a different firmware version (0x23014631). Haven't been able to test the blind remotes yet though.

Will try to contact IKEA about this and see if it is something they will fix. Thanks for your help @Adminiuga =)