Based on NETWORK_DEVICE option doesn't work. · Issue #8 · ownbee/hass-otbr-docker · GitHub I built the following setup on my system using an SLZB-MR1:
- Docker compose:
services:
openthread_border_router:
container_name: openthread_border_router
image: ghcr.io/ownbee/hass-otbr-docker:0c7bc03
environment:
- DEVICE=/tmp/ttyOTBR
- NETWORK_DEVICE=192.168.64.32:6638
- BACKBONE_IF=eno1
- BAUDRATE=460800
- OTBR_REST_PORT=8151
- OTBR_REST_LISTEN_PORT=8151
- OTBR_WEB_PORT=8152
- OTBR_LOG_LEVEL=info
- FLOW_CONTROL=0
- NAT64=1
- AUTOFLASH_FIRMWARE=0
- OTBR_ENABLE=1
- FIREWALL=1
devices:
- /dev/net/tun:/dev/net/tun
volumes:
- /etc/localtime:/etc/localtime:ro
- /opt/openthread_border_router/var/lib/thread:/var/lib/thread:rw
restart: unless-stopped
privileged: true
network_mode: host
stdin_open: true
tty: true
matter_server:
container_name: matter_server
image: ghcr.io/home-assistant-libs/python-matter-server:stable
volumes:
- /etc/localtime:/etc/localtime:ro
- /opt/matter_server/data:/data
restart: unless-stopped
privileged: true
network_mode: host
command: --storage-path /data
security_opt:
- apparmor:unconfined
depends_on:
- openthread_border_router
- /etc/sysctl.conf:
net.ipv6.conf.all.disable_ipv6=0
net.ipv4.conf.all.forwarding=1
net.ipv6.conf.all.forwarding=1
net.ipv6.conf.all.accept_ra=2
net.ipv6.conf.all.accept_ra_rt_info_max_plen=64
net.ipv6.conf.eno1.accept_ra=2
net.ipv6.conf.wpan0.accept_ra=2
I formed a thread network, added it to Home Assistant, selected it as preferred network:
Then I synced thread credentials on my Android device (also tried iPhone).
Now I tried adding Lafaer LWR01 and Tado X. Both devices are added successfully based on their status lights and the log, but the pairing from the app fails saying that the device is not reachable.
The device IP is fda0:e803:c69:1:a983:ad78:26b0:6e86, which is reachable from the host:
ping6 fda0:e803:c69:1:a983:ad78:26b0:6e86
PING fda0:e803:c69:1:a983:ad78:26b0:6e86 (fda0:e803:c69:1:a983:ad78:26b0:6e86) 56 data bytes
64 bytes from fda0:e803:c69:1:a983:ad78:26b0:6e86: icmp_seq=1 ttl=64 time=447 ms
64 bytes from fda0:e803:c69:1:a983:ad78:26b0:6e86: icmp_seq=2 ttl=64 time=521 ms
These are the logs:
- openthread_border_router:
[I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
[I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fda0:e803:c69:1::/64 -exist` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdd9:c86b:21ae:4658::/64 -exist` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fda0:e803:c69:1::/64 -exist` = 0
[I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
[I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
[INFO]-BA------: Publish meshcop service Home Assistant OpenThread Border Router #760B._meshcop._udp.local.
[INFO]-BA------: Result of publish meshcop service Home Assistant OpenThread Border Router #760B._meshcop._udp.local: OK
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:152, chksum:27b6, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] MeshForwarder-: Sent IPv6 UDP msg, len:155, chksum:7ade, ecn:no, to:0xffff, sec:yes, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:152, chksum:27b6, ecn:no, to:7ee1a9e7bc97ab74, sec:yes, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: Received IPv6 UDP msg, len:78, chksum:9723, ecn:no, from:7ee1a9e7bc97ab74, sec:no, prio:net, rss:-70.0, radio:15.4
[I] MeshForwarder-: src:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] Mle-----------: Receive Child Update Response from child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] MeshForwarder-: Received IPv6 UDP msg, len:111, chksum:9842, ecn:no, from:7ee1a9e7bc97ab74, sec:no, prio:net, rss:-69.0, radio:15.4
[I] MeshForwarder-: src:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] Mle-----------: Receive Child Update Request from child (fe80:0:0:0:7ce1:a9e7:bc97:ab74)
[I] Mle-----------: Child 0x6c01 IPv6 address[1]=fdd9:c86b:21ae:4658:3f4e:8933:79a6:ad1f
[I] Mle-----------: Child 0x6c01 IPv6 address[2]=fda0:e803:c69:1:a983:ad78:26b0:6e86
[I] Mle-----------: Child 0x6c01 has 2 registered IPv6 addresses, 2 addresses stored
[I] Mle-----------: Send Child Update Response to child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:105, chksum:331a, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-69, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:105, chksum:331a, ecn:no, to:7ee1a9e7bc97ab74, sec:no, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] NetDataPublshr: ExternalRoute ::/0 - State: Adding -> Added
[I] NetworkData---: Sent ServerData
[I] NetworkData---: Received ServerData
[I] NetworkData---: Sent ServerData ack
[I] Notifier------: StateChanged (0x00000200) [NetData]
[I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
[I] Mle-----------: Send Child Update Request to child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] BbrLeader-----: PBBR state: Unchanged
[I] BbrLeader-----: Rloc16:0x6c00, seqno:60, delay:5, timeout:3600
[I] RoutingManager: Will evaluate routing policy in 00:02.816 (2816 msec)
[I] NetDataPublshr: ExternalRoute ::/0 (state:Added) in netdata - total:1, preferred:0, desired:10
[I] NetDataPublshr: ExternalRoute fda0:e803:c69:2:0:0::/96 (state:Added) in netdata - total:1, preferred:0, desired:10
[I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
[I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fda0:e803:c69:1::/64 -exist` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdd9:c86b:21ae:4658::/64 -exist` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fda0:e803:c69:1::/64 -exist` = 0
[I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
[I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
[INFO]-BA------: Publish meshcop service Home Assistant OpenThread Border Router #760B._meshcop._udp.local.
[INFO]-BA------: Result of publish meshcop service Home Assistant OpenThread Border Router #760B._meshcop._udp.local: OK
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:161, chksum:d8a9, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] MeshForwarder-: Sent IPv6 UDP msg, len:164, chksum:622d, ecn:no, to:0xffff, sec:yes, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-71, ack-fp:1
[I] Mac-----------: Frame tx attempt 1/1 failed, error:NoAck, len:71, seqnum:146, type:Data, src:26832e34632a760b, dst:7ee1a9e7bc97ab74, sec:yes, ackreq:yes, radio:15.4
[I] DataPollHandlr: Indirect tx to child 6c01 failed, attempt 1/4
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:161, chksum:d8a9, ecn:no, to:7ee1a9e7bc97ab74, sec:yes, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: Received IPv6 UDP msg, len:78, chksum:7e4b, ecn:no, from:7ee1a9e7bc97ab74, sec:no, prio:net, rss:-69.0, radio:15.4
[I] MeshForwarder-: src:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] Mle-----------: Receive Child Update Response from child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] RoutingManager: RsSender: Sent RS 2/3
[I] RoutingManager: Received RS from fe80:0:0:0:a6bb:6dff:fe4f:63e0 on infra netif 2
[I] RoutingManager: Will evaluate routing policy in 00:00.303 (303 msec)
[I] RoutingManager: Received RA from fe80:0:0:0:0:0:0:1 on infra netif 2
[I] RoutingManager: - RA Header - flags - M:0 O:1 S:0
[I] RoutingManager: - RA Header - default route - lifetime:1800
[I] RoutingManager: - RA Header - default route - lifetime:1800
[I] RoutingManager: - PIO 2a01:261:e6c:5400::/64 (valid:81881, preferred:81881)
[I] RoutingManager: - RIO 2a01:261:e6c:5400::/64 (lifetime:86400, prf:high)
[I] MeshForwarder-: Received IPv6 UDP msg, len:111, chksum:4459, ecn:no, from:7ee1a9e7bc97ab74, sec:no, prio:net, rss:-69.0, radio:15.4
[I] MeshForwarder-: src:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] Mle-----------: Receive Child Update Request from child (fe80:0:0:0:7ce1:a9e7:bc97:ab74)
[I] Mle-----------: Child 0x6c01 IPv6 address[1]=fdd9:c86b:21ae:4658:3f4e:8933:79a6:ad1f
[I] Mle-----------: Child 0x6c01 IPv6 address[2]=fda0:e803:c69:1:a983:ad78:26b0:6e86
[I] Mle-----------: Child 0x6c01 has 2 registered IPv6 addresses, 2 addresses stored
[I] Mle-----------: Send Child Update Response to child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:105, chksum:cb07, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-69, ack-fp:1
[I] RoutingManager: Evaluating routing policy
[I] RoutingManager: Evaluating NAT64 prefix
[I] RoutingManager: Will evaluate routing policy in 00:17.533 (17533 msec)
[I] MeshForwarder-: Sent IPv6 UDP msg, len:105, chksum:cb07, ecn:no, to:7ee1a9e7bc97ab74, sec:no, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-69, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-69, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-69, ack-fp:0
[I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
[I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:9975, ecn:no, to:0xffff, sec:no, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-69, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] RoutingManager: RsSender: Sent RS 3/3
[I] RoutingManager: Received RS from fe80:0:0:0:a6bb:6dff:fe4f:63e0 on infra netif 2
[I] RoutingManager: Will evaluate routing policy in 00:00.111 (111 msec)
[I] RoutingManager: Received RA from fe80:0:0:0:0:0:0:1 on infra netif 2
[I] RoutingManager: - RA Header - flags - M:0 O:1 S:0
[I] RoutingManager: - RA Header - default route - lifetime:1800
[I] RoutingManager: - RA Header - default route - lifetime:1800
[I] RoutingManager: - PIO 2a01:261:e6c:5400::/64 (valid:81881, preferred:81881)
[I] RoutingManager: - RIO 2a01:261:e6c:5400::/64 (lifetime:86400, prf:high)
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] RoutingManager: Evaluating routing policy
[I] RoutingManager: Evaluating NAT64 prefix
[I] RoutingManager: Will evaluate routing policy in 00:17.661 (17661 msec)
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] Mac-----------: Frame rx failed, error:Duplicated, len:22, seqnum:243, type:Cmd(DataReq), src:0x6c01, dst:0x6c00, sec:yes, ackreq:yes, radio:15.4
[I] RoutingManager: RsSender: Finished sending RS msgs and waiting for RAs
[I] RoutingManager: Will evaluate routing policy immediately
[I] RoutingManager: Evaluating routing policy
[I] RoutingManager: Evaluating NAT64 prefix
[I] RoutingManager: Preparing RA
[I] RoutingManager: - RA Header - flags - M:0 O:1 S:1
[I] RoutingManager: - RA Header - default route - lifetime:0
[I] RoutingManager: - RIO fda0:e803:c69:1::/64 (lifetime:1800, prf:medium)
[I] RoutingManager: Sent RA on infra netif 2
[I] Settings------: Read SrpServerInfo {port:53536}
[I] SrpServer-----: Selected port 53537
[I] NetDataPublshr: Publishing DNS/SRP service unicast (ml-eid, port:53537, ver:0)
[I] NetDataPublshr: DNS/SRP service - State: NoEntry -> ToAdd
[I] NetDataPublshr: DNS/SRP service (state:ToAdd) in netdata - total:0, preferred:0, desired:2
[I] NetDataPublshr: DNS/SRP service - State: ToAdd -> Adding
[I] NetDataPublshr: DNS/SRP service (state:Adding) - update in 2893 msec
[I] RoutingManager: Will evaluate routing policy in 00:14.326 (14326 msec)
[I] RoutingManager: Received RA from fe80:0:0:0:a6bb:6dff:fe4f:63e0 on infra netif 2 (this BR routing-manager)
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] Mac-----------: Frame rx failed, error:Duplicated, len:22, seqnum:244, type:Cmd(DataReq), src:0x6c01, dst:0x6c00, sec:yes, ackreq:yes, radio:15.4
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] NetDataPublshr: DNS/SRP service - State: Adding -> Added
[I] SrpServer-----: Start listening on port 53537
[I] NetworkData---: Sent ServerData
[I] NetworkData---: Received ServerData
[I] NetworkData---: Allocated Service ID = 1
[I] NetworkData---: Sent ServerData ack
[I] Notifier------: StateChanged (0x00000200) [NetData]
[I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
[I] Mle-----------: Send Child Update Request to child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] BbrLeader-----: PBBR state: Unchanged
[I] BbrLeader-----: Rloc16:0x6c00, seqno:60, delay:5, timeout:3600
[I] P-Netif-------: Sent request#15 to add fdd9:c86b:21ae:4658:0:ff:fe00:fc11/64
[I] RoutingManager: Will evaluate routing policy in 00:02.070 (2070 msec)
[I] SrpClient-----: AutoStartState 1stTimeSelect -> Unicast
[I] SrpClient-----: Requesting max tx jitter 10000 (OnServerStart)
[I] SrpClient-----: Auto-starting, server [fdd9:c86b:21ae:4658:e1cb:665e:71d4:4f7a]:53537
[I] SrpClient-----: State Stopped -> Updated
[I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2
[I] NetDataPublshr: ExternalRoute ::/0 (state:Added) in netdata - total:1, preferred:0, desired:10
[I] NetDataPublshr: ExternalRoute fda0:e803:c69:2:0:0::/96 (state:Added) in netdata - total:1, preferred:0, desired:10
[I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
[I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fda0:e803:c69:1::/64 -exist` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdd9:c86b:21ae:4658::/64 -exist` = 0
[I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fda0:e803:c69:1::/64 -exist` = 0
[I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
[I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
[INFO]-BA------: Publish meshcop service Home Assistant OpenThread Border Router #760B._meshcop._udp.local.
[INFO]-BA------: Result of publish meshcop service Home Assistant OpenThread Border Router #760B._meshcop._udp.local: OK
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:188, chksum:a130, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] Notifier------: StateChanged (0x00000001) [Ip6+]
[I] P-Netif-------: Message dropped by Thread
[I] P-Netif-------: Succeeded to process request#15
[I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff33:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff32:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
[I] P-Netif-------: ADD [U] fdd9:c86b:21ae:4658:0:ff:fe00:fc11
[I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff33:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff32:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
[I] MeshForwarder-: Sent IPv6 UDP msg, len:191, chksum:c126, ecn:no, to:0xffff, sec:yes, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] Mle-----------: Send Child Update Request to child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:188, chksum:95f7, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:188, chksum:95f7, ecn:no, to:7ee1a9e7bc97ab74, sec:yes, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: Received IPv6 UDP msg, len:78, chksum:1597, ecn:no, from:7ee1a9e7bc97ab74, sec:no, prio:net, rss:-70.0, radio:15.4
[I] MeshForwarder-: src:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] Mle-----------: Receive Child Update Response from child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] P-Netif-------: Message dropped by Thread
[I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff33:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff32:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff33:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff32:40:fdd9:c86b:21ae:4658:0:1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
[I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
[I] MeshForwarder-: Received IPv6 UDP msg, len:111, chksum:9262, ecn:no, from:7ee1a9e7bc97ab74, sec:no, prio:net, rss:-71.0, radio:15.4
[I] MeshForwarder-: src:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] Mle-----------: Receive Child Update Request from child (fe80:0:0:0:7ce1:a9e7:bc97:ab74)
[I] Mle-----------: Child 0x6c01 IPv6 address[1]=fdd9:c86b:21ae:4658:3f4e:8933:79a6:ad1f
[I] Mle-----------: Child 0x6c01 IPv6 address[2]=fda0:e803:c69:1:a983:ad78:26b0:6e86
[I] Mle-----------: Child 0x6c01 has 2 registered IPv6 addresses, 2 addresses stored
[I] Mle-----------: Send Child Update Response to child (fe80:0:0:0:7ce1:a9e7:bc97:ab74,0x6c01)
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:105, chksum:b74e, ecn:no, to:0x6c01, sec:no, prio:net, radio:all
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:105, chksum:b74e, ecn:no, to:7ee1a9e7bc97ab74, sec:no, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[fe80:0:0:0:7ce1:a9e7:bc97:ab74]:19788
[I] MeshForwarder-: Received IPv6 UDP msg, len:313, chksum:cfa6, ecn:no, from:0x6c01, sec:yes, prio:normal, rss:-70.0, radio:15.4
[I] MeshForwarder-: src:[fda0:e803:c69:1:a983:ad78:26b0:6e86]:49154
[I] MeshForwarder-: dst:[fdd9:c86b:21ae:4658:e1cb:665e:71d4:4f7a]:53537
[I] SrpServer-----: Received DNS update from fda0:e803:c69:1:a983:ad78:26b0:6e86
[I] SrpServer-----: Processed SRP update info
[I] SrpServer-----: Host:7EE1A9E7BC97AB74.default.service.arpa.
[I] SrpServer-----: Lease:0, key-lease:1209600, ttl:7200
[I] SrpServer-----: No host address
[I] SrpServer-----: SRP update handler is notified (updatedId = 740185722)
[INFO]-ADPROXY-: Advertise SRP service updates: host=7EE1A9E7BC97AB74.default.service.arpa.
[INFO]-MDNS----: Removing host 7EE1A9E7BC97AB74
[INFO]-ADPROXY-: Handle unpublish SRP host '7EE1A9E7BC97AB74.default.service.arpa.': OK
[I] SrpServer-----: Handler result of SRP update (id = 740185722) is received: OK
[I] SrpServer-----: Committing update for new host 7EE1A9E7BC97AB74.default.service.arpa.
[I] SrpServer-----: Granted lease:0, key-lease:680400, ttl:30
[I] Settings------: Saved SrpServerInfo {port:53537}
[I] SrpServer-----: Send success response with granted lease: 0 and key lease: 680400
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:83, chksum:611e, ecn:no, to:0x6c01, sec:yes, prio:normal, radio:all
[I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-70, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:2ab8, ecn:no, to:0xffff, sec:no, prio:net, radio:all
[I] MeshForwarder-: src:[fe80:0:0:0:2483:2e34:632a:760b]:19788
[I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788
[I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:611e, ecn:no, to:0x6c01, sec:yes, prio:normal, radio:all
[I] MeshForwarder-: src:[fdd9:c86b:21ae:4658:e1cb:665e:71d4:4f7a]:53537
[I] MeshForwarder-: dst:[fda0:e803:c69:1:a983:ad78:26b0:6e86]:49154
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-70, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] RoutingManager: Evaluating routing policy
[I] RoutingManager: Evaluating NAT64 prefix
[I] RoutingManager: Preparing RA
[I] RoutingManager: - RA Header - flags - M:0 O:1 S:1
[I] RoutingManager: - RA Header - default route - lifetime:0
[I] RoutingManager: - RIO fda0:e803:c69:1::/64 (lifetime:1800, prf:medium)
[I] RoutingManager: Sent RA on infra netif 2
[I] RoutingManager: Will evaluate routing policy in 00:14.324 (14324 msec)
[I] RoutingManager: Received RA from fe80:0:0:0:a6bb:6dff:fe4f:63e0 on infra netif 2 (this BR routing-manager)
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-71, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-72, ack-fp:0
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:0, rss:-68, ack-fp:0
[I] MeshForwarder-: Received IPv6 UDP msg, len:313, chksum:e799, ecn:no, from:0x6c01, sec:yes, prio:normal, rss:-69.0, radio:15.4
[I] MeshForwarder-: src:[fda0:e803:c69:1:a983:ad78:26b0:6e86]:49154
[I] MeshForwarder-: dst:[fdd9:c86b:21ae:4658:e1cb:665e:71d4:4f7a]:53537
[I] SrpServer-----: Received DNS update from fda0:e803:c69:1:a983:ad78:26b0:6e86
[I] SrpServer-----: Processed SRP update info
[I] SrpServer-----: Host:7EE1A9E7BC97AB74.default.service.arpa.
[I] SrpServer-----: Lease:0, key-lease:1209600, ttl:7200
[I] SrpServer-----: No host address
[I] SrpServer-----: SRP update handler is notified (updatedId = 740185723)
[INFO]-ADPROXY-: Advertise SRP service updates: host=7EE1A9E7BC97AB74.default.service.arpa.
[INFO]-MDNS----: Removing host 7EE1A9E7BC97AB74
[INFO]-ADPROXY-: Handle unpublish SRP host '7EE1A9E7BC97AB74.default.service.arpa.': OK
[I] SrpServer-----: Handler result of SRP update (id = 740185723) is received: OK
[I] SrpServer-----: Committing update for existing host 7EE1A9E7BC97AB74.default.service.arpa.
[I] SrpServer-----: Granted lease:0, key-lease:680400, ttl:30
[I] SrpServer-----: Send success response with granted lease: 0 and key lease: 680400
[I] MeshForwarder-: Prepping indir tx IPv6 UDP msg, len:83, chksum:3efe, ecn:no, to:0x6c01, sec:yes, prio:normal, radio:all
[I] DataPollHandlr: Rx data poll, src:0x6c01, qed_msgs:1, rss:-68, ack-fp:1
[I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:3efe, ecn:no, to:0x6c01, sec:yes, prio:normal, radio:all
[I] MeshForwarder-: src:[fdd9:c86b:21ae:4658:e1cb:665e:71d4:4f7a]:53537
[I] MeshForwarder-: dst:[fda0:e803:c69:1:a983:ad78:26b0:6e86]:49154
- matter_server:
(MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Logging...
(MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Controller Stack...
CHIP:CTL: Setting attestation nonce to random value
CHIP:CTL: Setting CSR nonce to random value
CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
CHIP:DL: Wrote settings to /tmp/chip_kvs
CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_factory.ini
CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_config.ini
CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_counters.ini
CHIP:DL: Wrote settings to /data/chip_counters.ini
CHIP:DL: NVS set: chip-counters/reboot-count = 6 (0x6)
CHIP:DL: Got Ethernet interface: eno1
CHIP:DL: Found the primary Ethernet interface:eno1
CHIP:DL: Failed to get WiFi interface
CHIP:DL: Failed to reset WiFi statistic counts
CHIP:PAF: WiFiPAF: WiFiPAFLayer::Init()
(MainThread) INFO [chip.storage] Initializing persistent storage from file: /data/chip.json
(MainThread) INFO [chip.storage] Loading configuration from /data/chip.json...
(MainThread) INFO [chip.CertificateAuthority] Loading certificate authorities from storage...
(MainThread) INFO [chip.CertificateAuthority] New CertificateAuthority at index 1
(MainThread) INFO [chip.CertificateAuthority] Loading fabric admins from storage...
(MainThread) INFO [chip.FabricAdmin] New FabricAdmin: FabricId: 0x0000000000000001, VendorId = 0xFFF1
(MainThread) INFO [matter_server.server.stack] CHIP Controller Stack initialized.
(MainThread) INFO [matter_server.server.server] Matter Server initialized
(MainThread) INFO [matter_server.server.server] Using 'None' as primary interface (for link-local addresses)
(MainThread) INFO [matter_server.server.server] Starting the Matter Server...
(MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from DCL.
(MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 72 PAA root certificates from DCL.
(MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from Git.
(MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 2 PAA root certificates from Git.
(MainThread) INFO [chip.FabricAdmin] Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000001, NodeId: 0x000000000001B669, CatTags: []
(Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
(Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
(Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/lib/dnssd/minimal_mdns/Server.cpp:344: CHIP Error 0x00000046: No endpoint was available to send the message
(Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/lib/dnssd/minimal_mdns/Server.cpp:344: CHIP Error 0x00000046: No endpoint was available to send the message
(Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/lib/dnssd/minimal_mdns/Server.cpp:344: CHIP Error 0x00000046: No endpoint was available to send the message
(Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/lib/dnssd/minimal_mdns/Server.cpp:344: CHIP Error 0x00000046: No endpoint was available to send the message
(MainThread) INFO [matter_server.server.vendor_info] Loading vendor info from storage.
(MainThread) INFO [matter_server.server.vendor_info] Loaded 380 vendors from storage.
(MainThread) INFO [matter_server.server.vendor_info] Fetching the latest vendor info from DCL.
(MainThread) INFO [matter_server.server.vendor_info] Fetched 379 vendors from DCL.
(MainThread) INFO [matter_server.server.vendor_info] Saving vendor info to storage.
(MainThread) INFO [matter_server.server.device_controller] Loaded 0 nodes from stored configuration
(MainThread) INFO [matter_server.server.server] Matter Server successfully initialized.
So what am I doing wrong? The device indeed becomes unreachable after a while even with ping, as it probably goes to sleep.
I would be very grateful for any help or pointers on how to solve this.