Hi there! I am using ZHA and a Sonoff ZB Bridge. After installing and pairing all the Ikea remotes, switches and light bulbs and a Aqara cube, all seemed to work pretty decent.
Now some of my switches, remotes and the cube stopped sending events when pressed / used. The interesting fact is that not all stopped sending. One switch is sending info via zha_event. And I also checked it by activating the log in Integrations -> Zigbee Home Automation -> Configure -> + button.
This is what I get for different situations. Any idea how to solve this in order for all devices to be seen in zha_event?
1. Pressing the on button on a an Ikea Switch that is paired and working
[0x8193:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=50 command_id=1>
[0x8193:1:0x0006] ZCL request 0x0001: []
[0x8193:1:0x0006] No handler for cluster command 1
[0x7842: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=58 command_id=Command.Default_Response>
[0x7842:1:0x0006]: executed 'on' command with args: '()' kwargs: '{}' result: [1, <Status.SUCCESS: 0>]
light.zha_lab_white_bulb_01: turned on: {'on_off': [1, <Status.SUCCESS: 0>]}
2. And this is what happens in the logs if I press an Ikea switch that does not respond via zha_event
[0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [60, <Bool.false: 0>]
Ignoring message (b'013b00') on cluster 6: unknown endpoint or cluster id: 1
Ignoring message (b'013c01') on cluster 6: unknown endpoint or cluster id: 1
Ignoring message (b'013e00') on cluster 6: unknown endpoint or cluster id: 1
Ignoring message (b'013f01') on cluster 6: unknown endpoint or cluster id: 1
3. This is what happens if I try to re-pair a switch that is not working and I manage to re-pair it in the end
[0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [60, <Bool.false: 0>]
Device 0x9280 (00:0d:6f:ff:fe:d0:9d:89) joined the network
Device 00:0d:6f:ff:fe:d0:9d:89 changed id (0xcede => 0x9280)
device - 0x9280:00:0d:6f:ff:fe:d0:9d:89 entering async_device_initialized - is_new_join: False
device - 0x9280:00:0d:6f:ff:fe:d0:9d:89 has been reset and re-added or its nwk address changed
skipping discovery for previously discovered device - 0x9280:00:0d:6f:ff:fe:d0:9d:89
[0x9280](TRADFRI on/off switch): started configuration
[0x9280:ZDO](TRADFRI on/off switch): 'async_configure' stage succeeded
[0x9280] Extending timeout for 0x4e request
[0x9280] Extending timeout for 0x50 request
[0x9280] Extending timeout for 0x52 request
Device 0x9280 (00:0d:6f:ff:fe:d0:9d:89) joined the network
Skip initialization for existing device 00:0d:6f:ff:fe:d0:9d:89
Device 0x9280 (00:0d:6f:ff:fe:d0:9d:89) joined the network
Skip initialization for existing device 00:0d:6f:ff:fe:d0:9d:89
[0x9280:zdo] ZDO request ZDOCmd.Device_annce: [0x9280, 00:0d:6f:ff:fe:d0:9d:89, 128]
[0x9280](TRADFRI on/off switch): started initialization
[0x9280:ZDO](TRADFRI on/off switch): 'async_initialize' stage succeeded
[0x9280:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[0x9280:zdo] Unsupported ZDO request:ZDOCmd.Node_Desc_req
[0x9280:1:0x0001]: bound 'power' cluster: Status.SUCCESS
[0x9280] Extending timeout for 0x54 request
[0x9280:1:0x0000]: bound 'basic' cluster: Status.SUCCESS
[0x9280:1:0x0000]: finished channel configuration
[0x9280] Extending timeout for 0x56 request
[0x9280:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [180, <Bool.true: 1>]
[0x9280:1:0x0003] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=0 command_id=1>
[0x9280:1:0x0003] ZCL request 0x0001: []
[0x9280:1:0x0003] No handler for cluster command 1
[0x9280:1:0x0003]: received 'identify_query' command with [] args on cluster_id '3' tsn '0'
[0x9280:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=84 command_id=Command.Configure_Reporting_rsp>
[0x9280:1:0x0001]: reporting 'battery_voltage' attr on 'power' cluster: 3600/10800/1: Result: '[[ConfigureReportingResponseRecord(status=0)]]'
[0x9280] Extending timeout for 0x58 request
[0x9280: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=82 command_id=65>
[0x9280:1:0x1000]: bound 'lightlink' cluster: None
[0x9280:1:0x1000]: finished channel configuration
[0x9280] Extending timeout for 0x5a request
[0x9280: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=86 command_id=Command.Read_Attributes_rsp>
[0x9280:1:0x0000]: initializing channel: from_cache: False
[0x9280] Extending timeout for 0x5c request
[0x9280:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=88 command_id=Command.Configure_Reporting_rsp>
[0x9280:1:0x0001]: reporting 'battery_percentage_remaining' attr on 'power' cluster: 3600/10800/1: Result: '[[ConfigureReportingResponseRecord(status=0)]]'
[0x9280:1:0x0001]: finished channel configuration
[0x9280] Extending timeout for 0x5e request
[0x9280:1:0x0020] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=90 command_id=Command.Write_Attributes_rsp>
[0x9280:1:0x0020]: 3300.0s check-in interval set: [[WriteAttributesStatusRecord(status=<Status.SUCCESS: 0>)]]
[0x9280] Extending timeout for 0x60 request
[0x9280:1:0x0102]: bound 'window_covering' cluster: Status.SUCCESS
[0x9280:1:0x0102]: finished channel configuration
[0x9280] Extending timeout for 0x62 request
[0x9280:1:0x0008]: bound 'level' cluster: Status.SUCCESS
[0x9280:1:0x0008]: finished channel configuration
[0x9280] Extending timeout for 0x64 request
[0x9280:1:0x0019]: bound 'ota' cluster: Status.SUCCESS
[0x9280:1:0x0019]: finished channel configuration
[0x9280] Extending timeout for 0x66 request
[0x9280:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=102 command_id=Command.Read_Attributes_rsp>
[0x9280:1:0x0001]: initializing channel: from_cache: False
[0x9280] Extending timeout for 0x68 request
[0x9280:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=104 command_id=Command.Read_Attributes_rsp>
[0x9280] Extending timeout for 0x6a request
[0x9280: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=106 command_id=Command.Read_Attributes_rsp>
[0x9280:1:0x0000]: initializing channel: from_cache: False
[0x9280:1:0x1000]: initializing channel: from_cache: False
[0x9280:1:0x0020]: initializing channel: from_cache: False
[0x9280:1:0x0102]: initializing channel: from_cache: False
[0x9280:1:0x0008]: initializing channel: from_cache: False
[0x9280:1:0x0006]: initializing channel: from_cache: False
[0x9280:1:0x0019]: initializing channel: from_cache: False
[0x9280:1:0x0001]: 'async_initialize' stage succeeded
[0x9280:1:0x0000]: 'async_initialize' stage succeeded
[0x9280:1:0x1000]: 'async_initialize' stage succeeded
[0x9280:1:0x0020]: 'async_initialize' stage succeeded
[0x9280:1:0x0102]: 'async_initialize' stage succeeded
[0x9280:1:0x0008]: 'async_initialize' stage succeeded
[0x9280:1:0x0006]: 'async_initialize' stage succeeded
[0x9280:1:0x0019]: 'async_initialize' stage succeeded
[0x9280](TRADFRI on/off switch): power source: Battery or Unknown
[0x9280](TRADFRI on/off switch): completed initialization
[0x9280] Invalid state on future for 0x60 seq -- probably duplicate response
[0x9280:1:0x0020]: bound 'poll_control' cluster: Status.SUCCESS
[0x9280:1:0x0020]: finished channel configuration
[0x9280] Invalid state on future for 0x62 seq -- probably duplicate response
[0x9280:1:0x0006]: bound 'on_off' cluster: Status.SUCCESS
[0x9280:1:0x0006]: finished channel configuration
[0x9280:1:0x0001]: 'async_configure' stage succeeded
[0x9280:1:0x0000]: 'async_configure' stage succeeded
[0x9280:1:0x1000]: 'async_configure' stage succeeded
[0x9280:1:0x0020]: 'async_configure' stage succeeded
[0x9280:1:0x0102]: 'async_configure' stage succeeded
[0x9280:1:0x0008]: 'async_configure' stage succeeded
[0x9280:1:0x0006]: 'async_configure' stage succeeded
[0x9280:1:0x0019]: 'async_configure' stage succeeded
[0x9280](TRADFRI on/off switch): completed configuration
[0x9280](TRADFRI on/off switch): stored in registry: ZhaDeviceEntry(name='unk_manufacturer unk_model', ieee='00:0d:6f:ff:fe:d0:9d:89', last_seen=1601364324.2411315)
[0x9280] Extending timeout for 0x6c request
[0x9280: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=108 command_id=Command.Default_Response>
[0x9280:1:0x0003]: executed 'trigger_effect' command with args: '(2, 0)' kwargs: '{}' result: [64, <Status.SUCCESS: 0>]
[0x9280](TRADFRI on/off switch): started initialization
[0x9280:ZDO](TRADFRI on/off switch): 'async_initialize' stage succeeded
[0x9280] Extending timeout for 0x6e request
[0x9280] Extending timeout for 0x70 request
[0x9280:1:0x1000]: initializing channel: from_cache: False
[0x9280:1:0x0020]: initializing channel: from_cache: False
[0x9280:1:0x0102]: initializing channel: from_cache: False
[0x9280:1:0x0008]: initializing channel: from_cache: False
[0x9280:1:0x0006]: initializing channel: from_cache: False
[0x9280:1:0x0019]: initializing channel: from_cache: False
[0x9280:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=110 command_id=Command.Read_Attributes_rsp>
[0x9280:1:0x0001]: initializing channel: from_cache: False
[0x9280] Extending timeout for 0x72 request
[0x9280: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=112 command_id=Command.Read_Attributes_rsp>
[0x9280:1:0x0000]: initializing channel: from_cache: False
[0x9280:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=114 command_id=Command.Read_Attributes_rsp>
[0x9280:1:0x0001]: 'async_initialize' stage succeeded
[0x9280:1:0x0000]: 'async_initialize' stage succeeded
[0x9280:1:0x1000]: 'async_initialize' stage succeeded
[0x9280:1:0x0020]: 'async_initialize' stage succeeded
[0x9280:1:0x0102]: 'async_initialize' stage succeeded
[0x9280:1:0x0008]: 'async_initialize' stage succeeded
[0x9280:1:0x0006]: 'async_initialize' stage succeeded
[0x9280:1:0x0019]: 'async_initialize' stage succeeded
[0x9280](TRADFRI on/off switch): power source: Battery or Unknown
[0x9280](TRADFRI on/off switch): completed initialization
light.zha_lab_color_bulb_01: polling current state - from cache: False
[0x8f1b: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=116 command_id=Command.Read_Attributes_rsp>
[0x8f1b: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=118 command_id=Command.Read_Attributes_rsp>
[0x8F1B:1:0x0008]: received attribute: 0 update with value: 170
[0x8f1b: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=120 command_id=Command.Read_Attributes_rsp>
4. This is what happens if I try to re-pair an Ikea switch and I could not manage to re-pair it. And it is still not sending to zha_event after this I censored the MAC_ADDRESS.
[0x0000:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [60, <Bool.false: 0>]
Device 0x0de9 ([MAC_ADDRESS]) left the network
[0x0DE9](unk_model): started initialization
[0x0DE9:ZDO](unk_model): 'async_initialize' stage succeeded
[0x0DE9](unk_model): power source: Battery or Unknown
[0x0DE9](unk_model): completed initialization
Device 0x0de9 ([MAC_ADDRESS]) joined the network
Skip initialization for existing device [MAC_ADDRESS]
Device 0x0de9 ([MAC_ADDRESS]) joined the network
Skip initialization for existing device ([MAC_ADDRESS])
Device 0x0de9 ([MAC_ADDRESS]) joined the network
Skip initialization for existing device ([MAC_ADDRESS])
[0x0de9:zdo] ZDO request ZDOCmd.Device_annce: [0x0DE9, [MAC_ADDRESS], 128]
[0x0de9:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[0x0de9:zdo] Unsupported ZDO request:ZDOCmd.Node_Desc_req
[0x0de9:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [180, <Bool.true: 1>]
Ignoring message (b'010001') on cluster 3: unknown endpoint or cluster id: 1
[0x0de9:zdo] ZDO request ZDOCmd.Match_Desc_req: [0xFFFD, 260, [25], []]
Ignoring message (b'010101017c11c511310601220100') on cluster 25: unknown endpoint or cluster id: 1
Ignoring message (b'010201017c11c511310601220100') on cluster 25: unknown endpoint or cluster id: 1
Ignoring message (b'010301017c11c511310601220100') on cluster 25: unknown endpoint or cluster id: 1
Ignoring message (b'010401017c11c511310601220100') on cluster 25: unknown endpoint or cluster id: 1
Ignoring message (b'010501017c11c511310601220100') on cluster 25: unknown endpoint or cluster id: 1