silabs_multiprotocol: Can't commission Thread device

[tathamoddie]

Describe the issue you are experiencing

I am unable to commission a thread device (Nanoleaf bulb) with the HA SkyConnect.

Android runs through:

1. Connecting to device... (with generic animation)
2. Connecting to device... (with device image)
3. Generating Matter credentials...
4. Checking network connectivity...
5. Cannot connect to thread network home-assistant

Calls from the add-on to otbr-agent appear to be failing when attempting to provision the required IPv6 addresses.

What type of installation are you running?

Home Assistant OS

Which operating system are you running on?

Home Assistant Operating System

Which add-on are you reporting an issue with?

Silicon Labs Multiprotocol

What is the version of the add-on?

2.4.0

Steps to reproduce the issue

1. Deploy SkyConnect with multi-protocol add-on
2. Attempt to commission device using HA Android companion app

System Health information

System Information

version	core-2024.1.0
installation_type	Home Assistant OS
dev	false
hassio	true
docker	true
user	root
virtualenv	false
python_version	3.11.6
os_name	Linux
os_version	6.1.63-haos
arch	x86_64
timezone	Australia/Sydney
config_dir	/config

Home Assistant Community Store

GitHub API	ok
GitHub Content	ok
GitHub Web	ok
GitHub API Calls Remaining	4929
Installed Version	1.33.0
Stage	running
Available Repositories	1366
Downloaded Repositories	10

Home Assistant Cloud

logged_in	true
subscription_expiration	7 September 2024 at 10:00
relayer_connected	true
relayer_region	ap-southeast-1
remote_enabled	true
remote_connected	true
alexa_enabled	true
google_enabled	true
remote_server	ap-southeast-1-0.ui.nabu.casa
certificate_status	ready
instance_id
can_reach_cert_server	ok
can_reach_cloud_auth	ok
can_reach_cloud	ok

Home Assistant Supervisor

host_os	Home Assistant OS 11.2
update_channel	stable
supervisor_version	supervisor-2023.12.0
agent_version	1.6.0
docker_version	24.0.7
disk_total	30.8 GB
disk_used	13.2 GB
healthy	true
supported	true
board	ova
supervisor_api	ok
version_api	ok
installed_addons	Terminal & SSH (9.8.1), Silicon Labs Multiprotocol (2.4.0), OneDrive Backup (2.2.4), Samba share (12.2.0), File editor (5.7.0), UniFi Network Application (3.0.1), Matter Server (5.0.2), Studio Code Server (5.14.2), Mosquitto broker (6.4.0)

Dashboards

dashboards	1
resources	4
views	6
mode	storage

Recorder

oldest_recorder_run	26 December 2023 at 00:49
current_recorder_run	5 January 2024 at 21:59
estimated_db_size	329.05 MiB
database_engine	sqlite
database_version	3.41.2

Anything in the Supervisor logs that might be useful for us?

No response

Anything in the add-on logs that might be useful for us?

otbr-agent[306]: 00:00:00.155 [N] RoutingManager: BR ULA prefix: fda6:ab0:841d::/48 (loaded)
otbr-agent[306]: 00:00:00.155 [N] RoutingManager: Local on-link prefix: fd30:cecb:62f8:d443::/64
otbr-agent[306]: 00:00:00.193 [N] Mle-----------: Role disabled -> detached
otbr-agent[306]: 00:00:00.205 [N] Platform------: [netif] Changing interface state to up.
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] ADD [U] fe80:0:0:0:9cba:7f81:df3d:2702 failed (InvalidArgs)
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] ADD [U] fd33:2b1b:f729:9003:c0a7:7ed1:df2b:d39c failed (InvalidArgs)
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] ADD [U] fd33:2b1b:f729:9003:0:ff:fe00:800 failed (InvalidArgs)
otbr-agent[306]: 00:00:00.214 [W] Platform------: [netif] Failed to process event, error:InvalidArgs


----

s6-rc: info: service universal-silabs-flasher: starting
[22:09:57] INFO: Checking /dev/ttyUSB0 identifying SkyConnect v1.0 from Nabu Casa.
[22:09:57] INFO: Starting universal-silabs-flasher with /dev/ttyUSB0
2024-01-05 22:09:58.041 farmassistant.local universal_silabs_flasher.flash INFO Extracted GBL metadata: NabuCasaMetadata(metadata_version=1, sdk_version='4.4.0', ezsp_version=None, ot_rcp_version=None, fw_type=<FirmwareImageType.RCP_UART_802154: 'rcp-uart-802154'>, baudrate=460800)
2024-01-05 22:09:58.042 farmassistant.local universal_silabs_flasher.flasher INFO Probing ApplicationType.GECKO_BOOTLOADER at 115200 baud
2024-01-05 22:10:00.082 farmassistant.local universal_silabs_flasher.flasher INFO Probing ApplicationType.CPC at 460800 baud
2024-01-05 22:10:00.122 farmassistant.local universal_silabs_flasher.flasher INFO Detected ApplicationType.CPC, version '4.4.0' at 460800 baudrate (bootloader baudrate None)
2024-01-05 22:10:00.122 farmassistant.local universal_silabs_flasher.flash INFO Firmware version '4.4.0' is flashed, not re-installing
s6-rc: info: service universal-silabs-flasher successfully started
s6-rc: info: service cpcd-config: starting
[22:10:00] INFO: Using known baudrate of 460800 for cpcd!
[22:10:00] INFO: Generating cpcd configuration.
s6-rc: info: service cpcd-config successfully started
s6-rc: info: service cpcd: starting
[22:10:00] INFO: Starting cpcd...
WARNING in function 'main' in file /usr/src/cpc-daemon/main.c at line #187 : 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
[22:10:01] INFO: Starting zigbeed...
[22:10:01] INFO: Setup OTBR firewall...
[22:10:01] INFO: Starting otbr-agent...
otbr-agent[306]: [NOTE]-AGENT---: Running 0.3.0
otbr-agent[306]: [NOTE]-AGENT---: Thread version: 1.3.0
otbr-agent[306]: [NOTE]-AGENT---: Thread interface: wpan0
otbr-agent[306]: [NOTE]-AGENT---: Radio URL: spinel+cpc://cpcd_0?iid=2&iid-list=0
otbr-agent[306]: [NOTE]-ILS-----: Infra link selected: enp0s2
otbr-agent[306]: 49d.17:14:42.928 [C] Platform------: mCpcBusSpeed = 115200
[2024-01-05T11:10:00.704546Z] Info : [CPCd v4.4.0.0] [Library API v3] [RCP Protocol v5]
[2024-01-05T11:10:00.704620Z] Info : Git commit: 1fcaa81347466165f81e4533ee4471e9c3b6db0a / branch: 
[2024-01-05T11:10:00.704622Z] Info : Sources hash: 1e938c77bacd9c9d6d8fe7b457208a3a8a2435bd6374a1918605ffb864bf1ebe
[2024-01-05T11:10:00.704623Z] WARNING : In function 'main' in file /usr/src/cpc-daemon/main.c at line #187 : Running CPCd as 'root' is not recommended. Proceed at your own risk.
[2024-01-05T11:10:00.704634Z] Info : Reading cli arguments
[2024-01-05T11:10:00.704650Z] Info : /usr/local/bin/cpcd 
[2024-01-05T11:10:00.716675Z] Info : Reading configuration
[2024-01-05T11:10:00.716678Z] Info :   file_path = /usr/local/etc/cpcd.conf
[2024-01-05T11:10:00.716679Z] Info :   instance_name = cpcd_0
[2024-01-05T11:10:00.716680Z] Info :   socket_folder = /dev/shm
[2024-01-05T11:10:00.716681Z] Info :   operation_mode = MODE_NORMAL
[2024-01-05T11:10:00.716681Z] Info :   use_encryption = false
[2024-01-05T11:10:00.716682Z] Info :   binding_key_file = /etc/binding-key.key
[2024-01-05T11:10:00.716683Z] Info :   stdout_tracing = false
[2024-01-05T11:10:00.716683Z] Info :   file_tracing = false
[2024-01-05T11:10:00.716684Z] Info :   lttng_tracing = false
[2024-01-05T11:10:00.716684Z] Info :   enable_frame_trace = false
[2024-01-05T11:10:00.716685Z] Info :   traces_folder = /dev/shm/cpcd-traces
[2024-01-05T11:10:00.716685Z] Info :   bus = UART
[2024-01-05T11:10:00.716686Z] Info :   uart_baudrate = 460800
[2024-01-05T11:10:00.716687Z] Info :   uart_hardflow = true
[2024-01-05T11:10:00.716687Z] Info :   uart_file = /dev/ttyUSB0
[2024-01-05T11:10:00.716688Z] Info :   fu_recovery_pins_enabled = false
[2024-01-05T11:10:00.716689Z] Info :   fu_connect_to_bootloader = false
[2024-01-05T11:10:00.716689Z] Info :   fu_enter_bootloader = false
[2024-01-05T11:10:00.716690Z] Info :   restart_cpcd = false
[2024-01-05T11:10:00.716690Z] Info :   application_version_validation = false
[2024-01-05T11:10:00.716691Z] Info :   print_secondary_versions_and_exit = false
[2024-01-05T11:10:00.716692Z] Info :   use_noop_keep_alive = false
[2024-01-05T11:10:00.716692Z] Info :   reset_sequence = true
[2024-01-05T11:10:00.716693Z] Info :   stats_interval = 0
[2024-01-05T11:10:00.716693Z] Info :   rlimit_nofile = 2000
[2024-01-05T11:10:00.716694Z] Info : ENCRYPTION IS DISABLED 
[2024-01-05T11:10:00.738197Z] Info : Starting daemon in normal mode
[2024-01-05T11:10:00.757684Z] Info : Connecting to Secondary...
[2024-01-05T11:10:00.842754Z] Info : RX capability is 256 bytes
[2024-01-05T11:10:00.842776Z] Info : Connected to Secondary
[2024-01-05T11:10:00.847064Z] Info : Secondary Protocol v5
[2024-01-05T11:10:00.857909Z] Info : Secondary CPC v4.4.0
[2024-01-05T11:10:00.866506Z] Info : Secondary bus bitrate is 460800
[2024-01-05T11:10:00.876396Z] Info : Secondary APP vUNDEFINED
[2024-01-05T11:10:00.876573Z] Info : Daemon startup was successful. Waiting for client connections
[2024-01-05T11:10:01.297237Z] Info : New client connection using library v4.4.0.0
[2024-01-05T11:10:01.302197Z] Info : Opened connection socket for ep#12
[2024-01-05T11:10:01.307506Z] Info : Endpoint socket #12: Client connected. 1 connection(s)
[2024-01-05T11:10:02.135168Z] Info : New client connection using library v4.4.0.0
[2024-01-05T11:10:02.141049Z] Info : Endpoint socket #12: Client connected. 2 connection(s)
otbr-agent[306]: 00:00:00.155 [N] RoutingManager: BR ULA prefix: fda6:ab0:841d::/48 (loaded)
otbr-agent[306]: 00:00:00.155 [N] RoutingManager: Local on-link prefix: fd30:cecb:62f8:d443::/64
otbr-agent[306]: 00:00:00.193 [N] Mle-----------: Role disabled -> detached
otbr-agent[306]: 00:00:00.205 [N] Platform------: [netif] Changing interface state to up.
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] ADD [U] fe80:0:0:0:9cba:7f81:df3d:2702 failed (InvalidArgs)
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] ADD [U] fd33:2b1b:f729:9003:c0a7:7ed1:df2b:d39c failed (InvalidArgs)
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[306]: 00:00:00.213 [W] Platform------: [netif] ADD [U] fd33:2b1b:f729:9003:0:ff:fe00:800 failed (InvalidArgs)
otbr-agent[306]: 00:00:00.214 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
s6-rc: info: service otbr-agent successfully started
s6-rc: info: service otbr-agent-rest-discovery: starting
s6-rc: info: service otbr-web: starting
s6-rc: info: service otbr-web successfully started
[22:10:03] INFO: Starting otbr-web...
otbr-web[399]: [INFO]-WEB-----: Running 0.3.0
otbr-web[399]: [INFO]-WEB-----: Border router web started on wpan0
[22:10:03] 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.
Accepted connection 8.

Additional information

HA is running in a VM.

Host is Ubuntu Server.

A bridge is configured in netplan:

network:
  version: 2
  ethernets:
    eno1:
      dhcp4: no
      dhcp6: no
  bridges:
    br0:
      interfaces: [eno1]
      macaddress: 6c:2b:59:de:4b:23
      dhcp4: yes
      dhcp6: yes
      parameters:
        stp: yes

And this is passed to the VM:

virsh edit hassos

    <interface type='bridge'>
      <mac address='52:54:00:48:85:e4'/>
      <source bridge='br0'/>
      <model type='e1000'/>
      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
    </interface>

[rolf-tx]

Same issue on x86-64 bare metal HAOS install and using iPhone to try to add device. I tried removing and re-enabling multi protocol support still indicate no border router present. I believe this started before 2.4.0 release. (I can add device to Apple-TV/Home and then added it using the apple TV border router generated code.)

[10:03:30] INFO: Starting cpcd... WARNING in function 'main' in file /usr/src/cpc-daemon/main.c at line #187 : 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 [10:03:30] INFO: Starting zigbeed... [10:03:30] INFO: Setup OTBR firewall... [10:03:30] INFO: Starting otbr-agent... otbr-agent[302]: [NOTE]-AGENT---: Running 0.3.0 otbr-agent[302]: [NOTE]-AGENT---: Thread version: 1.3.0 otbr-agent[302]: [NOTE]-AGENT---: Thread interface: wpan0 otbr-agent[302]: [NOTE]-AGENT---: Radio URL: spinel+cpc://cpcd_0?iid=2&iid-list=0 otbr-agent[302]: [NOTE]-ILS-----: Infra link selected: enp3s0 otbr-agent[302]: 49d.17:42:58.791 [C] Platform------: mCpcBusSpeed = 115200 [2024-01-05T16:03:30.062720Z] Info : [CPCd v4.4.0.0] [Library API v3] [RCP Protocol v5] [2024-01-05T16:03:30.062751Z] Info : Git commit: 1fcaa81347466165f81e4533ee4471e9c3b6db0a / branch: [2024-01-05T16:03:30.062752Z] Info : Sources hash: 1e938c77bacd9c9d6d8fe7b457208a3a8a2435bd6374a1918605ffb864bf1ebe [2024-01-05T16:03:30.062754Z] WARNING : In function 'main' in file /usr/src/cpc-daemon/main.c at line #187 : Running CPCd as 'root' is not recommended. Proceed at your own risk. [2024-01-05T16:03:30.062762Z] Info : Reading cli arguments [2024-01-05T16:03:30.062766Z] Info : /usr/local/bin/cpcd [2024-01-05T16:03:30.063535Z] Info : Reading configuration [2024-01-05T16:03:30.063539Z] Info : file_path = /usr/local/etc/cpcd.conf [2024-01-05T16:03:30.063540Z] Info : instance_name = cpcd_0 [2024-01-05T16:03:30.063540Z] Info : socket_folder = /dev/shm [2024-01-05T16:03:30.063541Z] Info : operation_mode = MODE_NORMAL [2024-01-05T16:03:30.063542Z] Info : use_encryption = false [2024-01-05T16:03:30.063542Z] Info : binding_key_file = /etc/binding-key.key [2024-01-05T16:03:30.063543Z] Info : stdout_tracing = false [2024-01-05T16:03:30.063544Z] Info : file_tracing = false [2024-01-05T16:03:30.063544Z] Info : lttng_tracing = false [2024-01-05T16:03:30.063545Z] Info : enable_frame_trace = false [2024-01-05T16:03:30.063545Z] Info : traces_folder = /dev/shm/cpcd-traces [2024-01-05T16:03:30.063546Z] Info : bus = UART [2024-01-05T16:03:30.063546Z] Info : uart_baudrate = 460800 [2024-01-05T16:03:30.063547Z] Info : uart_hardflow = true [2024-01-05T16:03:30.063548Z] Info : uart_file = /dev/ttyUSB0 [2024-01-05T16:03:30.063548Z] Info : fu_recovery_pins_enabled = false [2024-01-05T16:03:30.063549Z] Info : fu_connect_to_bootloader = false [2024-01-05T16:03:30.063549Z] Info : fu_enter_bootloader = false [2024-01-05T16:03:30.063550Z] Info : restart_cpcd = false [2024-01-05T16:03:30.063551Z] Info : application_version_validation = false [2024-01-05T16:03:30.063551Z] Info : print_secondary_versions_and_exit = false [2024-01-05T16:03:30.063552Z] Info : use_noop_keep_alive = false [2024-01-05T16:03:30.063552Z] Info : reset_sequence = true [2024-01-05T16:03:30.063553Z] Info : stats_interval = 0 [2024-01-05T16:03:30.063553Z] Info : rlimit_nofile = 2000 [2024-01-05T16:03:30.063554Z] Info : ENCRYPTION IS DISABLED [2024-01-05T16:03:30.074718Z] Info : Starting daemon in normal mode [2024-01-05T16:03:30.086237Z] Info : Connecting to Secondary... [2024-01-05T16:03:30.166814Z] Info : RX capability is 256 bytes [2024-01-05T16:03:30.166841Z] Info : Connected to Secondary [2024-01-05T16:03:30.170211Z] Info : Secondary Protocol v5 [2024-01-05T16:03:30.177249Z] Info : Secondary CPC v4.4.0 [2024-01-05T16:03:30.183893Z] Info : Secondary bus bitrate is 460800 [2024-01-05T16:03:30.190565Z] Info : Secondary APP vUNDEFINED [2024-01-05T16:03:30.190722Z] Info : Daemon startup was successful. Waiting for client connections [2024-01-05T16:03:30.595536Z] Info : New client connection using library v4.4.0.0 [2024-01-05T16:03:30.598964Z] Info : Opened connection socket for ep#12 [2024-01-05T16:03:30.602365Z] Info : Endpoint socket #12: Client connected. 1 connection(s) [2024-01-05T16:03:31.460879Z] Info : New client connection using library v4.4.0.0 [2024-01-05T16:03:31.465745Z] Info : Endpoint socket #12: Client connected. 2 connection(s) otbr-agent[302]: 00:00:00.106 [N] RoutingManager: BR ULA prefix: fd47:e600:4854::/48 (loaded) otbr-agent[302]: 00:00:00.106 [N] RoutingManager: Local on-link prefix: fd56:4bf3:dde4:c4bc::/64 otbr-agent[302]: 00:00:00.140 [N] Mle-----------: Role disabled -> detached otbr-agent[302]: 00:00:00.146 [N] Platform------: [netif] Changing interface state to up. otbr-agent[302]: 00:00:00.154 [W] Platform------: [netif] ADD [U] fe80:0:0:0:c0a9:689a:1cd2:17c9 failed (InvalidArgs) otbr-agent[302]: 00:00:00.154 [W] Platform------: [netif] Failed to process event, error:InvalidArgs otbr-agent[302]: 00:00:00.155 [W] Platform------: [netif] ADD [U] fd2c:e96e:ca00:1793:2ec2:660:a96a:63a9 failed (InvalidArgs) otbr-agent[302]: 00:00:00.155 [W] Platform------: [netif] Failed to process event, error:InvalidArgs otbr-agent[302]: 00:00:00.155 [W] Platform------: [netif] ADD [U] fd2c:e96e:ca00:1793:0:ff:fe00:e000 failed (InvalidArgs) otbr-agent[302]: 00:00:00.155 [W] Platform------: [netif] Failed to process event, error:InvalidArgs s6-rc: info: service otbr-agent successfully started s6-rc: info: service otbr-agent-rest-discovery: starting [10:03:33] 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[302]: 00:00:25.967 [N] Mle-----------: RLOC16 e000 -> fffe otbr-agent[302]: 00:00:25.971 [W] Platform------: [netif] Failed to process request#5: Operation not supported otbr-agent[302]: 00:00:26.209 [N] Mle-----------: Attach attempt 1, AnyPartition reattaching with Active Dataset otbr-agent[302]: 00:00:32.710 [N] RouterTable---: Allocate router id 56 otbr-agent[302]: 00:00:32.710 [N] Mle-----------: RLOC16 fffe -> e000 otbr-agent[302]: 00:00:32.714 [N] Mle-----------: Role detached -> leader otbr-agent[302]: 00:00:32.715 [N] Mle-----------: Partition ID 0x3241e1b2 otbr-agent[302]: 00:00:32.753 [W] Platform------: [netif] Failed to process request#6: File exists otbr-agent[302]: 00:00:32.754 [W] Platform------: [netif] ADD [U] fd2c:e96e:ca00:1793:0:ff:fe00:fc00 failed (InvalidArgs) otbr-agent[302]: 00:00:32.755 [W] Platform------: [netif] Failed to process event, error:InvalidArgs otbr-agent[302]: [NOTE]-BBA-----: BackboneAgent: Backbone Router becomes Primary! otbr-agent[302]: 00:00:33.405 [W] Platform------: [netif] ADD [U] fd2c:e96e:ca00:1793:0:ff:fe00:fc38 failed (InvalidArgs) otbr-agent[302]: 00:00:33.405 [W] Platform------: [netif] Failed to process event, error:InvalidArgs otbr-agent[302]: 00:00:33.407 [W] Platform------: [netif] ADD [U] fd2c:e96e:ca00:1793:0:ff:fe00:fc10 failed (InvalidArgs) otbr-agent[302]: 00:00:33.407 [W] Platform------: [netif] Failed to process event, error:InvalidArgs otbr-agent[302]: 00:00:36.538 [W] Platform------: [netif] ADD [U] fd47:e600:4854:1:a9af:7815:24d5:62b2 failed (InvalidArgs) otbr-agent[302]: 00:00:36.539 [W] Platform------: [netif] Failed to process event, error:InvalidArgs otbr-agent[302]: 00:00:46.304 [W] Platform------: [netif] ADD [U] fd2c:e96e:ca00:1793:0:ff:fe00:fc11 failed (InvalidArgs) otbr-agent[302]: 00:00:46.304 [W] Platform------: [netif] Failed to process event, error:InvalidArgs Accepted connection 8.

[rolf-tx]

Same issues with 2.4.2

[fuomag9]

Same issue on already paired devices

[cwrau]

I have the same issue on odroid n2 with the sky connect stick.

[Iminet72]

I have the same error Skyconnect stick only runs threads. I can't add a new device Add-on version: 2.4.5 System: Home Assistant OS 11.4 (amd64 / generic-x86-64) Home Assistant Core: 2024.1.6 Home Assistant Supervisor: 2023.12.1

[github-actions[bot]]

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.