TL;DR
Trying to pair my Spirit Zigbee in ZHA, it simply does not show up. What can I do to troubleshoot?
Environment
I am running Home Assistantv 246 (stable), installed via 32bit image on a Raspberry Pi 4B with 4GB Ram with a Dresden Elektronik ConBee II stick attached via USB directly to the device. I have paired a few devices via the Zigbee ZHA integration, like SmartThings outletv4, a SmartThings multipurpose sensor and a TUYATEC-riuj5xzs RH3052. All pairings went instantly and were very smooth. I was so happy that it all went flawlessly that I tried my luck with the Eurotronic Spirit Zigbee thermostat/TRV.
Problem statement
Unfortunately, when I initiate the pairing process, nothing happens. While the device shows “Jin”, marking that it is trying to join, ZHA does not list any devices:
My question
Is there anyone out there with a similar setup, who saw this and knows what to do or how to solve it? Or is there any guidance how to troubleshoot this?
Support state of the device
Other information on the web indicate that this should work without a problem.
Possibly interesting logs
At the same time there are logs coming up on HA but I am not sure how those are related or what they tell me, since the Zigbee protocol is still rather new to me. But maybe they are relevant.
2020-10-07 00:48:22 DEBUG (MainThread) [zigpy_deconz.api] Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'<')
2020-10-07 00:48:22 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0be20009000200213c
2020-10-07 00:48:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0be2000800010021
2020-10-07 00:48:22 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS
2020-10-07 00:48:51 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1413:1:0x0b04]: async_update
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 76 under 77 request id, data: b'004c000b05'
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 77, 0, <DeconzAddressEndpoint address_mode=2 address=0x1413 endpoint=1>, 260, 2820, 1, b'\x00L\x00\x0b\x05', 2, 0)
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e3001b0014004d00021314010401040b010500004c000b050200
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e30009000200224d
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 77]
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee4000700a600
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e40007000000
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e40013000c00224d02131401010000000000
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 77: 00
2020-10-07 00:48:51 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x4d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1413 endpoint=1>, status: 0x00
2020-10-07 00:49:21 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1413:1:0x0b04]: async_update
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 78 under 79 request id, data: b'004e000b05'
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 79, 0, <DeconzAddressEndpoint address_mode=2 address=0x1413 endpoint=1>, 260, 2820, 1, b'\x00N\x00\x0b\x05', 2, 0)
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e5001b0014004f00021314010401040b010500004e000b050200
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e50009000200224f
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 79]
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee6000700a600
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e60007000000
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e60013000c00224f02131401010000000000
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 79: 00
2020-10-07 00:49:21 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x4f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1413 endpoint=1>, status: 0x00
2020-10-07 00:49:51 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1413:1:0x0b04]: async_update
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 80 under 81 request id, data: b'0050000b05'
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 81, 0, <DeconzAddressEndpoint address_mode=2 address=0x1413 endpoint=1>, 260, 2820, 1, b'\x00P\x00\x0b\x05', 2, 0)
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e7001b0014005100021314010401040b0105000050000b050200
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e700090002002251
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 81]
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ee8000700a600
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04e80007000000
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04e80013000c00225102131401010000000000
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 81: 00
2020-10-07 00:49:51 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x51 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1413 endpoint=1>, status: 0x00
2020-10-07 00:50:21 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x1413:1:0x0b04]: async_update
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 82 under 83 request id, data: b'0052000b05'
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 83, 0, <DeconzAddressEndpoint address_mode=2 address=0x1413 endpoint=1>, 260, 2820, 1, b'\x00R\x00\x0b\x05', 2, 0)
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12e9001b0014005300021314010401040b0105000052000b050200
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12e900090002002253
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 83]
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0eea000700a600
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04ea0007000000
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04ea0013000c00225302131401010000000000
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 83: 00
2020-10-07 00:50:21 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x53 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x1413 endpoint=1>, status: 0x00