Hello I have problems to get matter runing at my Homeassistant. I wanted to try to integrate an Aquara P2 Sensor over Matter and Thread. I have installed Matter and an OpenThread boarder Router but when I try to Integrate the P2. I am allways getting a fault like this: No connection to Thread network ha-thread-ce07
What Can I do to find and solve the fault.
My System is running as a VM on a Proxmox Server. All Updates are installed. Except for Operating System Update 12.3.
Guide I used:
https://phoscon.de/de/openthread/doc
Log from openthread boarder Router:
s6-rc: info: service banner successfully started
s6-rc: info: service universal-silabs-flasher: starting
[15:19:45] INFO: Flashing firmware is disabled
s6-rc: info: service universal-silabs-flasher successfully started
s6-rc: info: service otbr-agent: starting
[15:19:45] INFO: Setup OTBR firewall...
[15:19:45] INFO: Starting otbr-agent...
otbr-agent[169]: [NOTE]-AGENT---: Running 0.3.0-2279c02-dirty
otbr-agent[169]: [NOTE]-AGENT---: Thread version: 1.3.0
otbr-agent[169]: [NOTE]-AGENT---: Thread interface: wpan0
otbr-agent[169]: [NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200&uart-flow-control
otbr-agent[169]: [NOTE]-AGENT---: Radio URL: trel://enp0s18
otbr-agent[169]: [NOTE]-ILS-----: Infra link selected: enp0s18
otbr-agent[169]: 00:00:00.021 [N] RoutingManager: BR ULA prefix: fd0e:7dcd:677::/48 (loaded)
otbr-agent[169]: 00:00:00.021 [N] RoutingManager: Local on-link prefix: fdc7:1c53:9db1:f0c::/64
otbr-agent[169]: 00:00:00.032 [N] Mle-----------: Role disabled -> detached
otbr-agent[169]: 00:00:00.044 [N] Platform------: [netif] Changing interface state to up.
otbr-agent[169]: 00:00:00.046 [W] Platform------: [netif] Failed to process request#2: No such process
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] ADD [U] fe80:0:0:0:28f2:d17:eeb3:ff99 failed (InvalidArgs)
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:c6c:440a:edb5:b2ed failed (InvalidArgs)
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:0:ff:fe00:2c00 failed (InvalidArgs)
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:00.047 [W] Platform------: [netif] Failed to process request#6: No such process
s6-rc: info: service otbr-agent successfully started
s6-rc: info: service otbr-agent-rest-discovery: starting
s6-rc: info: service otbr-agent-configure: starting
[15:19:46] INFO: Enabling NAT64.
otbr-agent[169]: 00:00:00.300 [W] Platform------: [netif] Failed to process request#7: No such process
Done
Done
Done
otbr-agent[169]: 00:00:00.303 [W] Platform------: Daemon read: Connection reset by peer
s6-rc: info: service otbr-agent-configure successfully started
[15:19:46] 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
otbr-agent[169]: 00:00:27.909 [N] Mle-----------: RLOC16 2c00 -> fffe
otbr-agent[169]: 00:00:28.376 [N] Mle-----------: Attach attempt 1, AnyPartition reattaching with Active Dataset
otbr-agent[169]: 00:00:34.876 [N] RouterTable---: Allocate router id 11
otbr-agent[169]: 00:00:34.876 [N] Mle-----------: RLOC16 fffe -> 2c00
otbr-agent[169]: 00:00:34.877 [N] Mle-----------: Role detached -> leader
otbr-agent[169]: 00:00:34.878 [N] Mle-----------: Partition ID 0x4d10b7f2
otbr-agent[169]: 00:00:34.896 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:0:ff:fe00:2c00 failed (InvalidArgs)
otbr-agent[169]: 00:00:34.896 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:34.897 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:0:ff:fe00:fc00 failed (InvalidArgs)
otbr-agent[169]: 00:00:34.897 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: [NOTE]-BBA-----: BackboneAgent: Backbone Router becomes Primary!
otbr-agent[169]: 00:00:35.527 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:0:ff:fe00:fc38 failed (InvalidArgs)
otbr-agent[169]: 00:00:35.527 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:35.527 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:0:ff:fe00:fc10 failed (InvalidArgs)
otbr-agent[169]: 00:00:35.527 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:36.143 [W] Platform------: [netif] ADD [U] fd0e:7dcd:677:1:1707:b6:d237:d2ba failed (InvalidArgs)
otbr-agent[169]: 00:00:36.143 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:00:45.369 [W] Platform------: [netif] ADD [U] fd0d:419e:7a30:f23e:0:ff:fe00:fc11 failed (InvalidArgs)
otbr-agent[169]: 00:00:45.369 [W] Platform------: [netif] Failed to process event, error:InvalidArgs
otbr-agent[169]: 00:11:17.920 [W] Mle-----------: Failed to process Child ID Request: Security
otbr-agent[169]: 00:11:19.033 [W] Mle-----------: Failed to process Parent Request: Duplicated
otbr-agent[169]: 00:11:21.065 [W] Mle-----------: Failed to process Child ID Request: Security
otbr-agent[169]: 00:11:22.439 [W] Mle-----------: Failed to process Parent Request: Duplicated
otbr-agent[169]: 00:11:24.480 [W] Mle-----------: Failed to process Child ID Request: Security
otbr-agent[169]: 00:11:27.118 [W] Mle-----------: Failed to process Child ID Request: Security
otbr-agent[169]: 00:11:30.546 [W] Mle-----------: Failed to process Child ID Request: Security
otbr-agent[169]: 00:11:36.097 [W] Mle-----------: Failed to process Child ID Request: Security
otbr-agent[169]: 00:11:45.760 [W] Mle-----------: Failed to process Child ID Request: Security
Matter Log:
s6-rc: info: service banner successfully started
s6-rc: info: service matter-server: starting
s6-rc: info: service matter-server successfully started
s6-rc: info: service legacy-services: starting
[15:16:13] INFO: Starting Matter Server...
s6-rc: info: service legacy-services successfully started
[15:16:13] INFO: Using 'enp0s18' as primary network interface.
[15:16:13] INFO: Successfully send discovery information to Home Assistant.
2024-05-30 15:16:14.822 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Logging...
2024-05-30 15:16:14.822 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Controller Stack...
[1717074974.928835][126:126] CHIP:CTL: Setting attestation nonce to random value
[1717074974.933553][126:126] CHIP:CTL: Setting CSR nonce to random value
[1717074974.947456][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1717074974.947583][126:126] CHIP:DL: writing settings to file (/tmp/chip_kvs-qU6fcJ)
[1717074974.947629][126:126] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1717074974.947840][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_factory.ini
[1717074974.950867][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_config.ini
[1717074974.951232][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_counters.ini
[1717074974.952556][126:126] CHIP:DL: writing settings to file (/data/chip_counters.ini-kg8aeK)
[1717074974.952724][126:126] CHIP:DL: renamed tmp file to file (/data/chip_counters.ini)
[1717074974.952734][126:126] CHIP:DL: NVS set: chip-counters/reboot-count = 8 (0x8)
[1717074974.952937][126:126] CHIP:DL: Got Ethernet interface: enp0s18
[1717074974.953012][126:126] CHIP:DL: Found the primary Ethernet interface:enp0s18
[1717074974.953186][126:126] CHIP:DL: Failed to get WiFi interface
[1717074974.953236][126:126] CHIP:DL: Failed to reset WiFi statistic counts
2024-05-30 15:16:14.953 (MainThread) WARNING [PersistentStorage] Initializing persistent storage from file: /data/chip.json
2024-05-30 15:16:14.953 (MainThread) WARNING [PersistentStorage] Loading configuration from /data/chip.json...
2024-05-30 15:16:15.001 (MainThread) WARNING [CertificateAuthorityManager] Loading certificate authorities from storage...
2024-05-30 15:16:15.001 (MainThread) WARNING [CertificateAuthority] New CertificateAuthority at index 1
2024-05-30 15:16:15.002 (MainThread) WARNING [CertificateAuthority] Loading fabric admins from storage...
2024-05-30 15:16:15.002 (MainThread) WARNING [FabricAdmin] New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0x134B
2024-05-30 15:16:15.002 (MainThread) INFO [matter_server.server.stack] CHIP Controller Stack initialized.
2024-05-30 15:16:15.004 (MainThread) INFO [matter_server.server.server] Starting the Matter Server...
2024-05-30 15:16:15.009 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from DCL.
2024-05-30 15:16:17.211 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 58 PAA root certificates from DCL.
2024-05-30 15:16:26.161 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 74 PAA root certificates from Test DCL.
2024-05-30 15:16:26.161 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from Git.
2024-05-30 15:16:40.894 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 5 PAA root certificates from Git.
2024-05-30 15:16:40.894 (MainThread) WARNING [FabricAdmin] Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000002, NodeId: 0x000000000001B669, CatTags: []
2024-05-30 15:16:40.983 (MainThread) INFO [matter_server.server.device_controller] Loaded 0 nodes from stored configuration
2024-05-30 15:16:40.986 (MainThread) INFO [matter_server.server.vendor_info] Loading vendor info from storage.
2024-05-30 15:16:40.988 (MainThread) INFO [matter_server.server.vendor_info] Loaded 196 vendors from storage.
2024-05-30 15:16:40.988 (MainThread) INFO [matter_server.server.vendor_info] Fetching the latest vendor info from DCL.
2024-05-30 15:16:41.161 (MainThread) INFO [matter_server.server.vendor_info] Fetched 196 vendors from DCL.
2024-05-30 15:16:41.161 (MainThread) INFO [matter_server.server.vendor_info] Saving vendor info to storage.