Hey
I am new to HA and ZHA and was hoping for a bit of help with a pairing issue regarding adding the Ikea Trådfri Rodret Dimmer switch to HA
My setup is an RPI4 with RPI4 HA OS with ZHA connected to a SMlight slzb-06 over LAN.
I have successfully paired an Ikea light bulb that I can control through the GUI so I assume my setup is working.
When attempting to add the switch ZHA appears to be stuck in configuring:
After some time 2-5min, the switch appears as a device in ZHA but there are no events or triggers I can use to control the light.
Here is the log during this:
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=18, profile_id=0, cluster_id=32822, data=Serialized[b'\x12\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
[0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_rsp: [<Status.SUCCESS: 0>]
[0x0000:zdo] No handler for ZDO request:ZDOCmd.Mgmt_Permit_Joining_rsp([<Status.SUCCESS: 0>])
Device 0x5520 (b4:35:22:ff:fe:4b:4f:fd) joined the network
Device b4:35:22:ff:fe:4b:4f:fd changed id (0x6763 => 0x5520)
[0x5520] Skipping initialization, device is fully initialized
Device is initialized <Device model='RODRET Dimmer' manuf='IKEA of Sweden' nwk=0x5520 ieee=b4:35:22:ff:fe:4b:4f:fd is_initialized=True>
Checking quirks for IKEA of Sweden RODRET Dimmer (b4:35:22:ff:fe:4b:4f:fd)
Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'>
Fail because endpoint list mismatch: {1, 2, 242} {1}
Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'>
Fail because endpoint list mismatch: {1, 2, 41, 42, 242, 51, 21, 31} {1}
Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
Fail because endpoint list mismatch: {232, 230} {1}
Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
Fail because endpoint list mismatch: {232, 230} {1}
Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'>
Fail because device_type mismatch on at least one endpoint
Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
Fail because device_type mismatch on at least one endpoint
Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
Fail because device_type mismatch on at least one endpoint
Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
Fail because device_type mismatch on at least one endpoint
Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
Fail because endpoint list mismatch: {11, 13} {1}
device - 0x5520:b4:35:22:ff:fe:4b:4f:fd entering async_device_initialized - is_new_join: True
device - 0x5520:b4:35:22:ff:fe:4b:4f:fd has joined the ZHA zigbee network
[0x0F68](RODRET Dimmer): started configuration
[0x0F68:ZDO](RODRET Dimmer): 'async_configure' stage succeeded
[0x0F68:1:0x0001]: Performing cluster binding
[0x0f68] Extending timeout for 0x92 request
[0x0F68:1:0x0003]: Configuring cluster attribute reporting
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x5520), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=129, profile_id=0, cluster_id=19, data=Serialized[b'\x81 U\xfdOK\xfe\xff"5\xb4\x80'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None)
Device 0x5520 (b4:35:22:ff:fe:4b:4f:fd) joined the network
[0x5520:zdo] ZDO request ZDOCmd.Device_annce: [0x5520, b4:35:22:ff:fe:4b:4f:fd, 128]
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x5520), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=2, source_route=None, extended_timeout=False, tsn=0, profile_id=49246, cluster_id=3, data=Serialized[b'\x11\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=11, non_member_radius=0, lqi=207, rssi=None)
[0x5520:1:0x0003] Received ZCL frame: b'\x11\x00\x01'
[0x5520:1:0x0003] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=0, command_id=1, *direction=<Direction.Server_to_Client: 0>)
[0x5520:1:0x0003] Decoded ZCL frame: Identify:identify_query()
[0x5520:1:0x0003] Received command 0x01 (TSN 0): identify_query()
[0x5520:1:0x0003] No explicit handler for cluster command 0x01: identify_query()
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x5520), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=3, data=Serialized[b'\x11\x00\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=11, non_member_radius=0, lqi=207, rssi=None)
[0x5520:1:0x0003] Received ZCL frame: b'\x11\x00\x01'
[0x5520:1:0x0003] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=0, command_id=1, *direction=<Direction.Server_to_Client: 0>)
[0x5520:1:0x0003] Decoded ZCL frame: Identify:identify_query()
[0x5520:1:0x0003] Received command 0x01 (TSN 0): identify_query()
[0x5520:1:0x0003] No explicit handler for cluster command 0x01: identify_query()
[0x0F68:1:0x1000]: Couldn't get list of groups: Request failed after 5 attempts: <Status.NWK_NO_ROUTE: 205>
[0x0F68:1:0x0001]: Failed to bind 'power' cluster:
[0x0F68:1:0x0020]: Failed to bind 'poll_control' cluster:
[0x0F68:1:0x0008]: Failed to bind 'level' cluster:
[0x0F68:1:0x0006]: Failed to bind 'on_off' cluster:
[0x0F68](RODRET Dimmer): Device seen - marking the device available and resetting counter
[0x0F68](RODRET Dimmer): Update device availability - device available: True - new availability: True - changed: False
[0x0F68:1:0x0001]: failed to set reporting on 'power' cluster for: Request failed after 5 attempts: <Status.NWK_NO_ROUTE: 205>
[0x0F68:1:0x0020]: Couldn't set check-in interval: Request failed after 5 attempts: <Status.NWK_NO_ROUTE: 205>
[0x0F68:1:0x0020]: finished cluster handler configuration
[0x0F68:1:0x0001]: 'async_configure' stage succeeded
[0x0F68:1:0x0003]: 'async_configure' stage succeeded
[0x0F68:1:0x0000]: 'async_configure' stage succeeded
[0x0F68:1:0x0020]: 'async_configure' stage succeeded
[0x0F68:1:0x1000]: 'async_configure' stage succeeded
[0x0F68:1:0x0008]: 'async_configure' stage succeeded
[0x0F68:1:0x0006]: 'async_configure' stage succeeded
[0x0F68:1:0x0019]: 'async_configure' stage succeeded
[0x0F68](RODRET Dimmer): completed configuration
Tries remaining: 3
[0x0F68:1:0x0003] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=153, command_id=64, *direction=<Direction.Server_to_Client: 0>)
[0x0F68:1:0x0003] Sending request: trigger_effect(effect_id=<EffectIdentifier.Okay: 2>, effect_variant=<EffectVariant.Default: 0>)
[0x0f68] Extending timeout for 0x99 request
Tries remaining: 2
[0x0F68:1:0x0003] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=154, command_id=64, *direction=<Direction.Server_to_Client: 0>)
[0x0F68:1:0x0003] Sending request: trigger_effect(effect_id=<EffectIdentifier.Okay: 2>, effect_variant=<EffectVariant.Default: 0>)
[0x0f68] Extending timeout for 0x9a request
[0x7E52](TRADFRIbulbE27WSglobeopal1055lm): Device seen - marking the device available and resetting counter
[0x7E52](TRADFRIbulbE27WSglobeopal1055lm): Update device availability - device available: True - new availability: True - changed: False
Tries remaining: 1
[0x0F68:1:0x0003] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=155, command_id=64, *direction=<Direction.Server_to_Client: 0>)
[0x0F68:1:0x0003] Sending request: trigger_effect(effect_id=<EffectIdentifier.Okay: 2>, effect_variant=<EffectVariant.Default: 0>)
[0x0f68] Extending timeout for 0x9b request
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x5520), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=25, data=Serialized[b'\x01\x01\x01\x01|\x11\xcd\x11G\x00\x00\x01\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=29, non_member_radius=0, lqi=182, rssi=None)
[0x5520:1:0x0019] Received ZCL frame: b'\x01\x01\x01\x01|\x11\xcd\x11G\x00\x00\x01\x00\x00'
[0x5520:1:0x0019] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=1, command_id=1, *direction=<Direction.Server_to_Client: 0>)
[0x5520:1:0x0019] Decoded ZCL frame: Ota:QueryNextImageCommand(field_control=<FieldControl.HardwareVersion: 1>, manufacturer_code=4476, image_type=4557, current_file_version=16777287, hardware_version=0)
[0x5520:1:0x0019] Received command 0x01 (TSN 1): QueryNextImageCommand(field_control=<FieldControl.HardwareVersion: 1>, manufacturer_code=4476, image_type=4557, current_file_version=16777287, hardware_version=0)
[0x5520:1:0x0019] OTA query_next_image handler for 'IKEA of Sweden RODRET Dimmer': field_control=1, manufacturer_id=4476, image_type=4557, current_file_version=16777287, hardware_version=0, model='RODRET Dimmer'
[0x5520:1:0x0019] No OTA image is available
[0x5520:1:0x0019] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=True, *is_general=False), tsn=1, command_id=2, *direction=<Direction.Client_to_Server: 1>)
[0x5520:1:0x0019] Sending reply: query_next_image_response(status=<Status.NO_IMAGE_AVAILABLE: 152>, manufacturer_code=None, image_type=None, file_version=None, image_size=None)
[0x0F68](RODRET Dimmer): Device seen - marking the device available and resetting counter
[0x0F68](RODRET Dimmer): Update device availability - device available: True - new availability: True - changed: False
[0x7E52](TRADFRIbulbE27WSglobeopal1055lm): Device seen - marking the device available and resetting counter
[0x7E52](TRADFRIbulbE27WSglobeopal1055lm): Update device availability - device available: True - new availability: True - changed: False
[0x0F68](RODRET Dimmer): Device seen - marking the device available and resetting counter
[0x0F68](RODRET Dimmer): Update device availability - device available: True - new availability: True - changed: False