core: Cannot pair Eve motion sensor over Thread & Matter on HA Yellow

The problem

I am attempting for the first time to pair an eve motion sensor. It is new, shows matter & thread support on its packing. I can use the iPad HA app to start pairing by scanning the QR code on the device. It ends in a pairing failed error.

The log of the pairing:

2023-07-01 00:21:50 homeassistant universal_silabs_flasher.flasher[182] INFO Detected bootloader version '2.0.1'
2023-07-01 00:21:50 homeassistant universal_silabs_flasher.flasher[182] INFO Launched application from bootloader, continuing probing
2023-07-01 00:21:50 homeassistant universal_silabs_flasher.flasher[182] INFO Probing ApplicationType.CPC at 460800 baud
2023-07-01 00:21:50 homeassistant universal_silabs_flasher.flasher[182] INFO Detected ApplicationType.CPC, version '4.3.0' at 460800 baudrate (bootloader baudrate 115200)
2023-07-01 00:21:50 homeassistant universal_silabs_flasher.flash[182] INFO Firmware version '4.3.0' is flashed, not re-installing
s6-rc: info: service universal-silabs-flasher successfully started
s6-rc: info: service cpcd-config: starting
[00:21:52] INFO: Using known baudrate of 460800 for cpcd!
[00:21:53] INFO: Generating cpcd configuration.
s6-rc: info: service cpcd-config successfully started
s6-rc: info: service cpcd: starting
[00:21:54] INFO: Starting cpcd...
WARNING in function 'main' in file /usr/src/cpc-daemon/main.c at line #186 : Running CPCd as 'root' is not recommended. Proceed at your own risk.
s6-rc: info: service cpcd successfully started
s6-rc: info: service zigbeed: starting
s6-rc: info: service otbr-agent: starting
s6-rc: info: service zigbeed successfully started
[00:21:55] INFO: Starting zigbeed...
[00:21:54:324731] Info : [CPCd v4.3.0.0] [Library API v3] [RCP Protocol v4]
[00:21:54:324979] Info : Git commit: 5264ecaef094c48b94f501b71edff4caaadb4545 / branch: 
[00:21:54:324983] Info : Sources hash: d0b1e4d46195ed78ab5fccd6b1dd93f77d56dafcd09d1613ec1806014d40d496
[00:21:54:324991] WARNING : In function 'main' in file /usr/src/cpc-daemon/main.c at line #186 : Running CPCd as 'root' is not recommended. Proceed at your own risk.
[00:21:54:325043] Info : Reading cli arguments
[00:21:54:325054] Info : /usr/local/bin/cpcd 
[00:21:54:325857] Info : Reading configuration
[00:21:54:325879] Info :   file_path = /usr/local/etc/cpcd.conf
[00:21:54:325882] Info :   instance_name = cpcd_0
[00:21:54:325884] Info :   socket_folder = /dev/shm
[00:21:54:325887] Info :   operation_mode = MODE_NORMAL
[00:21:54:325889] Info :   use_encryption = false
[00:21:54:325891] Info :   binding_key_file = /etc/binding-key.key
[00:21:54:325893] Info :   stdout_tracing = false
[00:21:54:325895] Info :   file_tracing = false
[00:21:54:325896] Info :   lttng_tracing = false
[00:21:54:325898] Info :   enable_frame_trace = false
[00:21:54:325900] Info :   traces_folder = /dev/shm/cpcd-traces
[00:21:54:325902] Info :   bus = UART
[00:21:54:325904] Info :   uart_baudrate = 460800
[00:21:54:325907] Info :   uart_hardflow = true
[00:21:54:325909] Info :   uart_file = /dev/ttyAMA1
[00:21:54:325911] Info :   fu_recovery_pins_enabled = false
[00:21:54:325914] Info :   fu_connect_to_bootloader = false
[00:21:54:325916] Info :   fu_enter_bootloader = false
[00:21:54:325918] Info :   restart_cpcd = false
[00:21:54:325920] Info :   application_version_validation = false
[00:21:54:325921] Info :   print_secondary_versions_and_exit = false
[00:21:54:325923] Info :   use_noop_keep_alive = false
[00:21:54:325925] Info :   reset_sequence = true
[00:21:54:325927] Info :   stats_interval = 0
[00:21:54:325929] Info :   rlimit_nofile = 2000
[00:21:54:325931] Info : ENCRYPTION IS DISABLED 
[00:21:54:325940] Info : Starting daemon in normal mode
[00:21:54:338172] Info : Connecting to Secondary...
[00:21:54:410157] Info : RX capability is 256 bytes
[00:21:54:410188] Info : Connected to Secondary
[00:21:54:411340] Info : Secondary Protocol v4
[00:21:54:414102] Info : Secondary CPC v4.3.0
[00:21:54:415380] Info : Secondary bus bitrate is 460800
[00:21:54:418117] Info : Secondary APP vUNDEFINED
[00:21:54:418370] Info : Daemon startup was successful. Waiting for client connections
[00:21:55] INFO: Setup OTBR firewall...
[00:21:56] INFO: Starting otbr-agent...
otbr-agent[305]: [NOTE]-AGENT---: Running 0.3.0
otbr-agent[305]: [NOTE]-AGENT---: Thread version: 1.3.0
otbr-agent[305]: [NOTE]-AGENT---: Thread interface: wpan0
otbr-agent[305]: [NOTE]-AGENT---: Radio URL: spinel+cpc://cpcd_0?iid=2
otbr-agent[305]: [NOTE]-ILS-----: Infra link selected: eth0
otbr-agent[305]: 49d.17:03:48.111 [C] Platform------: mCpcBusSpeed = 115200
[00:21:56:147909] Info : New client connection using library v4.3.0.0
[00:21:56:163440] Info : Opened connection socket for ep#12
[00:21:56:163654] Info : Endpoint socket #12: Client connected. 1 connections
[00:21:56:241375] Info : New client connection using library v4.3.0.0
[00:21:56:243614] Info : Endpoint socket #12: Client connected. 2 connections
otbr-agent[305]: 00:00:00.037 [N] BorderRouter--: BR ULA prefix: fd93:15c6:e4a9::/48 (loaded)
otbr-agent[305]: 00:00:00.037 [N] BorderRouter--: Local on-link prefix: fd1f:b1e4:16a5:b529::/64
s6-rc: info: service otbr-agent successfully started
s6-rc: info: service otbr-agent-rest-discovery: starting
otbr-agent[305]: 00:00:00.079 [N] Mle-----------: Role disabled -> detached
otbr-agent[305]: 00:00:00.089 [N] Platform------: [netif] Changing interface state to up.
[00:21:59] INFO: Successfully sent discovery information to Home Assistant.
s6-rc: info: service otbr-agent-rest-discovery successfully started
s6-rc: info: service legacy-services: starting
s6-rc: info: service legacy-services successfully started
Listening on port 9999 for connection...

Accepting connection.

otbr-agent[305]: 00:00:26.491 [N] Mle-----------: RLOC16 1400 -> fffe
otbr-agent[305]: 00:00:26.494 [W] Platform------: [netif] Failed to process request#5: Unknown error -95
otbr-agent[305]: 00:00:27.075 [N] Mle-----------: Attach attempt 1, AnyPartition reattaching with Active Dataset
otbr-agent[305]: 00:00:33.577 [N] RouterTable---: Allocate router id 5
otbr-agent[305]: 00:00:33.578 [N] Mle-----------: RLOC16 fffe -> 1400
otbr-agent[305]: 00:00:33.580 [N] Mle-----------: Role detached -> leader
otbr-agent[305]: 00:00:33.582 [N] Mle-----------: Partition ID 0x479cb3e3
otbr-agent[305]: 00:00:33.644 [W] Platform------: [netif] Failed to process request#6: Unknown error -17
otbr-agent[305]: [NOTE]-BBA-----: BackboneAgent: Backbone Router becomes Primary!
Accepted connection 7.

Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface fe80::30d3:93ff:fe63:9654/veth5ccb40c/22
Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface fe80::30d3:93ff:fe63:9654/veth5ccb40c/22
Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface fe80::30d3:93ff:fe63:9654/veth5ccb40c/22
Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface fe80::30d3:93ff:fe63:9654/veth5ccb40c/22
Default: mDNSPlatformSendUDP got error 99 (Cannot assign requested address) sending packet to ff02::fb on interface fe80::30d3:93ff:fe63:9654/veth5ccb40c/22

So there are many error messages which seem not to be resolved

What version of Home Assistant Core has the issue?

core-2023.6.3

What was the last working version of Home Assistant Core?

No response

What type of installation are you running?

Home Assistant OS

Integration causing the issue

Thread, OTBR, ZigBee, ZHA (Silicon Labs Multiprotocol)

Link to integration documentation on our website

https://www.home-assistant.io/integrations/zha/#known-working-zigbee-radio-modules

Diagnostics information

Debug logging, this sequence repeats endlessly …

2023-07-01 00:26:41.401 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-07-01 00:26:41.402 DEBUG (MainThread) [bellows.uart] Sending: b'46c121a9a52a755c7e'
2023-07-01 00:26:41.408 DEBUG (MainThread) [bellows.uart] Data frame: b'65c1a1a9a52a15b249944a25aa5592499c4e26abecce638bffc66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482496dc7e'
2023-07-01 00:26:41.409 DEBUG (MainThread) [bellows.uart] Sending: b'87009f7e'
2023-07-01 00:26:41.410 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[0, 16, 0, 0, 0, 0, 1, 1, 4, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-07-01 00:26:41.411 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-07-01 00:26:41.412 DEBUG (MainThread) [bellows.uart] Sending: b'57c621a9fe2a16e9a17e'
2023-07-01 00:26:41.414 DEBUG (MainThread) [bellows.uart] Data frame: b'76c6a1a9fe2a15b3a26f617e'
2023-07-01 00:26:41.414 DEBUG (MainThread) [bellows.uart] Sending: b'8070787e'
2023-07-01 00:26:41.415 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xfb']
2023-07-01 00:26:41.416 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 251
2023-07-01 00:26:41.416 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 0, MAC_TX_BROADCAST = 16, MAC_RX_UNICAST = 0, MAC_TX_UNICAST_SUCCESS = 0, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 1, APS_DATA_TX_BROADCAST = 1, APS_DATA_RX_UNICAST = 4, APS_DATA_TX_UNICAST_SUCCESS = 2, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 0, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 0, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 0, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 251]
2023-07-01 00:26:51.419 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-07-01 00:26:51.421 DEBUG (MainThread) [bellows.uart] Sending: b'60c721a9a52a00307e'
2023-07-01 00:26:51.429 DEBUG (MainThread) [bellows.uart] Data frame: b'07c7a1a9a52a15b249944a25aa5592499c4e26abecce638bffc66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482436147e'
2023-07-01 00:26:51.429 DEBUG (MainThread) [bellows.uart] Sending: b'8160597e'
2023-07-01 00:26:51.430 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[0, 16, 0, 0, 0, 0, 1, 1, 4, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-07-01 00:26:51.431 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-07-01 00:26:51.431 DEBUG (MainThread) [bellows.uart] Sending: b'71c421a9fe2a16ad327e'
2023-07-01 00:26:51.433 DEBUG (MainThread) [bellows.uart] Data frame: b'10c4a1a9fe2a15b3a2426a7e'
2023-07-01 00:26:51.433 DEBUG (MainThread) [bellows.uart] Sending: b'82503a7e'
2023-07-01 00:26:51.434 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xfb']
2023-07-01 00:26:51.434 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 251
2023-07-01 00:26:51.434 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 0, MAC_TX_BROADCAST = 16, MAC_RX_UNICAST = 0, MAC_TX_UNICAST_SUCCESS = 0, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 1, APS_DATA_TX_BROADCAST = 1, APS_DATA_RX_UNICAST = 4, APS_DATA_TX_UNICAST_SUCCESS = 2, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 0, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 0, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 0, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 251]
2023-07-01 00:27:01.436 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-07-01 00:27:01.438 DEBUG (MainThread) [bellows.uart] Sending: b'02c521a9a52a90eb7e'
2023-07-01 00:27:01.457 DEBUG (MainThread) [bellows.uart] Data frame: b'21c5a1a9a52a15b248944a25aa5592499c4e26abecce638bffc66389fc7e3fa7ebcdde6f8fffc7dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a251904824678d7e'
2023-07-01 00:27:01.458 DEBUG (MainThread) [bellows.uart] Sending: b'83401b7e'
2023-07-01 00:27:01.459 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[0, 17, 0, 0, 0, 0, 1, 1, 4, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]]
2023-07-01 00:27:01.459 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,)
2023-07-01 00:27:01.460 DEBUG (MainThread) [bellows.uart] Sending: b'7d33ca21a9fe2a16fe687e'
2023-07-01 00:27:01.463 DEBUG (MainThread) [bellows.uart] Data frame: b'32caa1a9fe2a15b3a2bde07e'
2023-07-01 00:27:01.464 DEBUG (MainThread) [bellows.uart] Sending: b'8430fc7e'
2023-07-01 00:27:01.465 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xfb']
2023-07-01 00:27:01.465 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 251
2023-07-01 00:27:01.465 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 0, MAC_TX_BROADCAST = 17, MAC_RX_UNICAST = 0, MAC_TX_UNICAST_SUCCESS = 0, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 0, APS_DATA_RX_BROADCAST = 1, APS_DATA_TX_BROADCAST = 1, APS_DATA_RX_UNICAST = 4, APS_DATA_TX_UNICAST_SUCCESS = 2, APS_DATA_TX_UNICAST_RETRY = 0, APS_DATA_TX_UNICAST_FAILED = 0, ROUTE_DISCOVERY_INITIATED = 0, NEIGHBOR_ADDED = 0, NEIGHBOR_REMOVED = 0, NEIGHBOR_STALE = 0, JOIN_INDICATION = 0, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 0, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 251]
2023-07-01 00:27:11.467 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: ()
2023-07-01 00:27:11.468 DEBUG (MainThread) [bellows.uart] Sending: b'24cb21a9a52ae7aa7e'

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

Additional information

Hardware is HA Yellow Rev 1.3

About this issue

  • Original URL
  • State: closed
  • Created a year ago
  • Comments: 20 (7 by maintainers)

Most upvoted comments

@gsgxnet just a bit of patience is required, we’re working hard to get that all working and have stumbled on a few roadblocks. It is our priority to get this working and provide good documentation to set it up.

Great! So I misunderstood your message - “works as intended and closing issue” - I thought is the typical wording and action for “forget about it”. Not so in this case.
Many Thanks for your works.