zha-device-handlers: [BUG] IKEA Fyrtur blinds command delay

Bug/issue

I am having delay issues when using the Tradfri Open/Close Remote in combination with the Fyrtur Block-out Roller Blind, I have 2 next to each other in my setup. Both the blinds are grouped together in ‘cover.bedroom_finn’

In the group binding (home assistant) options of the Tradfri remote I binded the WindowCovering group to the custom ‘cover.bedroom_finn’ group mentioned above.

In the video below you can see both of the start going down almost instantly but when I click the ‘stop’ button the left one take a couple seconds longer to stop. When I want them to both go up, the left one takes almost 4 seconds longer to get going:

https://www.youtube.com/watch?v=pX8AkwHNXos

I am not sure if the delay has something to do we my zigbee network quality as the ikea bulb you see below always reacts instantly.

In the package from Ikea there was also a signal repeater included, I reset the repeater and have it plugged in an outlet nearby but I am not sure if I need further bindings to ensure it actually repeating. I would assume that’s it just works out of the box when reset as it just needs to repeat the zigbee messages.

Logging

WindowCovering -> config_status = bitmap8.2|1

2020-09-24 11:13:56 DEBUG (MainThread) [zigpy.device] [0x226a] Extending timeout for 0xdb request
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 219 under 220 request id, data: b'00db000700'
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 220, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x00\xdb\x00\x07\x00', 2, 0)
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 220]
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 220: 00
2020-09-24 11:13:56 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xdc 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:14:00 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.processor_use, old_state=<state sensor.processor_use=1; unit_of_measurement=%, friendly_name=Processor use, icon=mdi:cpu-64-bit @ 2020-09-24T11:13:30.582167+02:00>, new_state=<state sensor.processor_use=2; unit_of_measurement=%, friendly_name=Processor use, icon=mdi:cpu-64-bit @ 2020-09-24T11:14:00.583169+02:00>>
2020-09-24 11:14:02 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:14:02 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:14:02 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08\xdb\x01\x07\x00\x00\x18\x03', 0, 175, 255, 230, 244, 254, 0, -55]
2020-09-24 11:14:02 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=219 command_id=Command.Read_Attributes_rsp>
2020-09-24 11:14:02 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08db010700001803'
2020-09-24 11:14:02 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[config_status] = bitmap8.2|1
2020-09-24 11:14:02 DEBUG (MainThread) [homeassistant.components.zha.api] Read attribute for: cluster_id: [258] cluster_type: [in] endpoint_id: [1] attribute: [7] manufacturer: [None] response: [bitmap8.2|1] failure: [{}],

WindowCovering -> window_covering_mode = 0

2020-09-24 11:15:10 DEBUG (MainThread) [zigpy.device] [0x226a] Extending timeout for 0xe7 request
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 231 under 232 request id, data: b'00e7001700'
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 232, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x00\xe7\x00\x17\x00', 2, 0)
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 232]
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 232: 00
2020-09-24 11:15:10 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xe8 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:15:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:15:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:15:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08\xe7\x01\x17\x00\x00\x18\x00', 0, 175, 255, 242, 244, 254, 0, -55]
2020-09-24 11:15:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=231 command_id=Command.Read_Attributes_rsp>
2020-09-24 11:15:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08e7011700001800'
2020-09-24 11:15:13 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[window_covering_mode] = 0
2020-09-24 11:15:13 DEBUG (MainThread) [homeassistant.components.zha.api] Read attribute for: cluster_id: [258] cluster_type: [in] endpoint_id: [1] attribute: [23] manufacturer: [None] response: [0] failure: [{}],

Closing and making it stop by clicking a button again (while it’s going down, not holding down any buttons on the remote):

2020-09-24 11:32:06 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:06 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:06 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x0009>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5a64>, 1, 260, 258, b'\x01\x0e\x01', 0, 175, 151, 188, 246, 254, 0, -75]
2020-09-24 11:32:06 DEBUG (MainThread) [zigpy.zcl] [0x5a64:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=14 command_id=1>
2020-09-24 11:32:06 DEBUG (MainThread) [zigpy.zcl] [0x5a64:1:0x0102] ZCL request 0x0001: []
2020-09-24 11:32:06 DEBUG (MainThread) [zigpy.zcl] [0x5a64:1:0x0102] No handler for cluster command 1
2020-09-24 11:32:06 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=68:0a:e2:ff:fe:41:94:c3, unique_id=68:0a:e2:ff:fe:41:94:c3:1:0x0102, endpoint_id=1, cluster_id=258, command=down_close, args=[]>
2020-09-24 11:32:06 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5a64>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'010e01'
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [41, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0132>, 11, 260, 768, b'\x18\x83\n\x04\x00!\xf7h\x03\x00!\x14u\x07\x00!n\x01', 0, 175, 223, 188, 246, 254, 0, -66]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy.zcl] [0x0132:11:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=131 command_id=Command.Report_Attributes>
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy.zcl] [0x0132:11:0x0300] ZCL request 0x000a: [[Attribute(attrid=4, value=<TypeValue type=uint16_t, value=26871>), Attribute(attrid=3, value=<TypeValue type=uint16_t, value=29972>), Attribute(attrid=7, value=<TypeValue type=uint16_t, value=366>)]]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy.zcl] [0x0132:11:0x0300] Attribute report received: current_y=26871, current_x=29972, color_temperature=366
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0132>, ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'18830a040021f76803002114750700216e01'
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08=\n\x08\x00 \x01', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=61 command_id=Command.Report_Attributes>
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=1>)]]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=1
2020-09-24 11:32:07 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 1
2020-09-24 11:32:07 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 1
2020-09-24 11:32:07 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=100, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=99, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 61 under 2 request id, data: b'183d0b0a00'
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'083d0a08002001'
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 2, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18=\x0b\n\x00', 2, 0)
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 2]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 2: 00
2020-09-24 11:32:07 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x02 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08>\n\x08\x00 \x03', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=62 command_id=Command.Report_Attributes>
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=3>)]]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=3
2020-09-24 11:32:08 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 3
2020-09-24 11:32:08 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 3
2020-09-24 11:32:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=99, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=97, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 62 under 3 request id, data: b'183e0b0a00'
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'083e0a08002003'
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 3, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18>\x0b\n\x00', 2, 0)
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 3]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 3: 00
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x03 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08?\n\x08\x00 \x06', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=63 command_id=Command.Report_Attributes>
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=6>)]]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=6
2020-09-24 11:32:08 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 6
2020-09-24 11:32:08 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 6
2020-09-24 11:32:08 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=97, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=94, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 63 under 4 request id, data: b'183f0b0a00'
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'083f0a08002006'
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 4, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18?\x0b\n\x00', 2, 0)
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 4]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 4: 00
2020-09-24 11:32:08 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x04 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08@\n\x08\x00 \x08', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=64 command_id=Command.Report_Attributes>
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=8>)]]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=8
2020-09-24 11:32:09 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 8
2020-09-24 11:32:09 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 8
2020-09-24 11:32:09 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=94, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=92, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 64 under 5 request id, data: b'18400b0a00'
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08400a08002008'
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 5, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18@\x0b\n\x00', 2, 0)
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 5]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 5: 00
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x05 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08A\n\x08\x00 \x0b', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=65 command_id=Command.Report_Attributes>
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=11>)]]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=11
2020-09-24 11:32:09 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 11
2020-09-24 11:32:09 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 11
2020-09-24 11:32:09 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=92, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=89, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 65 under 6 request id, data: b'18410b0a00'
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08410a0800200b'
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 6, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18A\x0b\n\x00', 2, 0)
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 6]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 6: 00
2020-09-24 11:32:09 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x06 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08C\n\x08\x00 \x10', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=67 command_id=Command.Report_Attributes>
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=16>)]]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=16
2020-09-24 11:32:10 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 16
2020-09-24 11:32:10 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 16
2020-09-24 11:32:10 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=89, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=84, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 67 under 7 request id, data: b'18430b0a00'
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08430a08002010'
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 7, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18C\x0b\n\x00', 2, 0)
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 7]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 7: 00
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x07 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08B\n\x08\x00 \r', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=66 command_id=Command.Report_Attributes>
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=13>)]]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=13
2020-09-24 11:32:10 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 13
2020-09-24 11:32:10 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 13
2020-09-24 11:32:10 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=84, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=87, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 66 under 8 request id, data: b'18420b0a00'
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08420a0800200d'
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 8, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18B\x0b\n\x00', 2, 0)
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 8]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 8: 00
2020-09-24 11:32:10 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x08 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08D\n\x08\x00 \x12', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=68 command_id=Command.Report_Attributes>
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=18>)]]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=18
2020-09-24 11:32:11 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 18
2020-09-24 11:32:11 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 18
2020-09-24 11:32:11 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=87, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=82, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 68 under 9 request id, data: b'18440b0a00'
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08440a08002012'
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 9, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18D\x0b\n\x00', 2, 0)
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 9]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 9: 00
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x09 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08E\n\x08\x00 \x14', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=69 command_id=Command.Report_Attributes>
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=20>)]]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=20
2020-09-24 11:32:11 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 20
2020-09-24 11:32:11 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 20
2020-09-24 11:32:11 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=82, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=80, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 69 under 10 request id, data: b'18450b0a00'
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08450a08002014'
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 10, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18E\x0b\n\x00', 2, 0)
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 10]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 10: 00
2020-09-24 11:32:11 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08F\n\x08\x00 \x17', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=70 command_id=Command.Report_Attributes>
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=23>)]]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=23
2020-09-24 11:32:12 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 23
2020-09-24 11:32:12 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 23
2020-09-24 11:32:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=80, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=77, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 70 under 11 request id, data: b'18460b0a00'
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08460a08002017'
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 11, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18F\x0b\n\x00', 2, 0)
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 11]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 11: 00
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08G\n\x08\x00 \x19', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=71 command_id=Command.Report_Attributes>
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=25>)]]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=25
2020-09-24 11:32:12 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 25
2020-09-24 11:32:12 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 25
2020-09-24 11:32:12 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=77, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=75, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 71 under 12 request id, data: b'18470b0a00'
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08470a08002019'
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 12, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18G\x0b\n\x00', 2, 0)
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 12]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 12: 00
2020-09-24 11:32:12 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08i\n\x08\x00 \x01', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=105 command_id=Command.Report_Attributes>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=1>)]]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=1
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 1
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 1
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=100, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=99, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 105 under 13 request id, data: b'18690b0a00'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08690a08002001'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08H\n\x08\x00 \x1c', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=72 command_id=Command.Report_Attributes>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=28>)]]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=28
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 28
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 28
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=75, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=72, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 72 under 14 request id, data: b'18480b0a00'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08480a0800201c'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 13, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18i\x0b\n\x00', 2, 0)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 13]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 14, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18H\x0b\n\x00', 2, 0)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 14]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 13: 00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 14: 00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08j\n\x08\x00 \x03', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=106 command_id=Command.Report_Attributes>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=3>)]]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=3
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 3
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 3
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=99, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=97, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 106 under 15 request id, data: b'186a0b0a00'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'086a0a08002003'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 15, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18j\x0b\n\x00', 2, 0)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 15]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 15: 00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08I\n\x08\x00 \x1e', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=73 command_id=Command.Report_Attributes>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=30>)]]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=30
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 30
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 30
2020-09-24 11:32:13 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=72, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=70, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 73 under 16 request id, data: b'18490b0a00'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08490a0800201e'
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 16, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18I\x0b\n\x00', 2, 0)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 16]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 16: 00
2020-09-24 11:32:13 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x10 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08k\n\x08\x00 \x06', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=107 command_id=Command.Report_Attributes>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=6>)]]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=6
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 6
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 6
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=97, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=94, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 107 under 17 request id, data: b'186b0b0a00'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'086b0a08002006'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 17, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18k\x0b\n\x00', 2, 0)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 17]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 17: 00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x11 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08J\n\x08\x00 !', 0, 175, 199, 212, 246, 254, 0, -69]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=74 command_id=Command.Report_Attributes>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=33>)]]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=33
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 33
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 33
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=70, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=67, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 74 under 18 request id, data: b'184a0b0a00'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'084a0a08002021'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 18, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18J\x0b\n\x00', 2, 0)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 18]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 18: 00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x12 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08l\n\x08\x00 \x08', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=108 command_id=Command.Report_Attributes>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=8>)]]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=8
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 8
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 8
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=94, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=92, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 108 under 19 request id, data: b'186c0b0a00'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'086c0a08002008'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 19, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18l\x0b\n\x00', 2, 0)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 19]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 19: 00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x13 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08K\n\x08\x00 #', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=75 command_id=Command.Report_Attributes>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=35>)]]
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=35
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 35
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 35
2020-09-24 11:32:14 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=67, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=65, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 75 under 20 request id, data: b'184b0b0a00'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'084b0a08002023'
2020-09-24 11:32:14 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 20, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18K\x0b\n\x00', 2, 0)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 20]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 20: 00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x14 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08m\n\x08\x00 \x0b', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=109 command_id=Command.Report_Attributes>
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=11>)]]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=11
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 11
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 11
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=92, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=89, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 109 under 21 request id, data: b'186d0b0a00'
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'086d0a0800200b'
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 21, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18m\x0b\n\x00', 2, 0)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 21]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 21: 00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x15 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08L\n\x08\x00 &', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=76 command_id=Command.Report_Attributes>
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=38>)]]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=38
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 38
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 38
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=65, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=62, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 76 under 22 request id, data: b'184c0b0a00'
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'084c0a08002026'
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 22, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18L\x0b\n\x00', 2, 0)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 22]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 22: 00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x16 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08n\n\x08\x00 \r', 0, 175, 199, 212, 246, 254, 0, -69]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=110 command_id=Command.Report_Attributes>
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=13>)]]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=13
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 13
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 13
2020-09-24 11:32:15 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=89, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=87, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 110 under 23 request id, data: b'186e0b0a00'
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'086e0a0800200d'
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 23, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18n\x0b\n\x00', 2, 0)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 23]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 23: 00
2020-09-24 11:32:15 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x17 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08M\n\x08\x00 (', 0, 175, 199, 212, 246, 254, 0, -69]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=77 command_id=Command.Report_Attributes>
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=40>)]]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=40
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 40
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 40
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=62, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=60, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 77 under 24 request id, data: b'184d0b0a00'
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'084d0a08002028'
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 24, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18M\x0b\n\x00', 2, 0)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 24]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 24: 00
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x18 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08o\n\x08\x00 \x10', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=111 command_id=Command.Report_Attributes>
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=16>)]]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=16
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 16
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 16
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=87, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=84, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 111 under 25 request id, data: b'186f0b0a00'
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'086f0a08002010'
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 25, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18o\x0b\n\x00', 2, 0)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 25]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 25: 00
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x19 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08N\n\x08\x00 +', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=78 command_id=Command.Report_Attributes>
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=43>)]]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=43
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 43
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 43
2020-09-24 11:32:16 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=60, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=57, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 78 under 26 request id, data: b'184e0b0a00'
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'084e0a0800202b'
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 26, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18N\x0b\n\x00', 2, 0)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 26]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 26: 00
2020-09-24 11:32:16 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08O\n\x08\x00 -', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=79 command_id=Command.Report_Attributes>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=45>)]]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=45
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 45
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 45
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=57, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=55, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 79 under 27 request id, data: b'184f0b0a00'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'084f0a0800202d'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 27, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18O\x0b\n\x00', 2, 0)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 27]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 27: 00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1b 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa1f2>, 2, 260, 1024, b'\x08$\n\x00\x00!DR', 0, 175, 223, 212, 246, 254, 0, -66]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0xa1f2:2:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=36 command_id=Command.Report_Attributes>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0xa1f2:2:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=21060>)]]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0xa1f2:2:0x0400] Attribute report received: measured_value=21060
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.hall_upper_office_illuminance, old_state=<state sensor.hall_upper_office_illuminance=126.9; unit_of_measurement=lx, friendly_name=sensor.hall_upper_office_illuminance, device_class=illuminance @ 2020-09-24T11:31:47.558404+02:00>, new_state=<state sensor.hall_upper_office_illuminance=127.6; unit_of_measurement=lx, friendly_name=sensor.hall_upper_office_illuminance, device_class=illuminance @ 2020-09-24T11:32:17.494688+02:00>>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 36 under 28 request id, data: b'18240b0a00'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xa1f2>, ep: 2, profile: 0x0104, cluster_id: 0x0400, data: b'08240a0000214452'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 28, 0, <DeconzAddressEndpoint address_mode=2 address=0xA1F2 endpoint=2>, 260, 1024, 1, b'\x18$\x0b\n\x00', 2, 0)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 28]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 28: 00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xa1f2 endpoint=2>, status: 0x00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08q\n\x08\x00 \x15', 0, 175, 138, 212, 246, 254, 0, -68]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=113 command_id=Command.Report_Attributes>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=21>)]]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=21
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 21
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 21
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=84, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=79, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 113 under 29 request id, data: b'18710b0a00'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08710a08002015'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08P\n\x08\x00 0', 0, 175, 199, 212, 246, 254, 0, -69]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=80 command_id=Command.Report_Attributes>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=48>)]]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=48
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 48
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 48
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=55, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=52, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 80 under 30 request id, data: b'18500b0a00'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08500a08002030'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 29, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18q\x0b\n\x00', 2, 0)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, 29]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 30, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18P\x0b\n\x00', 2, 0)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, 30]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08p\n\x08\x00 \x12', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=112 command_id=Command.Report_Attributes>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=18>)]]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=18
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 18
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 18
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=79, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=82, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 112 under 31 request id, data: b'18700b0a00'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08700a08002012'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 29: 00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 31, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18p\x0b\n\x00', 2, 0)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, 31]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 30: 00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 31: 00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x1f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08r\n\x08\x00 \x17', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=114 command_id=Command.Report_Attributes>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=23>)]]
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=23
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 23
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 23
2020-09-24 11:32:17 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=82, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=77, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 114 under 32 request id, data: b'18720b0a00'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08720a08002017'
2020-09-24 11:32:17 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 32, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18r\x0b\n\x00', 2, 0)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 32]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 32: 00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x20 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08Q\n\x08\x00 2', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=81 command_id=Command.Report_Attributes>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=50>)]]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=50
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 50
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 50
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=52, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=50, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 81 under 33 request id, data: b'18510b0a00'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08510a08002032'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 33, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18Q\x0b\n\x00', 2, 0)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 33]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 33: 00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x21 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.GROUP address=0x0009>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5a64>, 1, 260, 258, b'\x01\x0f\x00', 0, 175, 151, 212, 246, 254, 0, -75]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x5a64:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=15 command_id=0>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x5a64:1:0x0102] ZCL request 0x0000: []
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x5a64:1:0x0102] No handler for cluster command 0
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event zha_event[L]: device_ieee=68:0a:e2:ff:fe:41:94:c3, unique_id=68:0a:e2:ff:fe:41:94:c3:1:0x0102, endpoint_id=1, cluster_id=258, command=up_open, args=[]>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x5a64>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'010f00'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08s\n\x08\x00 \x1a', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=115 command_id=Command.Report_Attributes>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=26>)]]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=26
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 26
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 26
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=77, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=74, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 115 under 34 request id, data: b'18730b0a00'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08730a0800201a'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 34, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18s\x0b\n\x00', 2, 0)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 34]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 34: 00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x22 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08R\n\x08\x00 4', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=82 command_id=Command.Report_Attributes>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=52>)]]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=52
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 52
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 52
2020-09-24 11:32:18 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=50, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=48, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 82 under 35 request id, data: b'18520b0a00'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08520a08002034'
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 35, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18R\x0b\n\x00', 2, 0)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 35]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 35: 00
2020-09-24 11:32:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x23 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00




2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08t\n\x08\x00 \x1c', 0, 175, 207, 212, 246, 254, 0, -68]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=116 command_id=Command.Report_Attributes>
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=28>)]]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=28
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 28
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 28
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=74, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=72, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 116 under 36 request id, data: b'18740b0a00'
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08740a0800201c'
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 36, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18t\x0b\n\x00', 2, 0)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 36]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 36: 00
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x24 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, 1, 260, 258, b'\x08S\n\x08\x00 6', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=83 command_id=Command.Report_Attributes>
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=54>)]]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x226a:1:0x0102] Attribute report received: current_position_lift_percentage=54
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x226A:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 54
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 54
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_right, old_state=<state cover.bedroom_finn_right=open; current_position=48, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>, new_state=<state cover.bedroom_finn_right=open; current_position=46, friendly_name=cover.bedroom_finn_right, supported_features=15 @ 2020-09-24T10:47:41.651633+02:00>>
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 83 under 37 request id, data: b'18530b0a00'
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x226a>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08530a08002036'
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 37, 0, <DeconzAddressEndpoint address_mode=2 address=0x226A endpoint=1>, 260, 258, 1, b'\x18S\x0b\n\x00', 2, 0)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 37]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 37: 00
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x25 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x226a endpoint=1>, status: 0x00
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 1, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, 1, 260, 258, b'\x08u\n\x08\x00 \x1d', 0, 175, 215, 212, 246, 254, 0, -67]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=117 command_id=Command.Report_Attributes>
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] ZCL request 0x000a: [[Attribute(attrid=8, value=<TypeValue type=uint8_t, value=29>)]]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy.zcl] [0x55f7:1:0x0102] Attribute report received: current_position_lift_percentage=29
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x55F7:1:0x0102]: Attribute report 'Window Covering'[current_position_lift_percentage] = 29
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.components.zha.cover] setting position: 29
2020-09-24 11:32:19 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.bedroom_finn_left, old_state=<state cover.bedroom_finn_left=open; current_position=72, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>, new_state=<state cover.bedroom_finn_left=open; current_position=71, friendly_name=cover.bedroom_finn_left, supported_features=15 @ 2020-09-24T10:48:25.226646+02:00>>
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 117 under 38 request id, data: b'18750b0a00'
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x55f7>, ep: 1, profile: 0x0104, cluster_id: 0x0102, data: b'08750a0800201d'
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 38, 0, <DeconzAddressEndpoint address_mode=2 address=0x55F7 endpoint=1>, 260, 258, 1, b'\x18u\x0b\n\x00', 2, 0)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 38]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 38: 00
2020-09-24 11:32:19 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x26 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x55f7 endpoint=1>, status: 0x00

As you can see in the logs the right blinds is a lot faster in this test, the left blind is setting to position to ‘1’ when the right blind was already at position ‘25’

setting position: 25 (right blind) setting position: 1 (left blind)

At some point I click the button again to make the blinds stop, this is the end position for both of them, of course the right blind is a ‘more closed’ than the left blind:

setting position: 54 (right blind) setting position: 29 (left blind)

Is there any way to reduce this delay I am experiencing? Thanks in advance!