ZHA bulbs become unavailble seconds after pairing

Salamandar · December 16, 2021, 11:38am

Hi !
I got an issue lately. I can’t properly pair bulbs using ZHA.
Pairing works fine, devices are correctly detected BUT seconds after pairing (time during which i can control the bulb), they become unavailable.
Tried with 2 brands :

IKEA LED2002G5
Lexman RGBTW A60

Here is the log in the pairing page:

New device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) joined the network
[0xa5d1] Scheduling initialization
Tries remaining: 3
[0xa5d1] Requesting 'Node Descriptor'
Tries remaining: 2
[0xa5d1] Extending timeout for 0x21 request
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) joined the network
[0xa5d1] Scheduling initialization
[0xa5d1] Canceling old initialize call
Tries remaining: 3
[0xa5d1] Requesting 'Node Descriptor'
Tries remaining: 2
[0xa5d1] Extending timeout for 0x23 request
[0xF224:1:0x0006]: initializing channel: from_cache: False
[0x2874:1:0x0702]: initializing channel: from_cache: False
[0xF224:1:0x0300]: initializing channel: from_cache: False
[0xF224:1:0x0008]: initializing channel: from_cache: False
[0x2874:1:0x0b04]: initializing channel: from_cache: False
[0x2874:1:0x0006]: initializing channel: from_cache: False
[0x8358](TRADFRI SHORTCUT Button): Attempting to checkin with device - missed checkins: 2
[0x8358] Extending timeout for 0x39 request
[0x33D0](TRADFRIbulbE14WSglobeopal470lm): Attempting to checkin with device - missed checkins: 2
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) joined the network
[0xa5d1] Scheduling initialization
[0xa5d1] Canceling old initialize call
Tries remaining: 3
[0xa5d1] Requesting 'Node Descriptor'
Tries remaining: 2
[0xa5d1] Extending timeout for 0x31 request
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) joined the network
[0xa5d1] Scheduling initialization
[0xa5d1] Canceling old initialize call
Tries remaining: 3
[0xa5d1] Requesting 'Node Descriptor'
Tries remaining: 2
[0xa5d1] Extending timeout for 0x33 request
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) joined the network
[0xa5d1] Scheduling initialization
[0xa5d1] Canceling old initialize call
Received frame on uninitialized device <Device model=None manuf=None nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=False> from ep 0 to ep 0, cluster 19: b'\x81\xd1\xa5\xb5\x07\xd5\xfe\xffr\x02\\\x8e'
[0xa5d1:zdo] ZDO request ZDOCmd.Device_annce: [0xA5D1, 5c:02:72:ff:fe:d5:07:b5, 142]
Tries remaining: 3
[0xa5d1] Requesting 'Node Descriptor'
Tries remaining: 2
[0xa5d1] Extending timeout for 0x35 request
Received frame on uninitialized device <Device model=None manuf=None nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'5\x00\xd1\xa5\x01@\x8ew\x12RR\x00\x00,R\x00\x00'
[0xa5d1] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4727, maximum_buffer_size=82, maximum_incoming_transfer_size=82, server_mask=11264, maximum_outgoing_transfer_size=82, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)
[0xa5d1] Discovering endpoints
Tries remaining: 3
Received frame on uninitialized device <Device model=None manuf=None nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=False> from ep 0 to ep 0, cluster 32773: b'7\x00\xd1\xa5\x02\x01\xf2'
[0xa5d1] Discovered endpoints: [1, 242]
[0xa5d1] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>, <Endpoint id=242 in=[] out=[] status=<Status.NEW: 0>>]
[0xa5d1:1] Discovering endpoint information
Tries remaining: 3
Received frame on uninitialized device <Device model=None manuf=None nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'9\x00\xd1\xa5\x1c\x01\x04\x01\r\x01\x01\t\x00\x00\x03\x00\x04\x00\x05\x00\x06\x00\x08\x00\x00\x03\x00\x10\x82\xfc\x01\x19\x00'
[0xa5d1:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=269, device_version=1, input_clusters=[0, 3, 4, 5, 6, 8, 768, 4096, 64642], output_clusters=[25])
[0xa5d1:242] Discovering endpoint information
Tries remaining: 3
Received frame on uninitialized device <Device model=None manuf=None nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=False> from ep 0 to ep 0, cluster 32772: b';\x00\xd1\xa5\n\xf2\xe0\xa1a\x00\x00\x00\x01!\x00'
[0xa5d1:242] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=242, profile=41440, device_type=97, device_version=0, input_clusters=[], output_clusters=[33])
[0xa5d1:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=61 command_id=Command.Read_Attributes_rsp>
[0xa5d1] Read model 'LXEK-1' and manufacturer 'Adeo' from <Endpoint id=1 in=[basic:0x0000, identify:0x0003, groups:0x0004, scenes:0x0005, on_off:0x0006, level:0x0008, light_color:0x0300, lightlink:0x1000, manufacturer_specific:0xFC82] out=[ota:0x0019] status=<Status.ZDO_INIT: 1>>
[0xa5d1] Discovered basic device information for <Device model='LXEK-1' manuf='Adeo' nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=True>
Device is initialized <Device model='LXEK-1' manuf='Adeo' nwk=0xA5D1 ieee=5c:02:72:ff:fe:d5:07:b5 is_initialized=True>
Checking quirks for Adeo LXEK-1 (5c:02:72:ff:fe:d5:07:b5)
Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'>
Fail because endpoint list mismatch: {232, 230} {1, 242}
Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
Fail because endpoint list mismatch: {232, 230} {1, 242}
Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
Fail because endpoint list mismatch: {1} {1, 242}
Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'>
Fail because endpoint list mismatch: {1} {1, 242}
Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
Fail because endpoint list mismatch: {1} {1, 242}
Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
Fail because endpoint list mismatch: {11, 13} {1, 242}
Considering <class 'bellows.zigbee.application.EZSPCoordinator'>
Fail because endpoint list mismatch: {1} {1, 242}
device - 0xA5D1:5c:02:72:ff:fe:d5:07:b5 entering async_device_initialized - is_new_join: True
device - 0xA5D1:5c:02:72:ff:fe:d5:07:b5 has joined the ZHA zigbee network
[0xA5D1](LXEK-1): started configuration
[0xA5D1:ZDO](LXEK-1): 'async_configure' stage succeeded
Error handling '_save_attribute' event with (5c:02:72:ff:fe:d5:07:b5, 1, 0, 4, 'Adeo') params: FOREIGN KEY constraint failed
Error handling '_save_attribute' event with (5c:02:72:ff:fe:d5:07:b5, 1, 0, 5, 'LXEK-1') params: FOREIGN KEY constraint failed
[0xA5D1:1:0x0006]: bound 'on_off' cluster: Status.SUCCESS
[0xA5D1:1:0x0008]: bound 'level' cluster: Status.SUCCESS
[0xA5D1:1:0x0300]: bound 'light_color' cluster: Status.SUCCESS
[0xa5d1:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=69 command_id=Command.Configure_Reporting_rsp>
[0xA5D1:1:0x0006]: Successfully configured reporting for '{'on_off': (0, 900, 1)}' on 'on_off' cluster: [ConfigureReportingResponseRecord(status=0)]
[0xA5D1:1:0x0006]: finished channel configuration
[0xA5D1:1:0x0000]: finished channel configuration
[0xa5d1:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=71 command_id=Command.Configure_Reporting_rsp>
[0xA5D1:1:0x0008]: Successfully configured reporting for '{'current_level': (1, 900, 1)}' on 'level' cluster: [ConfigureReportingResponseRecord(status=0)]
[0xA5D1:1:0x0008]: finished channel configuration
[0xa5d1:1:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=73 command_id=Command.Configure_Reporting_rsp>
[0xA5D1:1:0x0019]: finished channel configuration
[0xA5D1:1:0x0300]: Successfully configured reporting for '{'current_x': (30, 900, 1), 'current_y': (30, 900, 1), 'color_temperature': (30, 900, 1)}' on 'light_color' cluster: [ConfigureReportingResponseRecord(status=0)]
[0xA5D1:1:0x0300]: finished channel configuration
[0xa5d1:1:0x1000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=75 command_id=65>
[0xA5D1:1:0x0006]: 'async_configure' stage succeeded
[0xA5D1:1:0x0300]: 'async_configure' stage succeeded
[0xA5D1:1:0x0008]: 'async_configure' stage succeeded
[0xA5D1:1:0x0000]: 'async_configure' stage succeeded
[0xA5D1:1:0x1000]: 'async_configure' stage succeeded
[0xA5D1:1:0x0019]: 'async_configure' stage succeeded
[0xA5D1](LXEK-1): completed configuration
[0xA5D1](LXEK-1): stored in registry: ZhaDeviceEntry(name='Adeo LXEK-1', ieee='5c:02:72:ff:fe:d5:07:b5', last_seen=1639655209.1913035)
[0xa5d1:1:0x0003] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=77 command_id=Command.Default_Response>
[0xA5D1:1:0x0003]: executed 'trigger_effect' command with args: '(2, 0)' kwargs: '{}' result: [64, <Status.SUCCESS: 0>]
[0xA5D1](LXEK-1): started initialization
[0xA5D1:ZDO](LXEK-1): 'async_initialize' stage succeeded
[0xA5D1:1:0x0006]: initializing channel: from_cache: False
[0xA5D1:1:0x0300]: initializing channel: from_cache: False
[0xA5D1:1:0x0008]: initializing channel: from_cache: False
[0xa5d1:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=79 command_id=Command.Read_Attributes_rsp>
[0xA5D1:1:0x0006]: finished channel initialization
[0xA5D1:1:0x0000]: initializing channel: from_cache: False
[0xA5D1:1:0x0000]: finished channel initialization
[0xA5D1:1:0x1000]: initializing channel: from_cache: False
[0xA5D1:1:0x1000]: finished channel initialization
[0xA5D1:1:0x0019]: initializing channel: from_cache: False
[0xA5D1:1:0x0019]: finished channel initialization
[0xa5d1:1:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=81 command_id=Command.Read_Attributes_rsp>
[0xa5d1:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=83 command_id=Command.Read_Attributes_rsp>
[0xA5D1:1:0x0008]: received attribute: 0 update with value: 254
[0xA5D1:1:0x0008]: finished channel initialization
[0xa5d1:1:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=85 command_id=Command.Read_Attributes_rsp>
[0xA5D1:1:0x0300]: finished channel initialization
[0xA5D1:1:0x0006]: 'async_initialize' stage succeeded
[0xA5D1:1:0x0300]: 'async_initialize' stage succeeded
[0xA5D1:1:0x0008]: 'async_initialize' stage succeeded
[0xA5D1:1:0x0000]: 'async_initialize' stage succeeded
[0xA5D1:1:0x1000]: 'async_initialize' stage succeeded
[0xA5D1:1:0x0019]: 'async_initialize' stage succeeded
[0xA5D1](LXEK-1): power source: Mains
[0xA5D1](LXEK-1): completed initialization
None: polling current state
None: polling current state
None: polling current state
None: polling current state
None: polling current state
None: polling current state
None: polling current state
[0x2874:1:0x0006]: attempting to update onoff state - from cache: False
[0x6743:1:0x0006]: attempting to update onoff state - from cache: False
[0xFB9C:1:0x0006]: attempting to update onoff state - from cache: False
[0xC6DC:1:0x0006]: attempting to update onoff state - from cache: False
[0x6FC1:1:0x0006]: attempting to update onoff state - from cache: False
[0x9823:1:0x0006]: attempting to update onoff state - from cache: False
[0x89E6:1:0x0006]: attempting to update onoff state - from cache: False
[0x994f:3:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=87 command_id=Command.Read_Attributes_rsp>
[0xf224] Delivery error for seq # 0x25, on endpoint id 1 cluster 0x0006: message send failure
[0xF224:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0xf224:1:0x0006]: Message send failure
[0xF224:1:0x0006]: async_initialize: retryable request #3 failed: [0xf224:1:0x0006]: Message send failure. Retrying in 3.1s
[0x2874] Delivery error for seq # 0x27, on endpoint id 1 cluster 0x0702: message send failure
[0x2874:1:0x0702]: failed to get attributes '['instantaneous_demand', 'current_summ_delivered', 'status']' on 'smartenergy_metering' cluster: [0x2874:1:0x0702]: Message send failure
[0x2874:1:0x0702]: async_initialize: retryable request #3 failed: [0x2874:1:0x0702]: Message send failure. Retrying in 2.7s
[0xf224] Delivery error for seq # 0x29, on endpoint id 1 cluster 0x0300: message send failure
[0xF224:1:0x0300]: failed to get attributes '['color_loop_active', 'current_x', 'current_y', 'color_temperature']' on 'light_color' cluster: [0xf224:1:0x0300]: Message send failure
[0xF224:1:0x0300]: async_initialize: retryable request #3 failed: [0xf224:1:0x0300]: Message send failure. Retrying in 3.0s
[0xf224] Delivery error for seq # 0x2b, on endpoint id 1 cluster 0x0008: message send failure
[0xF224:1:0x0008]: failed to get attributes '['current_level']' on 'level' cluster: [0xf224:1:0x0008]: Message send failure
[0xF224:1:0x0008]: async_initialize: retryable request #3 failed: [0xf224:1:0x0008]: Message send failure. Retrying in 3.2s
[0x2874] Delivery error for seq # 0x2d, on endpoint id 1 cluster 0x0b04: message send failure
[0x2874:1:0x0b04]: failed to get attributes '['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max', 'rms_voltage', 'rms_voltage_max']' on 'electrical_measurement' cluster: [0x2874:1:0x0b04]: Message send failure
[0x2874:1:0x0b04]: async_initialize: retryable request #3 failed: [0x2874:1:0x0b04]: Message send failure. Retrying in 3.1s
[0x1ee3:2:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=91 command_id=Command.Read_Attributes_rsp>
[0x1ee3:3:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=93 command_id=Command.Read_Attributes_rsp>
[0x1ee3:4:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=95 command_id=Command.Read_Attributes_rsp>
[0x1ee3:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=89 command_id=Command.Read_Attributes_rsp>
[0xa5d1:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=99 command_id=Command.Read_Attributes_rsp>
[0x2874] Delivery error for seq # 0x2f, on endpoint id 1 cluster 0x0006: message send failure
[0x2874:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0x2874:1:0x0006]: Message send failure
[0x2874:1:0x0006]: async_initialize: retryable request #3 failed: [0x2874:1:0x0006]: Message send failure. Retrying in 3.3s
[0x994f:3:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=115 command_id=Command.Read_Attributes_rsp>
[0x994F:3:0x0008]: received attribute: 0 update with value: 100
[0xfb9c:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=105 command_id=Command.Read_Attributes_rsp>
[0x6fc1:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=109 command_id=Command.Read_Attributes_rsp>
[0x9823:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=111 command_id=Command.Read_Attributes_rsp>
[0xa5d1:1:0x0008] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=117 command_id=Command.Read_Attributes_rsp>
[0xA5D1:1:0x0008]: received attribute: 0 update with value: 254
[0xa5d1:1:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=121 command_id=Command.Read_Attributes_rsp>
[0x994f:3:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=119 command_id=Command.Read_Attributes_rsp>
[0xa5d1:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[0xa5d1:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[0x6743:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=103 command_id=Command.Read_Attributes_rsp>
[0x2874:1:0x0702]: initializing channel: from_cache: False
[0xF224:1:0x0006]: initializing channel: from_cache: False
[0xF224:1:0x0300]: initializing channel: from_cache: False
[0x2874:1:0x0b04]: initializing channel: from_cache: False
[0xF224:1:0x0008]: initializing channel: from_cache: False
[0x2874:1:0x0006]: initializing channel: from_cache: False
[0x0000:zdo] ZDO request ZDOCmd.Parent_annce: [[00:15:8d:00:05:4c:b1:c4, 00:15:8d:00:02:c3:7c:3a, 00:15:8d:00:02:a6:fe:5a]]
[0x0000:zdo] No handler for ZDO request:ZDOCmd.Parent_annce([[00:15:8d:00:05:4c:b1:c4, 00:15:8d:00:02:c3:7c:3a, 00:15:8d:00:02:a6:fe:5a]])
Failed to parse message (b'818403c4') on cluster 32799, because Data is too short to contain 1 bytes
[0xce02] Delivery error for seq # 0x61, on endpoint id 1 cluster 0x0006: message send failure
[0x2874] Delivery error for seq # 0x65, on endpoint id 1 cluster 0x0006: message send failure
[0xc6dc] Delivery error for seq # 0x6b, on endpoint id 1 cluster 0x0006: message send failure
[0x89e6] Delivery error for seq # 0x71, on endpoint id 1 cluster 0x0006: message send failure
[0x2874] Delivery error for seq # 0x7b, on endpoint id 1 cluster 0x0702: message send failure
[0x2874:1:0x0702]: failed to get attributes '['instantaneous_demand', 'current_summ_delivered', 'status']' on 'smartenergy_metering' cluster: [0x2874:1:0x0702]: Message send failure
[0x2874:1:0x0702]: async_initialize: all attempts have failed: [DeliveryError('[0x2874:1:0x0702]: Message send failure'), DeliveryError('[0x2874:1:0x0702]: Message send failure'), DeliveryError('[0x2874:1:0x0702]: Message send failure'), DeliveryError('[0x2874:1:0x0702]: Message send failure')]
[0x2874:1:0x0000]: initializing channel: from_cache: False
[0x2874:1:0x0000]: finished channel initialization
[0x2874:1:0x0019]: initializing channel: from_cache: False
[0x2874:1:0x0019]: finished channel initialization
[0xf224] Delivery error for seq # 0x7d, on endpoint id 1 cluster 0x0006: message send failure
[0xF224:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0xf224:1:0x0006]: Message send failure
[0xF224:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('[0xf224:1:0x0006]: Message send failure'), DeliveryError('[0xf224:1:0x0006]: Message send failure'), DeliveryError('[0xf224:1:0x0006]: Message send failure'), DeliveryError('[0xf224:1:0x0006]: Message send failure')]
[0xF224:1:0x0000]: initializing channel: from_cache: False
[0xF224:1:0x0000]: finished channel initialization
[0xF224:1:0x1000]: initializing channel: from_cache: False
[0xF224:1:0x1000]: finished channel initialization
[0xF224:1:0x0019]: initializing channel: from_cache: False
[0xF224:1:0x0019]: finished channel initialization
[0xF224:1:0x0005]: initializing channel: from_cache: False
[0xF224:1:0x0005]: finished channel initialization
[0xf224] Delivery error for seq # 0x7f, on endpoint id 1 cluster 0x0300: message send failure
[0xF224:1:0x0300]: failed to get attributes '['color_loop_active', 'current_x', 'current_y', 'color_temperature']' on 'light_color' cluster: [0xf224:1:0x0300]: Message send failure
[0xF224:1:0x0300]: async_initialize: all attempts have failed: [DeliveryError('[0xf224:1:0x0300]: Message send failure'), DeliveryError('[0xf224:1:0x0300]: Message send failure'), DeliveryError('[0xf224:1:0x0300]: Message send failure'), DeliveryError('[0xf224:1:0x0300]: Message send failure')]
[0x2874] Delivery error for seq # 0x81, on endpoint id 1 cluster 0x0b04: message send failure
[0x2874:1:0x0b04]: failed to get attributes '['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max', 'rms_voltage', 'rms_voltage_max']' on 'electrical_measurement' cluster: [0x2874:1:0x0b04]: Message send failure
[0x2874:1:0x0b04]: async_initialize: all attempts have failed: [DeliveryError('[0x2874:1:0x0b04]: Message send failure'), DeliveryError('[0x2874:1:0x0b04]: Message send failure'), DeliveryError('[0x2874:1:0x0b04]: Message send failure'), DeliveryError('[0x2874:1:0x0b04]: Message send failure')]
[0xf224] Delivery error for seq # 0x83, on endpoint id 1 cluster 0x0008: message send failure
[0xF224:1:0x0008]: failed to get attributes '['current_level']' on 'level' cluster: [0xf224:1:0x0008]: Message send failure
[0xF224:1:0x0008]: async_initialize: all attempts have failed: [DeliveryError('[0xf224:1:0x0008]: Message send failure'), DeliveryError('[0xf224:1:0x0008]: Message send failure'), DeliveryError('[0xf224:1:0x0008]: Message send failure'), DeliveryError('[0xf224:1:0x0008]: Message send failure')]
[0xF224:1:0x0006]: 'async_initialize' stage succeeded
[0xF224:1:0x0300]: 'async_initialize' stage succeeded
[0xF224:1:0x0008]: 'async_initialize' stage succeeded
[0xF224:1:0x0000]: 'async_initialize' stage succeeded
[0xF224:1:0x1000]: 'async_initialize' stage succeeded
[0xF224:1:0x0019]: 'async_initialize' stage succeeded
[0xF224:1:0x0005]: 'async_initialize' stage succeeded
[0xF224](TRADFRI bulb E27 WS opal 1000lm): power source: Mains
[0xF224](TRADFRI bulb E27 WS opal 1000lm): completed initialization
[0x6743](TS0121): started initialization
[0x6743:ZDO](TS0121): 'async_initialize' stage succeeded
[0x6743:1:0x0006]: initializing channel: from_cache: False
[0x6743:1:0x0702]: initializing channel: from_cache: False
[0x6743:1:0x0b04]: initializing channel: from_cache: False
[0x6743:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=137 command_id=Command.Read_Attributes_rsp>
[0x6743:1:0x0006]: finished channel initialization
[0x6743:1:0x0000]: initializing channel: from_cache: False
[0x6743:1:0x0000]: finished channel initialization
[0x6743:1:0x0019]: initializing channel: from_cache: False
[0x6743:1:0x0019]: finished channel initialization
[0x6743:1:0x0702] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=139 command_id=Command.Read_Attributes_rsp>
[0x6743:1:0x0702]: finished channel initialization
[0x6743:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=141 command_id=Command.Read_Attributes_rsp>
[0x6743:1:0x0b04] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=143 command_id=Command.Read_Attributes_rsp>
[0x6743:1:0x0b04]: finished channel initialization
[0x6743:1:0x0006]: 'async_initialize' stage succeeded
[0x6743:1:0x0702]: 'async_initialize' stage succeeded
[0x6743:1:0x0b04]: 'async_initialize' stage succeeded
[0x6743:1:0x0000]: 'async_initialize' stage succeeded
[0x6743:1:0x0019]: 'async_initialize' stage succeeded
[0x6743](TS0121): power source: Mains
[0x6743](TS0121): completed initialization
[0x1EE3](E220-KR4N0Z0-HA): started initialization
[0x1EE3:ZDO](E220-KR4N0Z0-HA): 'async_initialize' stage succeeded
[0x1EE3:1:0x0006]: initializing channel: from_cache: False
[0x1EE3:1:0x0000]: initializing channel: from_cache: False
[0x1EE3:1:0x0000]: finished channel initialization
[0x1EE3:2:0x0006]: initializing channel: from_cache: False
[0x1EE3:2:0x0000]: initializing channel: from_cache: False
[0x1EE3:2:0x0000]: finished channel initialization
[0x1EE3:3:0x0006]: initializing channel: from_cache: False
[0x1ee3:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=145 command_id=Command.Read_Attributes_rsp>
[0x1EE3:1:0x0006]: finished channel initialization
[0x1EE3:3:0x0000]: initializing channel: from_cache: False
[0x1EE3:3:0x0000]: finished channel initialization
[0x1EE3:4:0x0006]: initializing channel: from_cache: False
[0x1EE3:1:0x0006]: 'async_initialize' stage succeeded
[0x1EE3:1:0x0000]: 'async_initialize' stage succeeded
[0x1ee3:2:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=147 command_id=Command.Read_Attributes_rsp>
[0x2874] Delivery error for seq # 0x85, on endpoint id 1 cluster 0x0006: message send failure
[0x2874:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0x2874:1:0x0006]: Message send failure
[0x2874:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('[0x2874:1:0x0006]: Message send failure'), DeliveryError('[0x2874:1:0x0006]: Message send failure'), DeliveryError('[0x2874:1:0x0006]: Message send failure'), DeliveryError('[0x2874:1:0x0006]: Message send failure')]
[0x1ee3:3:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=149 command_id=Command.Read_Attributes_rsp>
[0x1EE3:2:0x0006]: finished channel initialization
[0x2874:1:0x0006]: 'async_initialize' stage succeeded
[0x2874:1:0x0b04]: 'async_initialize' stage succeeded
[0x2874:1:0x0702]: 'async_initialize' stage succeeded
[0x2874:1:0x0000]: 'async_initialize' stage succeeded
[0x2874:1:0x0019]: 'async_initialize' stage succeeded

Can anyone help me ? At first I thought the Ikea bulb was unsupported but seeing that another brand has the same issue, I think the problem lies with ZHA or my zigbee adapter, Elelabs HUSBZB-1.

Salamandar · December 16, 2021, 11:50am

(End of the log because the forum puts a limit on the message length)


[0x1EE3:4:0x0000]: initializing channel: from_cache: False
[0x1EE3:4:0x0000]: finished channel initialization
[0x1EE3:3:0x0006]: finished channel initialization
[0x1EE3:2:0x0006]: 'async_initialize' stage succeeded
[0x1EE3:2:0x0000]: 'async_initialize' stage succeeded
[0x2874](TS0121): power source: Mains
[0x2874](TS0121): completed initialization
[0xCE02](CLA60 RGBW Z3): started initialization
[0xCE02:ZDO](CLA60 RGBW Z3): 'async_initialize' stage succeeded
[0x1EE3:3:0x0006]: 'async_initialize' stage succeeded
[0x1EE3:3:0x0000]: 'async_initialize' stage succeeded
[0x1ee3:4:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=151 command_id=Command.Read_Attributes_rsp>
[0xCE02:1:0x0006]: initializing channel: from_cache: False
[0xCE02:1:0x0300]: initializing channel: from_cache: False
[0xCE02:1:0x0008]: initializing channel: from_cache: False
[0x1EE3:4:0x0006]: finished channel initialization
[0x1EE3:4:0x0006]: 'async_initialize' stage succeeded
[0x1EE3:4:0x0000]: 'async_initialize' stage succeeded
[0x1EE3](E220-KR4N0Z0-HA): power source: Mains
[0x1EE3](E220-KR4N0Z0-HA): completed initialization
[0xC89A](CLA60 RGBW Z3): started initialization
[0xC89A:ZDO](CLA60 RGBW Z3): 'async_initialize' stage succeeded
[0xC89A:1:0x0006]: initializing channel: from_cache: False
[0xC89A:1:0x0300]: initializing channel: from_cache: False
[0xC89A:1:0x0008]: initializing channel: from_cache: False
[0xce02] Delivery error for seq # 0x87, on endpoint id 1 cluster 0x0008: message send failure
[0xce02] Delivery error for seq # 0x99, on endpoint id 1 cluster 0x0006: message send failure
[0xCE02:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0xce02:1:0x0006]: Message send failure
[0xCE02:1:0x0006]: async_initialize: retryable request #1 failed: [0xce02:1:0x0006]: Message send failure. Retrying in 1.1s
[0xce02] Delivery error for seq # 0x9b, on endpoint id 1 cluster 0x0300: message send failure
[0xCE02:1:0x0300]: failed to get attributes '['color_loop_active', 'current_x', 'current_y', 'color_temperature']' on 'light_color' cluster: [0xce02:1:0x0300]: Message send failure
[0xCE02:1:0x0300]: async_initialize: retryable request #1 failed: [0xce02:1:0x0300]: Message send failure. Retrying in 1.1s
[0xce02] Delivery error for seq # 0x9d, on endpoint id 1 cluster 0x0008: message send failure
[0xCE02:1:0x0008]: failed to get attributes '['current_level']' on 'level' cluster: [0xce02:1:0x0008]: Message send failure
[0xCE02:1:0x0008]: async_initialize: retryable request #1 failed: [0xce02:1:0x0008]: Message send failure. Retrying in 1.1s
[0xc89a] Delivery error for seq # 0x9f, on endpoint id 1 cluster 0x0006: message send failure
[0xC89A:1:0x0006]: failed to get attributes '['on_off']' on 'on_off' cluster: [0xc89a:1:0x0006]: Message send failure
[0xC89A:1:0x0006]: async_initialize: retryable request #1 failed: [0xc89a:1:0x0006]: Message send failure. Retrying in 1.1s
[0xc89a] Delivery error for seq # 0xa1, on endpoint id 1 cluster 0x0300: message send failure
[0xC89A:1:0x0300]: failed to get attributes '['color_loop_active', 'current_x', 'current_y', 'color_temperature']' on 'light_color' cluster: [0xc89a:1:0x0300]: Message send failure
[0xC89A:1:0x0300]: async_initialize: retryable request #1 failed: [0xc89a:1:0x0300]: Message send failure. Retrying in 0.9s
[0xc89a] Delivery error for seq # 0xa3, on endpoint id 1 cluster 0x0008: message send failure
[0xC89A:1:0x0008]: failed to get attributes '['current_level']' on 'level' cluster: [0xc89a:1:0x0008]: Message send failure
[0xC89A:1:0x0008]: async_initialize: retryable request #1 failed: [0xc89a:1:0x0008]: Message send failure. Retrying in 0.9s
[0xC89A:1:0x0300]: initializing channel: from_cache: False
[0xC89A:1:0x0008]: initializing channel: from_cache: False
[0xCE02:1:0x0006]: initializing channel: from_cache: False
[0xCE02:1:0x0300]: initializing channel: from_cache: False
[0xCE02:1:0x0008]: initializing channel: from_cache: False
[0xC89A:1:0x0006]: initializing channel: from_cache: False
[0xce02] Delivery error for seq # 0xa5, on endpoint id 1 cluster 0x0300: message send failure
[0xCE02:1:0x0300]: failed to get attributes '['color_mode', 'color_temperature', 'current_x', 'current_y', 'color_loop_active']' on 'light_color' cluster: [0xce02:1:0x0300]: Message send failure
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
[0x35cd:1:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=156 command_id=Command.Report_Attributes>
[0x35cd:1:0x0402] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=int16s, value=2001>)]]
[0x35cd:1:0x0402] Attribute report received: measured_value=2001
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
[0x0000:zdo] ZDO request ZDOCmd.Parent_annce: [[00:15:8d:00:02:a6:fe:5a]]
[0x0000:zdo] No handler for ZDO request:ZDOCmd.Parent_annce([[00:15:8d:00:02:a6:fe:5a]])
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network
Device 0xa5d1 (5c:02:72:ff:fe:d5:07:b5) left the network

grom.m20b25 · December 19, 2021, 1:58am

What firmware version do you have on the stick I had a similar problem with some hue bulbs on a silicon labs em3587 based stick firmware update and the bulbs worked fine after that

Salamandar · December 19, 2021, 8:15am

I’ll try updating the firmware. 'M using the official v6.

MikeyMike · January 16, 2022, 5:48pm

@Salamandar, did you manage to solve this problem with IKEA LED2002G5?
I bought 3 of them and non of them are working.
Manage to include them fine, but not able to control the light.

'[zigpy_deconz.api] Request id: 0x2a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x6428 endpoint=1>, status: 0xd0
2022-01-16 17:46:48 DEBUG (MainThread) [zigpy.device] [0x6428] Delivery error for seq # 0x29, on endpoint id 1 cluster 0x0006: message send failure
2022-01-16 17:46:48 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x6428:1:0x0006]: command failed: 'off' args: '()' kwargs '{}' exception: '[0x6428:1:0x0006]: Message send failure'
2022-01-16 17:46:48 DEBUG (MainThread) [homeassistant.components.zha.entity] light.ikea_of_sweden_tradfribulbe14wsglobeopal470lm_554ccffe_level_light_color_on_off: turned off: [0x6428:1:0x0006]: Message send failure'

Salamandar · January 16, 2022, 6:04pm

AH, yes ! Updating the firmware of the gateway did the trick. Zero deco since then.