Unable to pair IKEA Trådfri bulb using ZHA

Configuration
1

I’m having problems getting my Trådfri bulbs to pair using ZHA (or zigbee2mqtt) with a ConbeeII stick. They work fine in deconz or the trådfri app. Other Trådfri devices (the small dimmer button and outlets) work fine.

Anybody have any idea? Here’s the debug output while attempting to pair:

2020-12-10 23:16:59 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<')
2020-12-10 23:16:59 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b1c0009000200213c
2020-12-10 23:16:59 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b1c000800010021
2020-12-10 23:16:59 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS
2020-12-10 23:17:41 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1707](TRADFRI on/off switch): Attempting to checkin with device - missed checkins: 1
2020-12-10 23:17:41 DEBUG (MainThread) [zigpy.device] [0x1707] Extending timeout for 0x09 request
2020-12-10 23:17:41 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 9 under 10 request id, data: b'0009000400'
2020-12-10 23:17:41 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 10, 0, <DeconzAddressEndpoint address_mode=2 address=0x1707 endpoint=1>, 260, 0, 1, b'\x00\t\x00\x04\x00', 2, 0)
2020-12-10 23:17:41 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x121d001b0014000a00020717010401000001050000090004000200
2020-12-10 23:17:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x121d0009000200220a
2020-12-10 23:17:41 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 10]
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e1e000700a600
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x041e0007000000
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x041e0013000c00220a0207170101e100000000
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 10: e1
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1707 endpoint=1>, status: 0xe1
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 10 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
2020-12-10 23:17:43 DEBUG (MainThread) [zigpy.device] [0x1707] Delivery error for seq # 0x09, on endpoint id 1 cluster 0x0000: message send failure
2020-12-10 23:18:43 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x1707](TRADFRI on/off switch): Attempting to checkin with device - missed checkins: 2
2020-12-10 23:18:43 DEBUG (MainThread) [zigpy.device] [0x1707] Extending timeout for 0x0b request
2020-12-10 23:18:43 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 11 under 12 request id, data: b'000b000400'
2020-12-10 23:18:43 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 12, 0, <DeconzAddressEndpoint address_mode=2 address=0x1707 endpoint=1>, 260, 0, 1, b'\x00\x0b\x00\x04\x00', 2, 0)
2020-12-10 23:18:43 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x121f001b0014000c000207170104010000010500000b0004000200
2020-12-10 23:18:43 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x121f0009000200220c
2020-12-10 23:18:43 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 12]
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0e20000700a600
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04200007000000
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04200013000c00220c0207170101e100000000
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 12: e1
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x0c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1707 endpoint=1>, status: 0xe1
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 12 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
2020-12-10 23:18:45 DEBUG (MainThread) [zigpy.device] [0x1707] Delivery error for seq # 0x0b, on endpoint id 1 cluster 0x0000: message send failure
2

Turned out to be interference/reception issues. The stick and the USB extension cable was on top of the computer. Moving it to a new position cleared all of the TXStatus.MAC_CHANNEL_ACCESS_FAILURE issues I had.

2 Likes