zha-device-handlers: [BUG] TS011F _TZ3000_cfnprab5 (XENON 4AC+2USB power strip) does not pair
Describe the bug Heya,
I’ve been trying to pair this power strip to transfer from localtuya to zigbee. The quirk is available locally, but I just can’t get zha to finish the interview. While pairing it sits on my desk, surrounded by at least 5 zigbee devices within 2m.
What’s wrong? I simply don’t see it. It seems to leave the network before the interview finishes, so it’s just caught in a loop. I’ve included the pairing log from zha below.
Thanks for your help! Using this project has really been amazing so far. Kudos to all of you for such a great work!
Additional logs (pairing)
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x315d (70:b3:d5:2b:60:01:76:4c) left the network
[0x5610](MS01): Device seen - marking the device available and resetting counter
[0x5610](MS01): Update device availability - device available: True - new availability: True - changed: False
[0x33D5](LWO001): Device seen - marking the device available and resetting counter
[0x33D5](LWO001): Update device availability - device available: True - new availability: True - changed: False
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) joined the network
Device 70:b3:d5:2b:60:01:76:4c changed id (0x315d => 0x4ff4)
[0x4ff4] Scheduling initialization
Tries remaining: 3
[0x4ff4] Requesting 'Node Descriptor'
Tries remaining: 2
[0x4ff4] Extending timeout for 0xba request
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x4FF4), 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=185, profile_id=0, cluster_id=19, data=Serialized[b'\x1d\xf4OLv\x01`+\xd5\xb3p\x8e'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=180, rssi=-55)
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) joined the network
[0x4ff4] Scheduling initialization
[0x4ff4] Canceling old initialize call
Received frame on uninitialized device <Device model=None manuf=None nwk=0x4FF4 ieee=70:b3:d5:2b:60:01:76:4c is_initialized=False> from ep 0 to ep 0, cluster 19: b'\x1d\xf4OLv\x01`+\xd5\xb3p\x8e'
[0x4ff4:zdo] ZDO request ZDOCmd.Device_annce: [0x4FF4, 70:b3:d5:2b:60:01:76:4c, 142]
Tries remaining: 3
[0x4ff4] Requesting 'Node Descriptor'
Tries remaining: 2
[0x4ff4] Extending timeout for 0xbc request
[0x9C33](TS011F): Device seen - marking the device available and resetting counter
[0x9C33](TS011F): Update device availability - device available: True - new availability: True - changed: False
[0xDA16](TS0505B): Device seen - marking the device available and resetting counter
[0xDA16](TS0505B): Update device availability - device available: True - new availability: True - changed: False
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x33D5), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=104, profile_id=260, cluster_id=6, data=Serialized[b'\x18\xe9\n\x00\x00\x10\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=192, rssi=-52)
[0x33D5:11:0x0006] Received ZCL frame: b'\x18\xe9\n\x00\x00\x10\x00'
[0x33D5:11:0x0006] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=233, command_id=10, *direction=<Direction.Client_to_Server: 1>)
[0x33D5:11:0x0006] Decoded ZCL frame: OnOff:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
[0x33D5:11:0x0006] Received command 0x0A (TSN 233): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=Bool, value=<Bool.false: 0>))])
[0x33D5:11:0x0006] Attribute report received: on_off=<Bool.false: 0>
Tries remaining: 1
[0x4ff4] Extending timeout for 0xbe request
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x33D5), src_ep=11, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=105, profile_id=260, cluster_id=8, data=Serialized[b'\x18\xea\n\x00\x00 +'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=192, rssi=-52)
[0x33D5:11:0x0008] Received ZCL frame: b'\x18\xea\n\x00\x00 +'
[0x33D5:11:0x0008] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=234, command_id=10, *direction=<Direction.Client_to_Server: 1>)
[0x33D5:11:0x0008] Decoded ZCL frame: LevelControl:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=43))])
[0x33D5:11:0x0008] Received command 0x0A (TSN 234): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint8_t, value=43))])
[0x33D5:11:0x0008] Attribute report received: current_level=43
[0x33D5:11:0x0008]: received attribute: 0 update with value: 43
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x5BE2), 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=218, profile_id=0, cluster_id=0, data=Serialized[b'\x87hM\xe1%\x00K\x12\x00\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=196, rssi=-51)
[0x5be2:zdo] ZDO request ZDOCmd.NWK_addr_req: [00:12:4b:00:25:e1:4d:68, <AddrRequestType.Single: 0>, 0]
Tries remaining: 2
[0x4ff4] Requesting 'Node Descriptor'
Tries remaining: 2
[0x4ff4] Extending timeout for 0xc1 request
Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x5BE2), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=219, profile_id=260, cluster_id=1029, data=Serialized[b'\x08Q\n\x00\x00!\xec\x13'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=196, rssi=-51)
[0x5BE2:1:0x0405] Received ZCL frame: b'\x08Q\n\x00\x00!\xec\x13'
[0x5BE2:1:0x0405] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=81, command_id=10, *direction=<Direction.Client_to_Server: 1>)
[0x5BE2:1:0x0405] Decoded ZCL frame: RelativeHumidity:Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=5100))])
[0x5BE2:1:0x0405] Received command 0x0A (TSN 81): Report_Attributes(attribute_reports=[Attribute(attrid=0x0000, value=TypeValue(type=uint16_t, value=5100))])
[0x5BE2:1:0x0405] Attribute report received: measured_value=5100
[0x5BE2:1:0x0405] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=81, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>)
[0x5BE2:1:0x0405] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>)
[0x25A7](440400982842): Device seen - marking the device available and resetting counter
[0x25A7](440400982842): Update device availability - device available: True - new availability: True - changed: False
[0x6FA4](TRADFRIbulbE27WSglobeclear806lm): Device seen - marking the device available and resetting counter
[0x6FA4](TRADFRIbulbE27WSglobeclear806lm): Update device availability - device available: True - new availability: True - changed: False
[0x81CF](GL-C-008): Device seen - marking the device available and resetting counter
[0x81CF](GL-C-008): Update device availability - device available: True - new availability: True - changed: False
Tries remaining: 1
[0x4ff4] Extending timeout for 0xc4 request
Tries remaining: 1
[0x4ff4] Requesting 'Node Descriptor'
Tries remaining: 2
[0x4ff4] Extending timeout for 0xc6 request
[0x86B0](TS011F): Device seen - marking the device available and resetting counter
[0x86B0](TS011F): Update device availability - device available: True - new availability: True - changed: False
[0x51CE](LCA001): Device seen - marking the device available and resetting counter
[0x51CE](LCA001): Update device availability - device available: True - new availability: True - changed: False
[0x64FC](TRADFRI bulb E27 CWS 806lm): Device seen - marking the device available and resetting counter
[0x64FC](TRADFRI bulb E27 CWS 806lm): Update device availability - device available: True - new availability: True - changed: False
Tries remaining: 1
[0x4ff4] Extending timeout for 0xc8 request
[0x211C](TRADFRI control outlet): Device seen - marking the device available and resetting counter
[0x211C](TRADFRI control outlet): Update device availability - device available: True - new availability: True - changed: False
[0x9CFA](TRADFRI on/off switch): Device seen - marking the device available and resetting counter
[0x9CFA](TRADFRI on/off switch): Update device availability - device available: True - new availability: True - changed: False
[0x4D0C](TRADFRIbulbE27WSglobeclear806lm): Device seen - marking the device available and resetting counter
[0x4D0C](TRADFRIbulbE27WSglobeclear806lm): Update device availability - device available: True - new availability: True - changed: False
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
[0x2FD5](TRADFRI on/off switch): Device seen - marking the device available and resetting counter
[0x2FD5](TRADFRI on/off switch): Update device availability - device available: True - new availability: True - changed: False
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
Device 0x4ff4 (70:b3:d5:2b:60:01:76:4c) left the network
About this issue
- Original URL
- State: closed
- Created a year ago
- Comments: 23 (11 by maintainers)
Update: Updated my Skyconnect firmware. No pairing and connectivity issues anymore. See more here: https://github.com/home-assistant/core/issues/98624#issuecomment-1688222346
@MattWestb I can provide a PCAP file when paired to SmartThings hopefully tomorrow, but in my opinion, the problem is on a lower, Zigbee 3.0 transport/connectivity level that I don’t have much knowledge of. The problem is different than the Tuya magic initialization, because when I first paired these devices that use Telink chips, they added to ST as a ‘thing’, i.e. no system or custom driver has sent any special initialization.
There are a lot of issues with the new ~
TI CC2652P Zigbee chip~ /edit:/SiliconLabs EFR32 chipset in Hubitat, fixing the connectivity issues with one type of devices brokes the connectivity with other Zigbee devices.@MattWestb Thanks for the links, I’ll check when I have the time. Maybe later today.
@MattWestb ok, I have posted them on the above linked issue.