openthread / ot-br-posix

OpenThread Border Router, a Thread border router for POSIX-based platforms.
https://openthread.io/
BSD 3-Clause "New" or "Revised" License
411 stars 230 forks source link

Question: How to setup ot-br-posix on OpenWRT #1250

Closed caipiblack closed 2 years ago

caipiblack commented 2 years ago

Currently I build ot-br-posix on OpenWRT using the makefile and the instructions from here: https://github.com/openthread/ot-br-posix/tree/main/etc/openwrt/openthread-br

The build succeed, but when I execute otbr-agent on the OpenWRT device I get an error on startup:

root@Container-X:/# /usr/sbin/otbr-agent 'spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200' -d 7 -v
otbr-agent[6529]: [INFO]-UTILS---: Running 0.3.0-749c553-dirty
otbr-agent[6529]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[6529]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[6529]: [INFO]-UTILS---: Backbone interface: 
otbr-agent[6529]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200
otbr-agent[6529]: 49d.23:48:04.350 [INFO]-PLAT----: RCP reset: RESET_POWER_ON
otbr-agent[6529]: 49d.23:48:04.359 [NOTE]-PLAT----: RCP API Version: 5
otbr-agent[6529]: 49d.23:48:04.361 [CRIT]-PLAT----: platformConfigureTunDevice() at /home/path_to/sdk/build_dir/target-xxxx-generic_arm-scos-linux-gnueabi/openthread-br-66c170401d1f14942cad729186cb39c91109f9d6/third_party/openthread/repo/src/posix/platform/netif.cpp:1447: No such file or directory

The commit ID: 66c170401d1f14942cad729186cb39c91109f9d6

Question:

caipiblack commented 2 years ago

As discussed here (https://github.com/openthread/ot-br-posix/issues/1042) I solved these problems using these commands:

ln -s /var/run/ /run
ln -s /etc /var/lib

And now there is a new error:

root@Container-xxx:/# otbr-agent[2247]: [INFO]-UTILS---: Running 0.3.0-749c553-dirty
otbr-agent[2247]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[2247]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[2247]: [INFO]-UTILS---: Backbone interface: BR_LAN
otbr-agent[2247]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0
otbr-agent[2247]: [INFO]-UTILS---: Radio URL: trel://BR_LAN
otbr-agent[2247]: 49d.17:47:01.056 [INFO]-PLAT----: RCP reset: RESET_POWER_ON
otbr-agent[2247]: 49d.17:47:01.065 [NOTE]-PLAT----: RCP API Version: 5
otbr-agent[2247]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[2247]: [INFO]-APP-----: Border router agent started.
otbr-agent[2247]: 00:00:00.020 [INFO]-CORE----: Notifier: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
sh: ipset: not found
otbr-agent[2247]: [INFO]-UBUS----: Connected as a6c13a23
otbr-agent[2247]: 00:00:00.024 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 32512
otbr-agent[2247]: 00:00:00.024 [WARN]-PLAT----: Failed to update ipsets: Failed
otbr-agent[2247]: [INFO]-UBUS----: Uloop run
otbr-agent[2247]: 00:00:00.026 [WARN]-PLAT----: netlink NLMSG_ERROR response: seq=0, error=-99
otbr-agent[2247]: 00:00:00.027 [INFO]-PLAT----: [netif] Host netif is down
otbr-agent[2247]: 00:00:00.028 [INFO]-PLAT----: [trel] Interface address added successfully.

As I understand i have to install ipset.

Is there a documentation somewhere with the list of all dependancies required to build ot-br-posix on OpenWRT ?

What is the difference between OpenWRT build and standard build ? (OTBR_OPENWRT=ON)

superwhd commented 2 years ago

I took a look at the OpenWRT doc you mentioned. It seems the doc is out of date. @bukepo Do we have a plan to refresh it?

Regarding the ipset error, it's fine to ignore such errors if you don't need the firewall feature. The firewall feature needs some scripts to set up the iptables rules before running otbr-agent. I'm not familiar with OpenWRT platform, but for Raspberry Pi, we usually run script/setup to set up all the prerequisites. See also: https://openthread.io/codelabs/openthread-border-router#1.

caipiblack commented 2 years ago

My objective is to start ot-br-posix in a LXC container running on OpenWRT.

The container only includes the strict minimum. For example some administrative tools like ip6tables or iptables are not available but I can access them through a "custom software" built from another company like this:

remote_firewall_cli add iptables -t filter --group $RULES_GROUP -- "-I OUTPUT -p udp -m udp --dport 5540 -j ACCEPT"
remote_firewall_cli add iptables -6 -t filter --group $RULES_GROUP -- "-I OUTPUT -p udp -m udp --dport 5540 -j ACCEPT"

That's why i'm asking a documentation/list of requirements to run otbr-agent.

Currently:

Status:

Configuration of the border-router:

root@Container-xxx:/# ot-ctl dataset init new
Done
root@Container-xxx:/# ot-ctl dataset commit active
Done
root@Container-xxx:/# ot-ctl prefix add fd11:22::/64 pasor
Done
root@Container-xxx:/# ot-ctl ifconfig up
Done
root@Container-xxx:/# ot-ctl thread start
Done
root@Container-xxx:/# ot-ctl netdata register
Done
root@Container-xxx:/# ot-ctl ipaddr
fd11:22:0:0:4603:c3c2:59f9:85b3
fda8:9be8:e353:8070:0:ff:fe00:fc00
fda8:9be8:e353:8070:0:ff:fe00:a400
fda8:9be8:e353:8070:594a:3777:98a1:42f9
fe80:0:0:0:2c71:907a:4613:ecb3
Done

Logs from otbr-agent:

root@Container-xxxx:/# otbr-agent[16038]: [INFO]-UTILS---: Running 0.3.0-749c553-dirty
otbr-agent[16038]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[16038]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[16038]: [INFO]-UTILS---: Backbone interface: BR_LAN
otbr-agent[16038]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0
otbr-agent[16038]: [INFO]-UTILS---: Radio URL: trel://BR_LAN
otbr-agent[16038]: 49d.21:29:14.925 [INFO]-PLAT----: RCP reset: RESET_POWER_ON
otbr-agent[16038]: 49d.21:29:14.934 [NOTE]-PLAT----: RCP API Version: 5
otbr-agent[16038]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[16038]: [INFO]-APP-----: Border router agent started.
otbr-agent[16038]: 00:00:00.025 [INFO]-CORE----: Notifier: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
otbr-agent[16038]: [INFO]-UBUS----: Connected as 19c5f18c
otbr-agent[16038]: [INFO]-UBUS----: Uloop run
otbr-agent[16038]: 00:00:00.035 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[16038]: 00:00:00.039 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[16038]: 00:00:00.044 [INFO]-PLAT----: Execute command `ipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist` = 0
otbr-agent[16038]: 00:00:00.048 [INFO]-PLAT----: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[16038]: 00:00:00.052 [INFO]-PLAT----: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[16038]: 00:00:00.054 [WARN]-PLAT----: netlink NLMSG_ERROR response: seq=0, error=-99
otbr-agent[16038]: 00:00:00.054 [INFO]-PLAT----: [netif] Host netif is down
otbr-agent[16038]: 00:00:00.054 [INFO]-PLAT----: [trel] Interface address added successfully.
otbr-agent[16038]: 00:00:37.859 [INFO]-PLAT----: Session socket is ready
otbr-agent[16038]: 00:00:37.859 [INFO]-PLAT----: > dataset init new
otbr-agent[16038]: 00:00:37.859 [NOTE]-CLI-----: Input: dataset init new
otbr-agent[16038]: 00:00:37.861 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:00:44.347 [INFO]-PLAT----: Session socket is ready
otbr-agent[16038]: 00:00:44.347 [INFO]-PLAT----: > dataset commit active
otbr-agent[16038]: 00:00:44.347 [NOTE]-CLI-----: Input: dataset commit active
otbr-agent[16038]: 00:00:44.351 [INFO]-CORE----: [settings] Saved ActiveDataset
otbr-agent[16038]: 00:00:44.351 [INFO]-MESH-CP-: Active dataset set
otbr-agent[16038]: 00:00:44.354 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:00:44.354 [INFO]-CORE----: Notifier: StateChanged (0x101fc100) [KeySeqCntr Channel PanId NetName ExtPanId NetworkKey PSKc SecPolicy ActDset]
otbr-agent[16038]: 00:00:44.354 [INFO]-MLE-----: [announce-sender] ChannelMask:{ 11-26 }, period:21500
otbr-agent[16038]: 00:00:44.354 [INFO]-MLE-----: [announce-sender] StartingChannel:11
otbr-agent[16038]: 00:00:44.354 [INFO]-MLE-----: [announce-sender] StartingChannel:11
otbr-agent[16038]: 00:00:53.428 [INFO]-PLAT----: Session socket is ready
otbr-agent[16038]: 00:00:53.428 [INFO]-PLAT----: > prefix add fd11:22::/64 pasor
otbr-agent[16038]: 00:00:53.428 [NOTE]-CLI-----: Input: prefix add fd11:22::/64 pasor
otbr-agent[16038]: 00:00:53.429 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:00:57.796 [INFO]-PLAT----: Session socket is ready
otbr-agent[16038]: 00:00:57.796 [INFO]-PLAT----: > ifconfig up
otbr-agent[16038]: 00:00:57.797 [NOTE]-CLI-----: Input: ifconfig up
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Added multicast address ff02::1
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Added multicast address ff03::1
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Added multicast address ff03::fc
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Sent request#1 to add fe80::2c71:907a:4613:ecb3/64
otbr-agent[16038]: 00:00:57.800 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:00:57.801 [INFO]-CORE----: Notifier: StateChanged (0x01001009) [Ip6+ LLAddr Ip6Mult+ NetifState]
otbr-agent[16038]: 00:00:57.801 [NOTE]-PLAT----: [netif] Changing interface state to up.
otbr-agent[16038]: 00:00:57.801 [WARN]-PLAT----: [netif] Failed to process request#1: Unknown error -13
otbr-agent[16038]: 00:00:57.802 [INFO]-PLAT----: [netif] Host netif is up
otbr-agent[16038]: 00:01:02.421 [INFO]-PLAT----: Session socket is ready
otbr-agent[16038]: 00:01:02.421 [INFO]-PLAT----: > thread start
otbr-agent[16038]: 00:01:02.422 [NOTE]-CLI-----: Input: thread start
otbr-agent[16038]: 00:01:02.422 [NOTE]-MLE-----: Role disabled -> detached
otbr-agent[16038]: 00:01:02.422 [INFO]-PLAT----: [netif] Sent request#2 to add fda8:9be8:e353:8070:594a:3777:98a1:42f9/64
otbr-agent[16038]: 00:01:02.422 [INFO]-PLAT----: [netif] Added multicast address ff32:40:fda8:9be8:e353:8070:0:1
otbr-agent[16038]: 00:01:02.422 [INFO]-PLAT----: [netif] Added multicast address ff33:40:fda8:9be8:e353:8070:0:1
otbr-agent[16038]: 00:01:02.422 [INFO]-MLE-----: AttachState Idle -> Start
otbr-agent[16038]: 00:01:02.423 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:01:02.423 [INFO]-CORE----: Notifier: StateChanged (0x10001015) [Ip6+ Role MLAddr Ip6Mult+ ActDset]
otbr-agent[16038]: 00:01:02.423 [INFO]-MLE-----: [announce-sender] Stopped
otbr-agent[16038]: 00:01:02.423 [WARN]-PLAT----: [netif] Failed to process request#2: Unknown error -13
otbr-agent[16038]: 00:01:02.520 [NOTE]-MLE-----: Attempt to attach - attempt 1, any-partition reattaching with Active Dataset
otbr-agent[16038]: 00:01:02.520 [INFO]-MLE-----: AttachState Start -> ParentReqRouters
otbr-agent[16038]: 00:01:02.520 [INFO]-MLE-----: Send Parent Request to routers (ff02:0:0:0:0:0:0:2)
otbr-agent[16038]: 00:01:02.526 [INFO]-MAC-----: Sent IPv6 UDP msg, len:84, chksum:10e5, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:02.526 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:02.526 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:2]:19788
otbr-agent[16038]: 00:01:03.271 [INFO]-MLE-----: AttachState ParentReqRouters -> ParentReqReeds
otbr-agent[16038]: 00:01:03.271 [INFO]-MLE-----: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2)
otbr-agent[16038]: 00:01:03.280 [INFO]-MAC-----: Sent IPv6 UDP msg, len:84, chksum:e3ac, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:03.280 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:03.280 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:2]:19788
otbr-agent[16038]: 00:01:04.522 [INFO]-MLE-----: AttachState ParentReqReeds -> Idle
otbr-agent[16038]: 00:01:04.522 [NOTE]-MLE-----: Allocate router id 41
otbr-agent[16038]: 00:01:04.522 [NOTE]-MLE-----: RLOC16 fffe -> a400
otbr-agent[16038]: 00:01:04.523 [INFO]-PLAT----: [netif] Sent request#3 to add fda8:9be8:e353:8070:0:ff:fe00:a400/64
otbr-agent[16038]: 00:01:04.523 [NOTE]-MLE-----: Role detached -> leader
otbr-agent[16038]: 00:01:04.523 [INFO]-PLAT----: [netif] Sent request#4 to add fda8:9be8:e353:8070:0:ff:fe00:fc00/64
otbr-agent[16038]: 00:01:04.523 [INFO]-PLAT----: [netif] Added multicast address ff02::2
otbr-agent[16038]: 00:01:04.523 [INFO]-PLAT----: [netif] Added multicast address ff03::2
otbr-agent[16038]: 00:01:04.523 [NOTE]-MLE-----: Leader partition id 0x7c148e2
otbr-agent[16038]: 00:01:04.524 [INFO]-CORE----: Notifier: StateChanged (0x100012a5) [Ip6+ Role Rloc+ PartitionId NetData Ip6Mult+ ActDset]
otbr-agent[16038]: 00:01:04.528 [INFO]-CORE----: [settings] Saved NetworkInfo {rloc:0xa400, extaddr:2e71907a4613ecb3, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[16038]: 00:01:04.528 [INFO]-CORE----: [settings] ... pid:0x7c148e2, mlecntr:0x3eb, maccntr:0x3e8, mliid:594a377798a142f9}
otbr-agent[16038]: 00:01:04.528 [INFO]-MLE-----: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[16038]: 00:01:04.528 [INFO]-BBR-----: PBBR state: None
otbr-agent[16038]: 00:01:04.528 [INFO]-BBR-----: Domain Prefix: ::/0, state: None
otbr-agent[16038]: 00:01:04.529 [INFO]-CORE----: [settings] Re-saved NetworkInfo {rloc:0xa400, extaddr:2e71907a4613ecb3, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[16038]: 00:01:04.529 [INFO]-CORE----: [settings] ... pid:0x7c148e2, mlecntr:0x3eb, maccntr:0x3e8, mliid:594a377798a142f9}
otbr-agent[16038]: 00:01:04.529 [INFO]-N-DATA--: Sent server data notification
otbr-agent[16038]: 00:01:04.529 [INFO]-MLE-----: [announce-sender] Started
otbr-agent[16038]: 00:01:04.529 [INFO]-MESH-CP-: Border Agent start listening on port 0
otbr-agent[16038]: 00:01:04.533 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[16038]: 00:01:04.538 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[16038]: 00:01:04.541 [INFO]-PLAT----: Execute command `ipset add otbr-ingress-deny-src-swap fda8:9be8:e353:8070::/64 -exist` = 0
otbr-agent[16038]: 00:01:04.545 [INFO]-PLAT----: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[16038]: 00:01:04.549 [INFO]-PLAT----: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[16038]: 00:01:04.549 [WARN]-PLAT----: [netif] Failed to process request#3: Unknown error -13
otbr-agent[16038]: 00:01:04.550 [INFO]-N-DATA--: Received network data registration
otbr-agent[16038]: 00:01:04.550 [INFO]-N-DATA--: Allocated Context ID = 1
otbr-agent[16038]: 00:01:04.550 [INFO]-N-DATA--: Sent network data registration acknowledgment
otbr-agent[16038]: 00:01:04.550 [WARN]-PLAT----: [netif] Failed to process request#4: Unknown error -13
otbr-agent[16038]: 00:01:04.550 [INFO]-CORE----: Notifier: StateChanged (0x00000200) [NetData]
otbr-agent[16038]: 00:01:04.550 [INFO]-MLE-----: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[16038]: 00:01:04.550 [INFO]-BBR-----: PBBR state: None
otbr-agent[16038]: 00:01:04.551 [INFO]-BBR-----: Domain Prefix: ::/0, state: None
otbr-agent[16038]: 00:01:04.555 [INFO]-CORE----: [settings] Saved SlaacIidSecretKey
otbr-agent[16038]: 00:01:04.555 [INFO]-UTIL----: SLAAC: Generated and saved secret key
otbr-agent[16038]: 00:01:04.555 [INFO]-UTIL----: SLAAC: Adding address fd11:22:0:0:4603:c3c2:59f9:85b3
otbr-agent[16038]: 00:01:04.555 [INFO]-PLAT----: [netif] Sent request#5 to add fd11:22::4603:c3c2:59f9:85b3/64
otbr-agent[16038]: 00:01:04.561 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[16038]: 00:01:04.565 [INFO]-PLAT----: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[16038]: 00:01:04.568 [INFO]-PLAT----: Execute command `ipset add otbr-ingress-deny-src-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[16038]: 00:01:04.572 [INFO]-PLAT----: Execute command `ipset add otbr-ingress-deny-src-swap fda8:9be8:e353:8070::/64 -exist` = 0
otbr-agent[16038]: 00:01:04.576 [INFO]-PLAT----: Execute command `ipset add otbr-ingress-allow-dst-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[16038]: 00:01:04.580 [INFO]-PLAT----: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[16038]: 00:01:04.584 [INFO]-PLAT----: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[16038]: 00:01:04.585 [INFO]-CORE----: Notifier: StateChanged (0x00000001) [Ip6+]
otbr-agent[16038]: 00:01:04.585 [WARN]-PLAT----: [netif] Failed to process request#5: Unknown error -13
otbr-agent[16038]: 00:01:04.589 [INFO]-MAC-----: Sent IPv6 UDP msg, len:96, chksum:4de1, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:04.589 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:04.589 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[16038]: 00:01:04.596 [INFO]-MAC-----: Sent IPv6 UDP msg, len:118, chksum:9eee, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:04.596 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:04.596 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[16038]: 00:01:05.194 [INFO]-MLE-----: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[16038]: 00:01:05.202 [INFO]-MAC-----: Sent IPv6 UDP msg, len:90, chksum:1c88, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:05.202 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:05.202 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[16038]: 00:01:06.674 [INFO]-MLE-----: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[16038]: 00:01:06.679 [INFO]-MAC-----: Sent IPv6 UDP msg, len:90, chksum:435b, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:06.679 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:06.679 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[16038]: 00:01:09.087 [INFO]-PLAT----: Session socket is ready
otbr-agent[16038]: 00:01:09.087 [INFO]-PLAT----: > netdata register
otbr-agent[16038]: 00:01:09.087 [NOTE]-CLI-----: Input: netdata register
otbr-agent[16038]: 00:01:09.088 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:01:09.539 [INFO]-MLE-----: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[16038]: 00:01:09.548 [INFO]-MAC-----: Sent IPv6 UDP msg, len:90, chksum:40f7, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:09.548 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:09.548 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[16038]: 00:01:19.197 [INFO]-MLE-----: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[16038]: 00:01:19.204 [INFO]-MAC-----: Sent IPv6 UDP msg, len:90, chksum:f892, to:0xffff, sec:no, prio:net, radio:all
otbr-agent[16038]: 00:01:19.204 [INFO]-MAC-----:     src:[fe80:0:0:0:2c71:907a:4613:ecb3]:19788
otbr-agent[16038]: 00:01:19.204 [INFO]-MAC-----:     dst:[ff02:0:0:0:0:0:0:1]:19788

Result of ifconfig command on the device running otbr-agent:

root@Container-xxx:/# ifconfig
BR_LAN    Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:ED  
          inet addr:192.168.4.1  Bcast:192.168.4.255  Mask:255.255.255.0
          inet6 addr: fe80::db:b5ad:5c2f:fa43/64 Scope:Link
          inet6 addr: fe80::46d4:53ff:fe5d:d1ed/64 Scope:Link
          inet6 addr: fdaa:bbcc:ddee::1/128 Scope:Global
          inet6 addr: fe80::8044:e539:17e0:4f8d/64 Scope:Link
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:20654 errors:0 dropped:3 overruns:0 frame:0
          TX packets:6596 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:3374679 (3.2 MiB)  TX bytes:1046788 (1022.2 KiB)

eth0      Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:ED  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

eth1      Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:ED  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

eth2      Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:ED  
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:5244 errors:0 dropped:0 overruns:0 frame:0
          TX packets:4288 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:705879 (689.3 KiB)  TX bytes:911196 (889.8 KiB)

eth2.u    Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:ED  
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:5236 errors:0 dropped:0 overruns:0 frame:0
          TX packets:4288 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:600901 (586.8 KiB)  TX bytes:890172 (869.3 KiB)

eth3      Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:E7  
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:27774 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2246 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:2902320 (2.7 MiB)  TX bytes:588477 (574.6 KiB)

eth3.110  Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:E6  
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:344 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:119024 (116.2 KiB)

eth3.111  Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:E6  
          inet6 addr: fe80::46d4:53ff:fe5d:d1e6/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:351 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:119798 (116.9 KiB)

eth3.u    Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:E7  
          inet addr:192.168.20.30  Bcast:192.168.20.255  Mask:255.255.255.0
          inet6 addr: fe80::46d4:53ff:fe5d:d1e7/64 Scope:Link
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:27764 errors:0 dropped:109 overruns:0 frame:0
          TX packets:1551 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:2401928 (2.2 MiB)  TX bytes:337245 (329.3 KiB)

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:187292 errors:0 dropped:0 overruns:0 frame:0
          TX packets:187292 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:46459769 (44.3 MiB)  TX bytes:46459769 (44.3 MiB)

wl0       Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:EB  
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:141 overruns:0 frame:0
          TX packets:1287 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:410280 (400.6 KiB)

wl1       Link encap:Ethernet  HWaddr 44:D4:53:5D:D1:EA  
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:22 overruns:0 frame:818013
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)
          Interrupt:73 

wl1.1     Link encap:Ethernet  HWaddr 46:D4:53:5D:D1:EB  
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:10 overruns:0 frame:818013
          TX packets:1304 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:434315 (424.1 KiB)

wpan0     Link encap:UNSPEC  HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00  
          UP POINTOPOINT RUNNING NOARP MULTICAST  MTU:1280  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:500 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

Logs from the ot-cli-ftd device connected to the thread network formed by otbr-agent:

> factoryreset
> dataset set active xxxxxxxxx
Done
> ifconfig up
Done
> thread start
Done
> ipaddr
fd11:22:0:0:5e45:c062:663e:8372
fda8:9be8:e353:8070:0:ff:fe00:a401
fda8:9be8:e353:8070:f1e0:41b1:88bf:1c4f
fe80:0:0:0:a4ed:3304:9b23:c037
Done
> ping fd11:22:0:0:4603:c3c2:59f9:85b3
1 packets transmitted, 0 packets received. Packet loss = 100.0%.
Done

Result:

Currently it's not possible to ping the fd11:xxx ip of the border router from a thread device. But from the "cli" (on the two sides) they are correctly set.

I think the problem is due to the errors that appear in otbr-agent logs, (possibly related to netlink)

caipiblack commented 2 years ago

I have tested ot-br-posix on a fresh OpenWRT 21.02 built for Raspberry to see if i have the same problems or not.

1. No feed for package 'openthread-br' found

The instructions (here) doesn't seems to be correct (No feed for package 'openthread-br' found).

My feeds.conf contains something like this:

src-git base https://git.openwrt.org/openwrt/openwrt.git;v21.02.0
src-git packages https://git.openwrt.org/feed/packages.git^65057dcbb5de371503c9159de3d45824bec482e0
src-git luci https://git.openwrt.org/project/luci.git^3b3c2e5f9f82372df8ff01ac65668be47690dcd5
src-git routing https://git.openwrt.org/feed/routing.git^c30c9ffc93702365439a7647244a052531f2e957
src-git telephony https://git.openwrt.org/feed/telephony.git^7f73a9ad19269dcddcb7fc26e03a9823717587bb
src-link openthread "/home/path_to/ot-br-posix/etc/openwrt"

Here is the result of the commands:

$ ./scripts/feeds update openthread
Updating feed 'openthread' from '"/home/path_to/ot-br-posix/etc/openwrt"' ...
Create index file './feeds/openthread.index' 
Collecting package info: done
Collecting target info: done
$ ./scripts/feeds install openthread-br
WARNING: No feed for package 'openthread-br' found

Note: At this point, to build ot-br-posix, i put your makefile in openwrt_sdk/package/openthread-br and I modify it (Makefile.txt)

2. ipset warning/errors

After installing ipset and running the commands from script/otbr-firewall, there are errors when otbr-agent is started. (but as you said, we can bypass it)

NOTE: At this point otbr-agent seems to be running, we can create a network and a thread device is able to join the network. The thread device is also able to ping the border router IP.

And now ?

Now I try to find why in my primarry OpenWRT system it doesn't works.

The problem is:

I think the problem is due to the errors on these logs:

otbr-agent[16038]: 00:00:57.796 [INFO]-PLAT----: > ifconfig up
otbr-agent[16038]: 00:00:57.797 [NOTE]-CLI-----: Input: ifconfig up
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Added multicast address ff02::1
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Added multicast address ff03::1
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Added multicast address ff03::fc
otbr-agent[16038]: 00:00:57.800 [INFO]-PLAT----: [netif] Sent request#1 to add fe80::2c71:907a:4613:ecb3/64
otbr-agent[16038]: 00:00:57.800 [NOTE]-CLI-----: Output: Done
otbr-agent[16038]: 00:00:57.801 [INFO]-CORE----: Notifier: StateChanged (0x01001009) [Ip6+ LLAddr Ip6Mult+ NetifState]
otbr-agent[16038]: 00:00:57.801 [NOTE]-PLAT----: [netif] Changing interface state to up.
otbr-agent[16038]: 00:00:57.801 [WARN]-PLAT----: [netif] Failed to process request#1: Unknown error -13
otbr-agent[16038]: 00:00:57.802 [INFO]-PLAT----: [netif] Host netif is up
otbr-agent[16038]: 00:01:02.421 [INFO]-PLAT----: Session socket is ready

Did you know what can cause this error -13 (Permission denied) ? (The software is run as root) What is the software trying to do at this point ? Adding an IP to a Linux interface ? What does it use to do it ? "netlink" ?

mrninhvn commented 2 years ago

Hi, I'm also trying to run openthread border router with openwrt. I have the same problem as you, however I can't create a network in web interface, while using ot-ctl it's ok. This is my log:

root@X-WRT:~# /usr/sbin/otbr-agent -v -I wpan0 'spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200'
otbr-agent[9325]: [INFO]-UTILS---: Running 0.3.0-4a1a398f5
otbr-agent[9325]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[9325]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[9325]: [INFO]-UTILS---: Backbone interface:
otbr-agent[9325]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200
otbr-agent[9325]: 49d.17:21:27.805 [I] Platform------: RCP reset: RESET_POWER_ON
otbr-agent[9325]: 49d.17:21:27.815 [N] Platform------: RCP API Version: 5
otbr-agent[9325]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[9325]: [INFO]-APP-----: Border router agent started.
otbr-agent[9325]: 00:00:00.022 [I] Notifier------: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
otbr-agent[9325]: [INFO]-UBUS----: Connected as 5e375a7d
otbr-agent[9325]: [INFO]-UBUS----: Uloop run
ipset v7.6: The set with the given name does not exist
otbr-agent[9325]: 00:00:00.057 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 256
otbr-agent[9325]: 00:00:00.057 [W] Platform------: Failed to update ipsets: Failed
otbr-agent[9325]: 00:00:00.059 [I] Platform------: [netif] Host netif is down
otbr-agent[9325]: 00:00:09.374 [I] Notifier------: StateChanged (0x300c4010) [MLAddr Channel NetworkKey PSKc ActDset PndDset]
otbr-agent[9325]: 00:00:09.374 [I] AnnounceSender: StartingChannel:11
otbr-agent[9325]: 00:00:11.374 [C] Platform------: BSD TCP function() at radio_spinel_impl.hpp:2226: RadioSpinelNoResponse
caipiblack commented 2 years ago

Hi, I'm also trying to run openthread border router with openwrt. I have the same problem as you, however I can't create a network in web interface, while using ot-ctl it's ok. This is my log:

root@X-WRT:~# /usr/sbin/otbr-agent -v -I wpan0 'spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200'
otbr-agent[9325]: [INFO]-UTILS---: Running 0.3.0-4a1a398f5
otbr-agent[9325]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[9325]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[9325]: [INFO]-UTILS---: Backbone interface:
otbr-agent[9325]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200
otbr-agent[9325]: 49d.17:21:27.805 [I] Platform------: RCP reset: RESET_POWER_ON
otbr-agent[9325]: 49d.17:21:27.815 [N] Platform------: RCP API Version: 5
otbr-agent[9325]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[9325]: [INFO]-APP-----: Border router agent started.
otbr-agent[9325]: 00:00:00.022 [I] Notifier------: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
otbr-agent[9325]: [INFO]-UBUS----: Connected as 5e375a7d
otbr-agent[9325]: [INFO]-UBUS----: Uloop run
ipset v7.6: The set with the given name does not exist
otbr-agent[9325]: 00:00:00.057 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 256
otbr-agent[9325]: 00:00:00.057 [W] Platform------: Failed to update ipsets: Failed
otbr-agent[9325]: 00:00:00.059 [I] Platform------: [netif] Host netif is down
otbr-agent[9325]: 00:00:09.374 [I] Notifier------: StateChanged (0x300c4010) [MLAddr Channel NetworkKey PSKc ActDset PndDset]
otbr-agent[9325]: 00:00:09.374 [I] AnnounceSender: StartingChannel:11
otbr-agent[9325]: 00:00:11.374 [C] Platform------: BSD TCP function() at radio_spinel_impl.hpp:2226: RadioSpinelNoResponse

Your problem is not exactly the same as mine.

As I know, to make ipset working you need to install ipset and execute some ipset commands before starting otbr-agent. The commands to execute are in script/otbr-firewall

It will solve some ipset errors but there are still errors that i don’t know how to fix (it’s possible that otbr-agent was made for a newer version of ipset than the openwrt version (to check, but as i don’t find any list of requirements to port openthread…))

The documentation of openthread is very good about how to use it but there are some lacks about how to port it, so you have to see what they do in bootstrap and setup script …

And my problem is not resolved yet.

mrninhvn commented 2 years ago

Hi, I'm also trying to run openthread border router with openwrt. I have the same problem as you, however I can't create a network in web interface, while using ot-ctl it's ok. This is my log:

root@X-WRT:~# /usr/sbin/otbr-agent -v -I wpan0 'spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200'
otbr-agent[9325]: [INFO]-UTILS---: Running 0.3.0-4a1a398f5
otbr-agent[9325]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[9325]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[9325]: [INFO]-UTILS---: Backbone interface:
otbr-agent[9325]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=115200
otbr-agent[9325]: 49d.17:21:27.805 [I] Platform------: RCP reset: RESET_POWER_ON
otbr-agent[9325]: 49d.17:21:27.815 [N] Platform------: RCP API Version: 5
otbr-agent[9325]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[9325]: [INFO]-APP-----: Border router agent started.
otbr-agent[9325]: 00:00:00.022 [I] Notifier------: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
otbr-agent[9325]: [INFO]-UBUS----: Connected as 5e375a7d
otbr-agent[9325]: [INFO]-UBUS----: Uloop run
ipset v7.6: The set with the given name does not exist
otbr-agent[9325]: 00:00:00.057 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 256
otbr-agent[9325]: 00:00:00.057 [W] Platform------: Failed to update ipsets: Failed
otbr-agent[9325]: 00:00:00.059 [I] Platform------: [netif] Host netif is down
otbr-agent[9325]: 00:00:09.374 [I] Notifier------: StateChanged (0x300c4010) [MLAddr Channel NetworkKey PSKc ActDset PndDset]
otbr-agent[9325]: 00:00:09.374 [I] AnnounceSender: StartingChannel:11
otbr-agent[9325]: 00:00:11.374 [C] Platform------: BSD TCP function() at radio_spinel_impl.hpp:2226: RadioSpinelNoResponse

Your problem is not exactly the same as mine.

As I know, to make ipset working you need to install ipset and execute some ipset commands before starting otbr-agent. The commands to execute are in script/otbr-firewall

It will solve some ipset errors but there are still errors that i don’t know how to fix (it’s possible that otbr-agent was made for a newer version of ipset than the openwrt version (to check, but as i don’t find any list of requirements to port openthread…))

The documentation of openthread is very good about how to use it but there are some lacks about how to port it, so you have to see what they do in bootstrap and setup script …

And my problem is not resolved yet.

My otbr still works with ipset error, it can create network but can't join network

mrninhvn commented 2 years ago

Update my ifconfig up log:

otbr-agent[26928]: 00:08:15.341 [I] Platform------: > ifconfig up
otbr-agent[26928]: 00:08:15.341 [N] Cli-----------: Input: ifconfig up
otbr-agent[26928]: 00:08:15.344 [I] Platform------: [netif] Added multicast address ff02::1
otbr-agent[26928]: 00:08:15.345 [I] Platform------: [netif] Added multicast address ff03::1
otbr-agent[26928]: 00:08:15.346 [I] Platform------: [netif] Added multicast address ff03::fc
otbr-agent[26928]: 00:08:15.351 [I] Platform------: [netif] Sent request#11 to add fe80::14d4:291b:808:b7b/64
otbr-agent[26928]: 00:08:15.352 [N] Cli-----------: Output: Done
otbr-agent[26928]: 00:08:15.355 [W] Platform------: Failed to write CLI output: Broken pipe
otbr-agent[26928]: 00:08:15.359 [I] Notifier------: StateChanged (0x01001009) [Ip6+ LLAddr Ip6Mult+ NetifState]
otbr-agent[26928]: 00:08:15.360 [N] Platform------: [netif] Changing interface state to up.
otbr-agent[26928]: 00:08:15.371 [I] Platform------: [netif] Succeeded to process request#11
otbr-agent[26928]: 00:08:15.373 [I] Platform------: [netif] ADD [U] fe80:0:0:0:14d4:291b:808:b7b (already subscribed, ignored)
otbr-agent[26928]: 00:08:15.374 [I] Platform------: [netif] Host netif is up
otbr-agent[26928]: 00:08:15.386 [N] Platform------: [netif] Message dropped by Thread
otbr-agent[26928]: 00:08:15.389 [I] Platform------: [netif] DEL [M] ff32:40:fdb1:7957:de2c:0:0:1 (not found, ignored)
otbr-agent[26928]: 00:08:15.391 [I] Platform------: [netif] DEL [M] ff33:40:fdb1:7957:de2c:0:0:1 (not found, ignored)
otbr-agent[26928]: 00:08:15.392 [I] Platform------: [netif] DEL [M] ff03:0:0:0:0:0:0:2 (not found, ignored)otbr-agent[26928]: 00:08:15.394 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[26928]: 00:08:15.395 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[26928]: 00:08:15.397 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16
otbr-agent[26928]: 00:08:15.398 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[26928]: 00:08:15.400 [I] Notifier------: StateChanged (0x00001000) [Ip6Mult+]
otbr-agent[26928]: 00:08:15.416 [N] Platform------: [netif] Message dropped by Thread
otbr-agent[26928]: 00:08:15.419 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[26928]: 00:08:15.420 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[26928]: 00:08:15.422 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[26928]: 00:08:15.423 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[26928]: 00:08:16.026 [N] Platform------: [netif] Message dropped by Thread
otbr-agent[26928]: 00:08:16.029 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[26928]: 00:08:16.031 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[26928]: 00:08:16.032 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[26928]: 00:08:16.034 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
mrninhvn commented 2 years ago

Update my test status, maybe it will help.

I think maybe the cause of the problem is missing mdns, not sure.

image

caipiblack commented 2 years ago

Update:

I have set some configurations so at this point the ping is working (from ot-ctl and from linux ip stack):

echo "0" > /proc/sys/net/ipv6/conf/all/disable_ipv6
echo "2" > /proc/sys/net/ipv6/conf/all/accept_ra
echo "64" > /proc/sys/net/ipv6/conf/all/accept_ra_rt_info_max_plen
echo "1" > /proc/sys/net/ipv6/conf/all/forwarding
echo "1" > /proc/sys/net/ipv4/ip_forward

_Note: in my OpenWRT device I don't have /proc/sys/net/ipv6/conf/all/accept_ra_rt_info_max_plen so I can't set it._

New problem:

  1. The Router Advertisement packet is not transmit when we create thread network OR it does not contains fd11:22 prefix, So external device have to configure manually the route to fd11:22 prefix.
  2. When I try to discover thread devices with mDNS, It doesn't works, I think the mDNS packets are not routed to the thread network

Questions:

caipiblack commented 2 years ago

Update my test status, maybe it will help.

  • My otbr openwrt run on Xiaomi router 3.
  • After setup ipset, ifconfig up running fine, no error.
  • I can create network and add border router running on pi to it.
  • With 2 border router, I can commissioning new device to network, device can ping to fd11:22: address on pi router but can't ping to openwrt router. OpenWRT route UDP message normally. I: 27484 [DL]SRP Client was started, detected server: fd79:892c:2cf3:f167:7d3b:151a:71af:4fc3
  • When there is only openwrt router, i can ping from device to router, but can't ping from device to other wifi device and vice versa.

I think maybe the cause of the problem is missing mdns, not sure.

image

You can try this:

  1. Set this on the computer runing otbr-agent:
        echo "0" > /proc/sys/net/ipv6/conf/all/disable_ipv6
        echo "2" > /proc/sys/net/ipv6/conf/all/accept_ra
        echo "64" > /proc/sys/net/ipv6/conf/all/accept_ra_rt_info_max_plen
        echo "1" > /proc/sys/net/ipv6/conf/all/forwarding
        echo "1" > /proc/sys/net/ipv4/ip_forward

I don't realy know which one is needed or not so.. Try

  1. Create Openthread network using webpage or commandline
  2. Before playing with ping, make sure your firewall is "open"
iptables -P INPUT ACCEPT
iptables -P FORWARD ACCEPT
iptables -P OUTPUT ACCEPT
iptables -t nat -F
iptables -t mangle -F
iptables -F
iptables -X

ip6tables -P INPUT ACCEPT
ip6tables -P FORWARD ACCEPT
ip6tables -P OUTPUT ACCEPT
ip6tables -t nat -F
ip6tables -t mangle -F
ip6tables -F
ip6tables -X

This will remove all firewall rules, this is just for the experiments..

  1. Add a thread device to the thread network
  2. From the thread device, try to ping the fd11 address of the border router (ot-ctl ping fd11:22:xxxxxxxxxxxx)
  3. From the border router, try to ping the fd11 address of the device using ot-ctl ping
  4. From the border router, try to ping the fd11 address of the device using ping
  5. From another computer in the network, try to ping the fd11 address of the device using ping

At the point n°8, it's possible that you need to configure manually a route on the computer, if like me the otbr-agent doesn't transmit router advertisments packets.

Note:

mrninhvn commented 2 years ago

Also, why MDNS is disabled in OpenWRT makefile ? Can we activate it ?

@caipiblack According to @wgtdkp, it seems that mdns is really missing. https://github.com/openthread/ot-br-posix/issues/426#issuecomment-1053932873

wgtdkp commented 2 years ago

re https://github.com/openthread/ot-br-posix/issues/1250#issuecomment-1042986617, @caipiblack how do you build the OTBR? Please provide the feature flags which are enabled.

From the log, It looks like the Border Routing feature (OTBR_BORDER_ROUTING) is not enabled.

wgtdkp commented 2 years ago

@caipiblack When you run into ping issues from WiFi device to Thread end device, could you capture the traffic on both the WiFi and Thread interface (wpan0)?

caipiblack commented 2 years ago

@mrninhvn @wgtdkp : As I said here (https://github.com/openthread/ot-br-posix/issues/1250#issuecomment-1058088042) the "ping" problem was solved for me.

@mrninhvn : I put here some things you can check to solve your problem : https://github.com/openthread/ot-br-posix/issues/1250#issuecomment-1058094422

@mrninhvn: There are multiple things that can make ping problem, please check these points:

$ route -A inet6
Table de routage IPv6 du noyau
Destination                    Next Hop                   Flag Met Ref Use If
ip6-localhost/128              [::]                       U    256 2     0 lo
64:ff9b::/96                   [::]                       U    1024 1     0 nat64
fd11:22::/64                   5670-generic.home          UG   100 1     0 enp1s0
mathieu-PC-Portable/128        [::]                       U    256 2     0 nat64
fe80::/64                      [::]                       U    100 2     0 enp1s0
fe80::/64                      [::]                       U    256 1     0 nat64
fe80::/64                      [::]                       U    600 1     0 wlp0s20f3
[::]/0                         [::]                       !n   -1  1     0 lo
ip6-localhost/128              [::]                       Un   0   7     0 lo
mathieu-PC-Portable/128        [::]                       Un   0   2     0 nat64
fe80::/128                     [::]                       Un   0   3     0 nat64
mathieu-PC-Portable/128        [::]                       Un   0   3     0 enp1s0
mathieu-PC-Portable/128        [::]                       Un   0   4     0 wlp0s20f3
mathieu-PC-Portable/128        [::]                       Un   0   2     0 nat64
ip6-mcastprefix/8              [::]                       U    256 11     0 wlp0s20f3
ip6-mcastprefix/8              [::]                       U    256 9     0 enp1s0
ip6-mcastprefix/8              [::]                       U    256 1     0 nat64
[::]/0                         [::]                       !n   -1  1     0 lo

NOTE: When you are using ot-br-posix on Raspberry, you don't have to set this rule because otbr-agent transmit "Router Advertisement" packet on the network after the Thread network creation. So your computers automatically add the routing rule in the routing table. The objective is that your computer know how to communicate with fd11:22::/64 network.

If you take the makefile from here it's not automatic because as @wgtdkp said OTBR_BORDER_ROUTING is not enabled.

So before I was doing the trick "manually" (route -A inet6 add fd11:22::/64 gw XXXX dev enp1s0) but now, thanks to @wgtdkp after compiling with OTBR_BORDER_ROUTING it's automatic.

@wgtdkp : Compiling otbr with OTBR_BORDER_ROUTING solved the "Router Advertisement" problem.

So at this point, my unique problem is with mDNS, but as we talk in another issue there is some work to make it working. I'm going to look it

@mrninhvn : Here is the makefile I am using. It's possible that it is not compatible with your OpenWRT version, I will try to update it later:

#
#  Copyright (c) 2020, The OpenThread Authors.
#  All rights reserved.
#
#  Redistribution and use in source and binary forms, with or without
#  modification, are permitted provided that the following conditions are met:
#  1. Redistributions of source code must retain the above copyright
#     notice, this list of conditions and the following disclaimer.
#  2. Redistributions in binary form must reproduce the above copyright
#     notice, this list of conditions and the following disclaimer in the
#     documentation and/or other materials provided with the distribution.
#  3. Neither the name of the copyright holder nor the
#     names of its contributors may be used to endorse or promote products
#     derived from this software without specific prior written permission.
#
#  THIS SOFTWARE IS PROVIDED BY THE COPYRIGHT HOLDERS AND CONTRIBUTORS "AS IS"
#  AND ANY EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT LIMITED TO, THE
#  IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS FOR A PARTICULAR PURPOSE
#  ARE DISCLAIMED. IN NO EVENT SHALL THE COPYRIGHT HOLDER OR CONTRIBUTORS BE
#  LIABLE FOR ANY DIRECT, INDIRECT, INCIDENTAL, SPECIAL, EXEMPLARY, OR
#  CONSEQUENTIAL DAMAGES (INCLUDING, BUT NOT LIMITED TO, PROCUREMENT OF
#  SUBSTITUTE GOODS OR SERVICES; LOSS OF USE, DATA, OR PROFITS; OR BUSINESS
#  INTERRUPTION) HOWEVER CAUSED AND ON ANY THEORY OF LIABILITY, WHETHER IN
#  CONTRACT, STRICT LIABILITY, OR TORT (INCLUDING NEGLIGENCE OR OTHERWISE)
#  ARISING IN ANY WAY OUT OF THE USE OF THIS SOFTWARE, EVEN IF ADVISED OF THE
#  POSSIBILITY OF SUCH DAMAGE.
#

include $(TOPDIR)/rules.mk

PKG_NAME:=openthread-br
PKG_LICENSE:=BSD-3-Clause
PKG_LICENSE_FILES:=LICENSE
PKG_RELEASE:=1
PKG_VERSION:=1.0

PKG_SOURCE_PROTO:=git
PKG_SOURCE_URL:=https://github.com/openthread/ot-br-posix.git
PKG_SOURCE_DATE:=2022-03-01
PKG_SOURCE_VERSION:=452f1f252a3ecd9a874db91cf9043f6d3bfe3a11
PKG_MIRROR_HASH:=skip

PKG_SOURCE:=$(PKG_NAME)-$(PKG_SOURCE_DATE).tar.gz
PKG_SOURCE_SUBDIR:=$(PKG_NAME)-$(PKG_SOURCE_DATE)
PKG_BUILD_DIR:=$(BUILD_DIR)/$(PKG_NAME)-$(PKG_SOURCE_DATE)

include $(INCLUDE_DIR)/package.mk
include $(INCLUDE_DIR)/cmake.mk

# --- MCA
#CMAKE_SOURCE_DIR=$(LOCAL_SOURCE_DIR)
TARGET_CFLAGS += -I$(STAGING_DIR)/usr/include
TARGET_LDFLAGS+= -lz -Wl,-rpath-link,$(STAGING_DIR)/usr/lib
# ---
CMAKE_OPTIONS+= \
    -DBUILD_TESTING=OFF \
    -DCMAKE_INSTALL_PREFIX=/usr \
    -DOTBR_BORDER_AGENT=OFF \
    -DOT_READLINE=OFF \
    -DOTBR_OPENWRT=ON \
    -DOTBR_BORDER_ROUTING=ON \
    -DOTBR_INFRA_IF_NAME=\"BR_LAN\"

define Package/openthread-br
    SECTION:=base
    CATEGORY:=Network
    TITLE:=OpenThread Border Router
    DEPENDS:=+libstdcpp +libjson-c +libubus +libblobmsg-json +libubox
endef

define Package/openthread-br/description
    A Thread border router for POSIX-based platforms.
endef

define Package/openthread-br/install
    $(CP) ./files/* $(STAGING_DIR)/

    $(INSTALL_DIR) $(1)/usr/sbin
    $(INSTALL_BIN) $(PKG_INSTALL_DIR)/usr/sbin/otbr-agent $(1)/usr/sbin
    $(INSTALL_BIN) $(PKG_INSTALL_DIR)/usr/sbin/ot-ctl $(1)/usr/sbin

    $(INSTALL_DIR) $(1)/etc/init.d
    $(CP) ./files/etc/init.d/otbr-agent $(1)/etc/init.d

    $(INSTALL_DIR) $(1)/usr/bin
    $(CP) ./files/usr/bin/create_thread_network $(1)/usr/bin

    $(INSTALL_DIR) $(1)/usr/lib/lua/luci/controller/admin
    $(INSTALL_BIN) $(PKG_BUILD_DIR)/src/openwrt/controller/thread.lua $(1)/usr/lib/lua/luci/controller/admin

    $(INSTALL_DIR) $(1)/usr/lib/lua/luci/view
    $(CP) $(PKG_BUILD_DIR)/src/openwrt/view/admin_thread $(1)/usr/lib/lua/luci/view

    $(INSTALL_DIR) $(1)/www/luci-static/resources
    $(CP) $(PKG_BUILD_DIR)/src/openwrt/handle_error.js $(1)/www/luci-static/resources
endef

$(eval $(call BuildPackage,openthread-br))
mrninhvn commented 2 years ago

@mrninhvn : Here is the makefile I am using. It's possible that it is not compatible with your OpenWRT version, I will try to update it later:

Oh Thank you, I will try again. And i also use this border router for matter like you, can your android chip-tool commission new matter device?

caipiblack commented 2 years ago

@mrninhvn : Here is the makefile I am using. It's possible that it is not compatible with your OpenWRT version, I will try to update it later:

Oh Thank you, I will try again. And i also use this border router for matter like you, can your android chip-tool commission new matter device?

@mrninhvn : Currently NO, the begin of the commissioning is OK and the thread device join the thread network, but when we arrive at the point where the controller and the device have to meet using the network instead of BLE, the controller doesn't find the Thread device. It fails with mDNS discovery. I'm going to look it now

@mrninhvn : Can you tell me if my explanations help you to solve your ping problem ? If yes I will close this issue, we can talk about mDNS on the other issue (https://github.com/openthread/ot-br-posix/issues/426)

I'm going to look what I can do for the mDNS problem.

caipiblack commented 2 years ago

Litlle update about something that i said before,

When the thread network is created the router advertisement packet is transmit to the LAN network, so the computer correctly add a rule in IPV6 table (so Router Advertisement seems OK), but for a reason that I don't understand yet, the ping is not working.

Currently I try to ping from the computer, the IPV6 address of the otbr-agent with prefix fd11:22:: (returned by ot-ctl ipaddr)

I think this is a routing issue in the OpenWRT device

Routing table of my computer:

$ route -A inet6
Table de routage IPv6 du noyau
Destination                    Next Hop                   Flag Met Ref Use If
fd11:22::/64                   5670-generic.home          UG   100 12     0 enp1s0
fd1e:5420:e2d1::/64            [::]                       U    100 1     0 enp1s0
fd51:1843:2628::/64            [::]                       U    100 1     0 enp1s0
fe80::/64                      [::]                       U    100 3     0 enp1s0
[::]/0                         [::]                       !n   -1  1     0 lo
ip6-localhost/128              [::]                       Un   0   2     0 lo
mathieu-PC-Portable/128        [::]                       Un   0   5     0 enp1s0
mathieu-PC-Portable/128        [::]                       Un   0   2     0 enp1s0
mathieu-PC-Portable/128        [::]                       Un   0   3     0 enp1s0
mathieu-PC-Portable/128        [::]                       Un   0   2     0 enp1s0
mathieu-PC-Portable/128        [::]                       Un   0   6     0 enp1s0
ip6-mcastprefix/8              [::]                       U    256 9     0 enp1s0
ip6-mcastprefix/8              [::]                       U    256 1     0 wlp0s20f3
[::]/0                         [::]                       !n   -1  1     0 lo

As you can see, there is a rule for fd11:22::/64 prefix (from the Router Advertisement)

Result of ot-ctl ipaddr on the border router device:

# ot-ctl ipaddr
fd4f:7846:cba7:a4cb:0:ff:fe00:fc10
fd11:22:0:0:ca52:d7df:1678:e313
fd4f:7846:cba7:a4cb:0:ff:fe00:fc00
fd4f:7846:cba7:a4cb:0:ff:fe00:a400
fd4f:7846:cba7:a4cb:594c:507d:b659:5237
fe80:0:0:0:46d:78a:1c3f:e79
Done

Result of ifconfig on the border router device:

# ifconfig
BR_LAN    Link encap:Ethernet  HWaddr 10:06:45:87:63:A0  
          inet addr:192.168.4.1  Bcast:192.168.4.255  Mask:255.255.255.0
          inet6 addr: fdaa:bbcc:ddee::1/128 Scope:Global
          inet6 addr: fe80::1206:45ff:fe87:63a0/64 Scope:Link
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:11628 errors:0 dropped:3 overruns:0 frame:0
          TX packets:11725 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:1202865 (1.1 MiB)  TX bytes:2066873 (1.9 MiB)

eth0      Link encap:Ethernet  HWaddr 10:06:45:87:63:A0  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

eth1      Link encap:Ethernet  HWaddr 10:06:45:87:63:A0  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

eth2      Link encap:Ethernet  HWaddr 10:06:45:87:63:A0  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

eth3      Link encap:Ethernet  HWaddr 10:06:45:87:63:A0  
          inet6 addr: fe80::1206:45ff:fe87:63a0/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:7783 errors:0 dropped:0 overruns:0 frame:0
          TX packets:6264 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:680891 (664.9 KiB)  TX bytes:1754213 (1.6 MiB)

eth3.u    Link encap:Ethernet  HWaddr 10:06:45:87:63:A0  
          inet6 addr: fe80::1206:45ff:fe87:63a0/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:7775 errors:0 dropped:0 overruns:0 frame:0
          TX packets:6069 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:512289 (500.2 KiB)  TX bytes:1709567 (1.6 MiB)

eth4      Link encap:Ethernet  HWaddr 10:06:45:87:63:9A  
          inet6 addr: fe80::1206:45ff:fe87:639a/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:6418 errors:0 dropped:0 overruns:0 frame:0
          TX packets:1076 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:694451 (678.1 KiB)  TX bytes:179148 (174.9 KiB)

eth4.110  Link encap:Ethernet  HWaddr 10:06:45:87:63:99  
          inet6 addr: fe80::1206:45ff:fe87:6399/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:163 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:48924 (47.7 KiB)

eth4.111  Link encap:Ethernet  HWaddr 10:06:45:87:63:99  
          inet6 addr: fe80::1206:45ff:fe87:6399/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:163 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:48935 (47.7 KiB)

eth4.u    Link encap:Ethernet  HWaddr 10:06:45:87:63:9A  
          inet addr:192.168.20.23  Bcast:192.168.20.255  Mask:255.255.255.0
          inet6 addr: fe80::1206:45ff:fe87:639a/64 Scope:Link
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:6413 errors:0 dropped:33 overruns:0 frame:0
          TX packets:949 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:577979 (564.4 KiB)  TX bytes:99107 (96.7 KiB)

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:53784 errors:0 dropped:0 overruns:0 frame:0
          TX packets:53784 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:13439275 (12.8 MiB)  TX bytes:13439275 (12.8 MiB)

wl0       Link encap:Ethernet  HWaddr 10:06:45:87:63:9E  
          inet6 addr: fe80::1206:45ff:fe87:639e/64 Scope:Link
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:135 overruns:0 frame:0
          TX packets:856 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:213532 (208.5 KiB)

wl1       Link encap:Ethernet  HWaddr 10:06:45:87:63:9D  
          inet6 addr: fe80::1206:45ff:fe87:639d/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:13 overruns:0 frame:233845
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)
          Interrupt:73 

wl1.1     Link encap:Ethernet  HWaddr 12:06:45:87:63:9E  
          inet6 addr: fe80::1006:45ff:fe87:639e/64 Scope:Link
          UP BROADCAST RUNNING ALLMULTI MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:11 overruns:0 frame:233845
          TX packets:876 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:234294 (228.8 KiB)

wpan0     Link encap:UNSPEC  HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00  
          inet6 addr: fd4f:7846:cba7:a4cb:594c:507d:b659:5237/64 Scope:Global
          inet6 addr: fd11:22::ca52:d7df:1678:e313/64 Scope:Global
          inet6 addr: fd4f:7846:cba7:a4cb:0:ff:fe00:fc00/64 Scope:Global
          inet6 addr: fd4f:7846:cba7:a4cb:0:ff:fe00:a400/64 Scope:Global
          inet6 addr: fe80::46d:78a:1c3f:e79/64 Scope:Link
          inet6 addr: fd4f:7846:cba7:a4cb:0:ff:fe00:fc10/64 Scope:Global
          UP POINTOPOINT RUNNING NOARP MULTICAST  MTU:1280  Metric:1
          RX packets:0 errors:0 dropped:1 overruns:0 frame:0
          TX packets:41 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:500 
          RX bytes:0 (0.0 B)  TX bytes:9348 (9.1 KiB)

Result of the ping:

ping6 fd11:22:0:0:ca52:d7df:1678:e313 -I enp1s0

This is timeout

The logs from otbr-agent:

otbr-agent[18052]: [INFO]-UTILS---: Running 0.3.0-4e31c3c-dirty
otbr-agent[18052]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[18052]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[18052]: [INFO]-UTILS---: Backbone interface: BR_LAN
otbr-agent[18052]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0
otbr-agent[18052]: [INFO]-UTILS---: Radio URL: trel://BR_LAN
otbr-agent[18052]: 49d.17:36:52.624 [I] Platform------: RCP reset: RESET_POWER_ON
otbr-agent[18052]: 49d.17:36:52.679 [N] Platform------: RCP API Version: 5
otbr-agent[18052]: 00:00:00.120 [I] Settings------: Read OmrPrefix fdf3:9311:80f5:ba4d::/64
otbr-agent[18052]: 00:00:00.120 [N] BorderRouter--: Local OMR prefix: fdf3:9311:80f5:ba4d::/64 (loaded)
otbr-agent[18052]: 00:00:00.120 [I] Settings------: Read OnLinkPrefix fd99:81d6:d07a:0::/64
otbr-agent[18052]: 00:00:00.120 [N] BorderRouter--: Local on-link prefix: fd99:81d6:d07a:0::/64 (loaded)
otbr-agent[18052]: 00:00:00.120 [I] BorderRouter--: Infra interface (22) state changed: NOT RUNNING -> RUNNING
otbr-agent[18052]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[18052]: 00:00:00.120 [I] SrpServer-----: selected port 53535
otbr-agent[18052]: 00:00:00.121 [I] NetDataPublshr: Publishing DNS/SRP service unicast (ml-eid, port:53535)
otbr-agent[18052]: 00:00:00.121 [I] NetDataPublshr: DNS/SRP service - State: NoEntry -> ToAdd
otbr-agent[18052]: [INFO]-APP-----: Border router agent started.
otbr-agent[18052]: 00:00:00.121 [I] Notifier------: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
otbr-agent[18052]: [INFO]-UBUS----: Connected as 05f998b3
otbr-agent[18052]: [INFO]-UBUS----: Uloop run
otbr-agent[18052]: 00:00:00.127 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[18052]: 00:00:00.131 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[18052]: 00:00:00.135 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:00.140 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[18052]: 00:00:00.144 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[18052]: 00:00:00.158 [I] Platform------: [netif] Host netif is down
otbr-agent[18052]: 00:00:05.270 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:05.270 [I] Platform------: > factoryreset
otbr-agent[18052]: 00:00:05.270 [I] Settings------: Wiped all info
otbr-agent[18052]: [INFO]-UTILS---: Running 0.3.0-4e31c3c-dirty
otbr-agent[18052]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[18052]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[18052]: [INFO]-UTILS---: Backbone interface: BR_LAN
otbr-agent[18052]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0
otbr-agent[18052]: [INFO]-UTILS---: Radio URL: trel://BR_LAN
otbr-agent[18052]: 49d.17:36:58.069 [I] Platform------: RCP reset: RESET_POWER_ON
otbr-agent[18052]: 49d.17:36:58.124 [N] Platform------: RCP API Version: 5
otbr-agent[18052]: 00:00:00.115 [N] BorderRouter--: No valid OMR prefix found in settings, generating new one
otbr-agent[18052]: 00:00:00.116 [I] Settings------: Saved OmrPrefix fde8:3735:3999:3165::/64
otbr-agent[18052]: 00:00:00.116 [N] BorderRouter--: Local OMR prefix: fde8:3735:3999:3165::/64 (generated)
otbr-agent[18052]: 00:00:00.116 [I] Settings------: Saved OnLinkPrefix fdd9:c4c3:c7fd:0::/64
otbr-agent[18052]: 00:00:00.116 [N] BorderRouter--: Local on-link prefix: fdd9:c4c3:c7fd:0::/64 (generated)
otbr-agent[18052]: 00:00:00.116 [I] BorderRouter--: Infra interface (22) state changed: NOT RUNNING -> RUNNING
otbr-agent[18052]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[18052]: 00:00:00.117 [I] SrpServer-----: selected port 53535
otbr-agent[18052]: 00:00:00.117 [I] NetDataPublshr: Publishing DNS/SRP service unicast (ml-eid, port:53535)
otbr-agent[18052]: 00:00:00.117 [I] NetDataPublshr: DNS/SRP service - State: NoEntry -> ToAdd
otbr-agent[18052]: [INFO]-APP-----: Border router agent started.
otbr-agent[18052]: 00:00:00.117 [I] Notifier------: StateChanged (0x00038200) [NetData PanId NetName ExtPanId]
otbr-agent[18052]: [INFO]-UBUS----: Connected as d3511927
otbr-agent[18052]: [INFO]-UBUS----: Uloop run
otbr-agent[18052]: 00:00:00.125 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[18052]: 00:00:00.129 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[18052]: 00:00:00.133 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:00.137 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[18052]: 00:00:00.141 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[18052]: 00:00:00.153 [I] Platform------: [netif] Host netif is down
otbr-agent[18052]: 00:00:01.334 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.335 [I] Platform------: > dataset init new
otbr-agent[18052]: 00:00:01.354 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.354 [I] Platform------: > dataset commit active
otbr-agent[18052]: 00:00:01.355 [I] Settings------: Saved ActiveDataset
otbr-agent[18052]: 00:00:01.355 [I] DatasetLocal--: Active dataset set
otbr-agent[18052]: 00:00:01.383 [I] Notifier------: StateChanged (0x101fc100) [KeySeqCntr Channel PanId NetName ExtPanId NetworkKey PSKc SecPolicy ActDset]
otbr-agent[18052]: 00:00:01.383 [I] AnnounceSender: ChannelMask:{ 11-26 }, period:21500
otbr-agent[18052]: 00:00:01.383 [I] AnnounceSender: StartingChannel:20
otbr-agent[18052]: 00:00:01.383 [I] AnnounceSender: StartingChannel:20
otbr-agent[18052]: 00:00:01.389 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.390 [I] Platform------: > prefix add fd11:22::/64 pasor
otbr-agent[18052]: 00:00:01.396 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.396 [I] Platform------: > ifconfig up
otbr-agent[18052]: 00:00:01.413 [I] Platform------: [netif] Added multicast address ff02::1
otbr-agent[18052]: 00:00:01.413 [I] Platform------: [netif] Added multicast address ff03::1
otbr-agent[18052]: 00:00:01.413 [I] Platform------: [netif] Added multicast address ff03::fc
otbr-agent[18052]: 00:00:01.414 [I] Platform------: [netif] Sent request#1 to add fe80::46d:78a:1c3f:e79/64
otbr-agent[18052]: 00:00:01.414 [I] DnssdServer---: started: OK
otbr-agent[18052]: 00:00:01.414 [I] Notifier------: StateChanged (0x01001009) [Ip6+ LLAddr Ip6Mult+ NetifState]
otbr-agent[18052]: 00:00:01.414 [N] Platform------: [netif] Changing interface state to up.
otbr-agent[18052]: 00:00:01.418 [I] Platform------: [netif] ADD [U] fe80:0:0:0:46d:78a:1c3f:e79 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.420 [I] Platform------: [netif] Succeeded to process request#1
otbr-agent[18052]: 00:00:01.420 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:01.420 [I] Platform------: [netif] Host netif is up
otbr-agent[18052]: 00:00:01.422 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb
otbr-agent[18052]: 00:00:01.422 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.422 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.422 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16
otbr-agent[18052]: 00:00:01.422 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[18052]: 00:00:01.422 [I] Notifier------: StateChanged (0x00001000) [Ip6Mult+]
otbr-agent[18052]: 00:00:01.432 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.433 [I] Platform------: > thread start
otbr-agent[18052]: 00:00:01.433 [N] Mle-----------: Role disabled -> detached
otbr-agent[18052]: 00:00:01.434 [I] Platform------: [netif] Sent request#2 to add fd4f:7846:cba7:a4cb:594c:507d:b659:5237/64
otbr-agent[18052]: 00:00:01.434 [I] Platform------: [netif] Added multicast address ff32:40:fd4f:7846:cba7:a4cb:0:1
otbr-agent[18052]: 00:00:01.434 [I] Platform------: [netif] Added multicast address ff33:40:fd4f:7846:cba7:a4cb:0:1
otbr-agent[18052]: 00:00:01.435 [I] Mle-----------: AttachState Idle -> Start
otbr-agent[18052]: 00:00:01.435 [I] Notifier------: StateChanged (0x10001015) [Ip6+ Role MLAddr Ip6Mult+ ActDset]
otbr-agent[18052]: 00:00:01.435 [I] AnnounceSender: Stopped
otbr-agent[18052]: 00:00:01.437 [I] Platform------: [netif] ADD [U] fd4f:7846:cba7:a4cb:594c:507d:b659:5237 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.437 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:01.437 [I] Platform------: [netif] Succeeded to process request#2
otbr-agent[18052]: 00:00:01.438 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.439 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.439 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.439 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.439 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.439 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.439 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[18052]: 00:00:01.452 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.454 [I] Platform------: > netdata register
otbr-agent[18052]: 00:00:01.464 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:01.464 [I] Platform------: > dataset active -x
otbr-agent[18052]: 00:00:01.553 [N] Mle-----------: Attempt to attach - attempt 1, any-partition reattaching with Active Dataset
otbr-agent[18052]: 00:00:01.553 [I] Mle-----------: AttachState Start -> ParentReqRouters
otbr-agent[18052]: 00:00:01.553 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2)
otbr-agent[18052]: 00:00:01.553 [I] MeshForwarder-: Sent IPv6 UDP msg, len:192, chksum:e924, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:01.553 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:01.553 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:01.576 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:1ef7, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:01.576 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:01.576 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
otbr-agent[18052]: 00:00:01.748 [I] MeshForwarder-: Sent IPv6 UDP msg, len:475, chksum:812a, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:01.748 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:01.748 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:01.787 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:01.788 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.789 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.789 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.789 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.789 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.789 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:01.789 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[18052]: 00:00:01.924 [I] MeshForwarder-: Sent IPv6 UDP msg, len:475, chksum:812a, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:01.924 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:01.924 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:02.183 [I] MeshForwarder-: Sent IPv6 UDP msg, len:475, chksum:812a, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:02.183 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:02.183 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:02.243 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:02.244 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:02.245 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:02.245 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:02.245 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:02.245 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:02.246 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:02.246 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[18052]: 00:00:02.304 [I] Mle-----------: AttachState ParentReqRouters -> ParentReqReeds
otbr-agent[18052]: 00:00:02.304 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2)
otbr-agent[18052]: 00:00:02.343 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:ed89, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:02.343 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:02.343 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
otbr-agent[18052]: 00:00:02.363 [I] MeshForwarder-: Sent IPv6 UDP msg, len:341, chksum:bf6c, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:02.363 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:02.363 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:02.428 [I] MeshForwarder-: Sent IPv6 UDP msg, len:195, chksum:faac, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:02.428 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:02.428 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:02.588 [I] MeshForwarder-: Sent IPv6 UDP msg, len:192, chksum:e924, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:02.588 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:02.588 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:03.368 [I] MeshForwarder-: Sent IPv6 UDP msg, len:369, chksum:753e, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:03.368 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:03.368 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:03.433 [I] MeshForwarder-: Sent IPv6 UDP msg, len:167, chksum:65bf, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:03.433 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:03.433 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:03.555 [I] Mle-----------: AttachState ParentReqReeds -> Idle
otbr-agent[18052]: 00:00:03.555 [N] RouterTable---: Allocate router id 41
otbr-agent[18052]: 00:00:03.555 [N] Mle-----------: RLOC16 fffe -> a400
otbr-agent[18052]: 00:00:03.568 [I] Platform------: [netif] Sent request#3 to add fd4f:7846:cba7:a4cb:0:ff:fe00:a400/64
otbr-agent[18052]: 00:00:03.569 [N] Mle-----------: Role detached -> leader
otbr-agent[18052]: 00:00:03.569 [I] Platform------: [netif] Sent request#4 to add fd4f:7846:cba7:a4cb:0:ff:fe00:fc00/64
otbr-agent[18052]: 00:00:03.570 [I] Platform------: [netif] Added multicast address ff02::2
otbr-agent[18052]: 00:00:03.570 [I] Platform------: [netif] Added multicast address ff03::2
otbr-agent[18052]: 00:00:03.570 [N] Mle-----------: Leader partition id 0x55ed1911
otbr-agent[18052]: 00:00:03.570 [I] Notifier------: StateChanged (0x100012a5) [Ip6+ Role Rloc+ PartitionId NetData Ip6Mult+ ActDset]
otbr-agent[18052]: 00:00:03.571 [I] Settings------: Saved NetworkInfo {rloc:0xa400, extaddr:066d078a1c3f0e79, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[18052]: 00:00:03.571 [I] Settings------: ... pid:0x55ed1911, mlecntr:0x3eb, maccntr:0x407, mliid:594c507db6595237}
otbr-agent[18052]: 00:00:03.571 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:03.572 [I] BbrLeader-----: PBBR state: None
otbr-agent[18052]: 00:00:03.572 [I] BbrLeader-----: Domain Prefix: ::/0, state: None
otbr-agent[18052]: 00:00:03.572 [I] Settings------: Re-saved NetworkInfo {rloc:0xa400, extaddr:066d078a1c3f0e79, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[18052]: 00:00:03.572 [I] Settings------: ... pid:0x55ed1911, mlecntr:0x3eb, maccntr:0x407, mliid:594c507db6595237}
otbr-agent[18052]: 00:00:03.572 [I] NetworkData---: Sent server data notification
otbr-agent[18052]: 00:00:03.573 [I] AnnounceSender: Started
otbr-agent[18052]: 00:00:03.573 [I] BorderAgent---: Border Agent start listening on port 0
otbr-agent[18052]: 00:00:03.573 [I] BorderRouter--: Border Routing manager started
otbr-agent[18052]: 00:00:03.573 [I] BorderRouter--: Start Router Solicitation, scheduled in 624 milliseconds
otbr-agent[18052]: 00:00:03.574 [I] BorderRouter--: Start evaluating routing policy, scheduled in 578 milliseconds
otbr-agent[18052]: 00:00:03.574 [I] NetDataPublshr: DNS/SRP service (state:ToAdd) in netdata - total:0, preferred:0, desired:2
otbr-agent[18052]: 00:00:03.574 [I] NetDataPublshr: DNS/SRP service - State: ToAdd -> Adding
otbr-agent[18052]: 00:00:03.574 [I] NetDataPublshr: DNS/SRP service (state:Adding) - update in 2067 msec
otbr-agent[18052]: 00:00:03.578 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[18052]: 00:00:03.583 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[18052]: 00:00:03.587 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd4f:7846:cba7:a4cb::/64 -exist` = 0
otbr-agent[18052]: 00:00:03.592 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[18052]: 00:00:03.596 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[18052]: 00:00:03.598 [I] Platform------: [netif] ADD [U] fd4f:7846:cba7:a4cb:0:ff:fe00:a400 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.599 [I] NetworkData---: Received network data registration
otbr-agent[18052]: 00:00:03.599 [I] NetworkData---: Allocated Context ID = 1
otbr-agent[18052]: 00:00:03.599 [I] NetworkData---: Sent network data registration acknowledgment
otbr-agent[18052]: 00:00:03.601 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:03.601 [I] Platform------: [netif] Succeeded to process request#3
otbr-agent[18052]: 00:00:03.603 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.603 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.604 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.604 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.604 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.604 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.604 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.605 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.605 [I] Notifier------: StateChanged (0x00000200) [NetData]
otbr-agent[18052]: 00:00:03.605 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:03.606 [I] BbrLeader-----: PBBR state: None
otbr-agent[18052]: 00:00:03.606 [I] BbrLeader-----: Domain Prefix: ::/0, state: None
otbr-agent[18052]: 00:00:03.606 [I] Settings------: Saved SlaacIidSecretKey
otbr-agent[18052]: 00:00:03.607 [I] Slaac---------: Generated and saved secret key
otbr-agent[18052]: 00:00:03.607 [I] Slaac---------: Adding address fd11:22:0:0:ca52:d7df:1678:e313
otbr-agent[18052]: 00:00:03.609 [I] Platform------: [netif] Sent request#5 to add fd11:22::ca52:d7df:1678:e313/64
otbr-agent[18052]: 00:00:03.609 [I] BorderRouter--: Start evaluating routing policy, scheduled in 579 milliseconds
otbr-agent[18052]: 00:00:03.610 [I] NetDataPublshr: DNS/SRP service (state:Adding) in netdata - total:0, preferred:0, desired:2
otbr-agent[18052]: 00:00:03.614 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[18052]: 00:00:03.618 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[18052]: 00:00:03.623 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:03.627 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd4f:7846:cba7:a4cb::/64 -exist` = 0
otbr-agent[18052]: 00:00:03.631 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:03.636 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[18052]: 00:00:03.640 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[18052]: 00:00:03.641 [I] Platform------: [netif] ADD [U] fd4f:7846:cba7:a4cb:0:ff:fe00:fc00 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.641 [I] Notifier------: StateChanged (0x00000001) [Ip6+]
otbr-agent[18052]: 00:00:03.643 [I] Platform------: [netif] Succeeded to process request#4
otbr-agent[18052]: 00:00:03.645 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.645 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.646 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.646 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.646 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.646 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.646 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.647 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.647 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:03.647 [I] Platform------: [netif] ADD [U] fd11:22:0:0:ca52:d7df:1678:e313 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.647 [I] Platform------: [netif] Succeeded to process request#5
otbr-agent[18052]: 00:00:03.668 [I] MeshForwarder-: Sent IPv6 UDP msg, len:96, chksum:6269, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:03.668 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:03.669 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:03.718 [I] MeshForwarder-: Sent IPv6 UDP msg, len:118, chksum:b389, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:03.718 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:03.718 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:03.843 [I] MeshForwarder-: Sent IPv6 UDP msg, len:299, chksum:bbb6, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:03.843 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:03.843 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:03.891 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:03.892 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.893 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.893 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.893 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.893 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.894 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.894 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.894 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:03.988 [I] MeshForwarder-: Sent IPv6 UDP msg, len:371, chksum:f213, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:03.988 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:03.988 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:04.088 [I] MeshForwarder-: Sent IPv6 UDP msg, len:306, chksum:8642, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:04.088 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:04.088 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:04.133 [I] MeshForwarder-: Sent IPv6 UDP msg, len:115, chksum:0cab, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:04.133 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:04.133 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:04.145 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:04.153 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:04.153 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:04.153 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:04.154 [I] BorderRouter--: Router advertisement scheduled in 16 seconds
otbr-agent[18052]: 00:00:04.154 [I] BorderRouter--: Start evaluating routing policy, scheduled in 16000 milliseconds
otbr-agent[18052]: 00:00:04.155 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:04.168 [I] MeshForwarder-: Sent IPv6 UDP msg, len:115, chksum:0cab, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:04.168 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:04.168 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:04.193 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:f72e, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:04.193 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:04.193 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:04.199 [I] BorderRouter--: Router solicitation times out
otbr-agent[18052]: 00:00:04.199 [I] BorderRouter--: Received Router Solicitation from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:04.199 [I] BorderRouter--: Start evaluating routing policy, scheduled in 141 milliseconds
otbr-agent[18052]: 00:00:04.201 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:04.293 [I] MeshForwarder-: Sent IPv6 UDP msg, len:115, chksum:0cab, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:04.293 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:04.293 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:04.341 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:04.341 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:04.341 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:04.342 [I] BorderRouter--: Router advertisement scheduled in 16 seconds
otbr-agent[18052]: 00:00:04.342 [I] BorderRouter--: Start evaluating routing policy, scheduled in 16000 milliseconds
otbr-agent[18052]: 00:00:04.342 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:04.483 [I] MeshForwarder-: Sent IPv6 UDP msg, len:109, chksum:94f8, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:04.483 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:04.483 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:04.773 [I] MeshForwarder-: Sent IPv6 UDP msg, len:209, chksum:59f5, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:04.773 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:04.773 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
root@Container-OTODO:/# otbr-agent[18052]: 00:00:05.018 [I] MeshForwarder-: Sent IPv6 UDP msg, len:209, chksum:59f5, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:05.018 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:05.018 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:05.268 [I] MeshForwarder-: Sent IPv6 UDP msg, len:209, chksum:59f5, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:05.268 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:05.268 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:05.303 [I] MeshForwarder-: Sent IPv6 UDP msg, len:109, chksum:94f8, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:05.303 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:05.303 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:05.468 [I] MeshForwarder-: Sent IPv6 UDP msg, len:197, chksum:3e72, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:05.468 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:05.468 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:05.558 [I] MeshForwarder-: Sent IPv6 UDP msg, len:219, chksum:c802, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:05.558 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:05.558 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:05.642 [I] NetDataPublshr: DNS/SRP service - State: Adding -> Added
otbr-agent[18052]: 00:00:05.642 [I] SrpServer-----: start listening on port 53535
otbr-agent[18052]: 00:00:05.642 [I] NetworkData---: Sent server data notification
otbr-agent[18052]: 00:00:05.642 [I] NetworkData---: Received network data registration
otbr-agent[18052]: 00:00:05.643 [I] NetworkData---: Allocated Service ID = 0
otbr-agent[18052]: 00:00:05.643 [I] NetworkData---: Sent network data registration acknowledgment
otbr-agent[18052]: 00:00:05.643 [I] Notifier------: StateChanged (0x00000200) [NetData]
otbr-agent[18052]: 00:00:05.643 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:05.643 [I] BbrLeader-----: PBBR state: None
otbr-agent[18052]: 00:00:05.643 [I] BbrLeader-----: Domain Prefix: ::/0, state: None
otbr-agent[18052]: 00:00:05.644 [I] Platform------: [netif] Sent request#6 to add fd4f:7846:cba7:a4cb:0:ff:fe00:fc10/64
otbr-agent[18052]: 00:00:05.644 [I] BorderRouter--: Start evaluating routing policy, scheduled in 510 milliseconds
otbr-agent[18052]: 00:00:05.644 [I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2
otbr-agent[18052]: 00:00:05.648 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[18052]: 00:00:05.652 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[18052]: 00:00:05.657 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:05.661 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd4f:7846:cba7:a4cb::/64 -exist` = 0
otbr-agent[18052]: 00:00:05.665 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:05.669 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[18052]: 00:00:05.673 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[18052]: 00:00:05.674 [I] Notifier------: StateChanged (0x00000001) [Ip6+]
otbr-agent[18052]: 00:00:05.675 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:05.676 [I] Platform------: [netif] ADD [U] fd4f:7846:cba7:a4cb:0:ff:fe00:fc10 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.677 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.677 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.677 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.678 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.678 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.678 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.678 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.678 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.678 [I] Platform------: [netif] Succeeded to process request#6
otbr-agent[18052]: 00:00:05.708 [I] MeshForwarder-: Sent IPv6 UDP msg, len:145, chksum:8f93, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:05.708 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:05.708 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:05.808 [I] MeshForwarder-: Sent IPv6 UDP msg, len:219, chksum:c802, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:05.808 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:05.808 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:05.979 [I] Platform------: [netif] Message dropped by Thread
otbr-agent[18052]: 00:00:05.980 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff33:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff32:40:fd4f:7846:cba7:a4cb:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[18052]: 00:00:05.981 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored)
otbr-agent[18052]: 00:00:06.057 [I] MeshForwarder-: Sent IPv6 UDP msg, len:219, chksum:c802, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:06.058 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:06.058 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:06.155 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:06.155 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:06.155 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:06.155 [I] BorderRouter--: Router advertisement scheduled in 16 seconds
otbr-agent[18052]: 00:00:06.155 [I] BorderRouter--: Start evaluating routing policy, scheduled in 16000 milliseconds
otbr-agent[18052]: 00:00:06.156 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:06.297 [I] MeshForwarder-: Sent IPv6 UDP msg, len:314, chksum:e551, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:06.298 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:06.298 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:06.467 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:06.490 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:0cc9, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:06.491 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:06.491 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:06.517 [I] MeshForwarder-: Sent IPv6 UDP msg, len:169, chksum:3785, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:06.518 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:06.518 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:07.342 [I] MeshForwarder-: Sent IPv6 UDP msg, len:300, chksum:77d5, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:07.343 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:07.343 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:07.377 [I] MeshForwarder-: Sent IPv6 UDP msg, len:108, chksum:297e, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:07.378 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:07.378 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:07.558 [I] MeshForwarder-: Sent IPv6 UDP msg, len:109, chksum:94f8, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:07.558 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:07.558 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:08.200 [I] BorderRouter--: Router solicitation times out
otbr-agent[18052]: 00:00:08.200 [I] BorderRouter--: Received Router Solicitation from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:08.202 [I] BorderRouter--: Start evaluating routing policy, scheduled in 476 milliseconds
otbr-agent[18052]: 00:00:08.202 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:08.537 [I] MeshForwarder-: Sent IPv6 UDP msg, len:197, chksum:3e72, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:08.538 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:08.538 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:08.679 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:08.679 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:08.679 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:08.679 [I] BorderRouter--: Router advertisement scheduled in 252 seconds
otbr-agent[18052]: 00:00:08.679 [I] BorderRouter--: Start evaluating routing policy, scheduled in 252000 milliseconds
otbr-agent[18052]: 00:00:08.680 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:09.362 [I] MeshForwarder-: Sent IPv6 UDP msg, len:280, chksum:798c, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:09.363 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:09.363 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:09.422 [I] MeshForwarder-: Sent IPv6 UDP msg, len:146, chksum:70e2, to:0xffff, sec:yes, prio:low
otbr-agent[18052]: 00:00:09.423 [I] MeshForwarder-:     src:[fd4f:7846:cba7:a4cb:594c:507d:b659:5237]:5353
otbr-agent[18052]: 00:00:09.423 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:fb]:5353
otbr-agent[18052]: 00:00:09.774 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:09.797 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:ccde, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:09.798 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:09.798 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:12.201 [I] BorderRouter--: Router solicitation times out
otbr-agent[18052]: 00:00:12.202 [I] BorderRouter--: Received Router Solicitation from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:12.202 [I] BorderRouter--: Start evaluating routing policy, scheduled in 318 milliseconds
otbr-agent[18052]: 00:00:12.202 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:12.521 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:12.521 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:12.521 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:12.522 [I] BorderRouter--: Router advertisement scheduled in 359 seconds
otbr-agent[18052]: 00:00:12.522 [I] BorderRouter--: Start evaluating routing policy, scheduled in 359000 milliseconds
otbr-agent[18052]: 00:00:12.522 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:13.200 [I] BorderRouter--: Vicarious router solicitation time out
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Router solicitation times out
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Start evaluating routing policy, scheduled in 0 milliseconds
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:13.203 [I] NetworkData---: Sent server data notification
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Adding external route fdd9:c4c3:c7fd:0::/64
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Start advertising new on-link prefix fdd9:c4c3:c7fd:0::/64 on interface 22
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Send on-link prefix fdd9:c4c3:c7fd:0::/64 in PIO (preferred lifetime = 1800 seconds, valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:13.203 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:13.204 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:13.204 [I] BorderRouter--: Router advertisement scheduled in 322 seconds
otbr-agent[18052]: 00:00:13.204 [I] BorderRouter--: Start evaluating routing policy, scheduled in 322000 milliseconds
otbr-agent[18052]: 00:00:13.204 [I] NetworkData---: Received network data registration
otbr-agent[18052]: 00:00:13.204 [I] NetworkData---: Sent network data registration acknowledgment
otbr-agent[18052]: 00:00:13.205 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:13.207 [I] Notifier------: StateChanged (0x00000200) [NetData]
otbr-agent[18052]: 00:00:13.207 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:13.207 [I] BbrLeader-----: PBBR state: None
otbr-agent[18052]: 00:00:13.207 [I] BbrLeader-----: Domain Prefix: ::/0, state: None
otbr-agent[18052]: 00:00:13.207 [I] BorderRouter--: Start evaluating routing policy, scheduled in 653 milliseconds
otbr-agent[18052]: 00:00:13.207 [I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2
otbr-agent[18052]: 00:00:13.211 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr-agent[18052]: 00:00:13.216 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr-agent[18052]: 00:00:13.221 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:13.225 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd4f:7846:cba7:a4cb::/64 -exist` = 0
otbr-agent[18052]: 00:00:13.229 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fd11:22:0:0::/64 -exist` = 0
otbr-agent[18052]: 00:00:13.233 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr-agent[18052]: 00:00:13.238 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr-agent[18052]: 00:00:13.297 [I] MeshForwarder-: Sent IPv6 UDP msg, len:162, chksum:6176, to:0xffff, sec:yes, prio:net
otbr-agent[18052]: 00:00:13.297 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:13.298 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:13.861 [I] BorderRouter--: Evaluating routing policy
otbr-agent[18052]: 00:00:13.861 [I] BorderRouter--: Send on-link prefix fdd9:c4c3:c7fd:0::/64 in PIO (preferred lifetime = 1800 seconds, valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:13.861 [I] BorderRouter--: Send OMR prefix fd11:22:0:0::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[18052]: 00:00:13.861 [I] BorderRouter--: Sent Router Advertisement on interface 22
otbr-agent[18052]: 00:00:13.862 [I] BorderRouter--: Router advertisement scheduled in 358 seconds
otbr-agent[18052]: 00:00:13.862 [I] BorderRouter--: Start evaluating routing policy, scheduled in 358000 milliseconds
otbr-agent[18052]: 00:00:13.862 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:00:15.226 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:15.252 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:a3e9, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:15.252 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:15.252 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:25.173 [I] Platform------: Session socket is ready
otbr-agent[18052]: 00:00:25.174 [I] Platform------: > ipaddr
otbr-agent[18052]: 00:00:30.486 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:00:30.511 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:dbb2, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:00:30.512 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:00:30.512 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:00:44.130 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:01:06.141 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:01:06.165 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:a036, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:01:06.165 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:01:06.165 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:01:14.731 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:01:29.378 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:01:29.403 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:3674, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:01:29.404 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:01:29.404 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:01:33.378 [I] Mac-----------: Received Beacon Request
otbr-agent[18052]: 00:01:33.379 [I] Mac-----------: Sending Beacon, name:OpenThread-b481, xpanid:60560df763f8c405, id:3, ver:2, joinable:no, native:no
otbr-agent[18052]: 00:01:33.388 [I] Mac-----------: Frame rx failed, error:UnknownNeighbor, len:28, seqnum:129, type:Beacon, src:0xf819, dst:None, sec:no, ackreq:no
otbr-agent[18052]: 00:01:33.389 [I] Mac-----------: Frame rx failed, error:UnknownNeighbor, len:28, seqnum:222, type:Beacon, src:0x0000, dst:None, sec:no, ackreq:no
otbr-agent[18052]: 00:01:33.393 [I] Mac-----------: Frame rx failed, error:UnknownNeighbor, len:28, seqnum:127, type:Beacon, src:0x0000, dst:None, sec:no, ackreq:no
otbr-agent[18052]: 00:01:33.458 [I] Mac-----------: Frame rx failed, error:UnknownNeighbor, len:28, seqnum:69, type:Beacon, src:0x06ba, dst:None, sec:no, ackreq:no
otbr-agent[18052]: 00:01:58.464 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:02:03.052 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:02:03.077 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:7eda, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:02:03.077 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:02:03.077 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[18052]: 00:02:30.356 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:1206:45ff:fe87:63a0 on interface 22
otbr-agent[18052]: 00:02:42.186 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[18052]: 00:02:42.210 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:c681, to:0xffff, sec:no, prio:net
otbr-agent[18052]: 00:02:42.210 [I] MeshForwarder-:     src:[fe80:0:0:0:46d:78a:1c3f:e79]:19788
otbr-agent[18052]: 00:02:42.210 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
wgtdkp commented 2 years ago

@caipiblack

  1. Could you show the output of command ip6tables -vL on your Border Router?
  2. Could you confirm that forwarding is enabled for both ipv6 and ipv4?
  3. Where comes the fdaa:bbcc:ddee::1/128 Scope:Global address of the BR_LAN interface? Did you manually add it? Could you show me the output of ifconfig on your computer where you initiated the pings?
  4. There is a log (otbr-agent[18052]: 00:00:13.861 [I] BorderRouter--: Send on-link prefix fdd9:c4c3:c7fd:0::/64 in PIO (preferred lifetime = 1800 seconds, valid lifetime = 1800 seconds)) shows that a on-link prefix is being advertised on the infrastructure link but I didn't see any related addresses are added to the BR_LAN interface. Could you show me the output of ifconfig on both your computer and Border Router 20 seconds after starting otbr-agent?
caipiblack commented 2 years ago

@wgtdkp :

Thanks for your help.

  1. You can find here the result of ip6tables -vL: iptables6.txt

  2. I enable the forwarding like this, is there something else to do ?

    echo "0" > /proc/sys/net/ipv6/conf/all/disable_ipv6
    echo "1" > /proc/sys/net/ipv6/conf/all/forwarding
    echo "1" > /proc/sys/net/ipv4/ip_forward
  3. This IP is on my BR_LAN interface when I start the router, it's not me who configure it. It probably comes with the OpenWRT image (it's another company who build it). If I remove this IPV6 it doesn't help. Ifconfig of the computer: pc_ifconfig.md

  4. Here you can fin ifconfig result of the border router:

This is how I start otbr-agent:

ln -s /var/run/ /run
ln -s /etc /var/lib
/usr/sbin/otbr-agent -I wpan0 -B BR_LAN spinel+hdlc+uart:///dev/ttyACM0 trel://BR_LAN -v

This is how I create the network:

#!/bin/sh
ot-ctl factoryreset
sleep 1.5
ot-ctl dataset init new
ot-ctl dataset commit active
ot-ctl prefix add fd11:22::/64 pasor
ot-ctl ifconfig up
ot-ctl thread start
ot-ctl netdata register

dataset="$(ot-ctl dataset active -x | head -n 1)"
echo "Thread dataset: $dataset"

Here the startup logs of the daemon: logs_network_creation.txt

And yeah, I don't see any IP added to BR_LAN

wgtdkp commented 2 years ago

@caipiblack Looks like OTBR firewall feature is enabled, can you try re-building with -DOT_FIREWALL=OFF?

caipiblack commented 2 years ago

@wgtdkp : unfortunately it is still not working. As before, the computer update it's routing table after receiving the RA packet, but when I ping the fd11:22:xxxx adress from the computer it doesn't works. I see the ICMP packets on the BR_LAN interface but I don't see responses and there is nothing in wpan0

Here are the build options:

CMAKE_OPTIONS+= \
    -DBUILD_TESTING=OFF \
    -DCMAKE_INSTALL_PREFIX=/usr \
    -DOTBR_BORDER_AGENT=OFF \
    -DOT_READLINE=OFF \
    -DOTBR_OPENWRT=ON \
    -DOTBR_BORDER_ROUTING=ON \
    -DOT_FIREWALL=OFF
    # \
    #-DOTBR_MDNS=mDNSResponder \
    #-DOTBR_DNSSD_DISCOVERY_PROXY=ON \
    #-DOTBR_SRP_ADVERTISING_PROXY=ON \
    #-DOTBR_VENDOR_NAME="OpenThread" \
    #-DOTBR_PRODUCT_NAME="Border Router" \
    #-DOTBR_MESHCOP_SERVICE_INSTANCE_NAME="OpenThread Border Router"

Is there something missing in the options ?

wgtdkp commented 2 years ago

echo "2" > /proc/sys/net/ipv6/conf/all/accept_ra echo "64" > /proc/sys/net/ipv6/conf/all/accept_ra_rt_info_max_plen

I think you need to use specific interface for the two configurations:

echo "2" > /proc/sys/net/ipv6/conf/BR_LAN/accept_ra
echo "64" > /proc/sys/net/ipv6/conf/BR_LAN/accept_ra_rt_info_max_plen
caipiblack commented 2 years ago

@wgtdkp : I already try it :(

@wgtdkp : But I have good and bad news.

I try to compile ot-br-posix on a fresh OpenWRT running on Raspberry PI and I don't have the ping problem.

Working things:

NOTE: it doesn't resolve mDNS issues. I will see this later.

So currently I have to understand why on the Raspberry PI it works and not in my router but I think it is a system issue (firewall, forwarding, routing table...?), (not directly related to ot-br-posix ?)

Also I start writing markdown file to explain how to use otbr-agent on OpenWRT. I will update the document and Makefile as long as I integrate OpenThread in my device.

This is a draft:

border-router.zip otbr-openwrt-rpi.md

@mrninhvn You can try the instructions and my Makefile.

@wgtdkp : When otbr-agent is running on the raspberry pi, I see that my computer have a lot of "IPV6" in the interfaces and when it is running on a real router my interface doesn't have all these ip's. I have to compare the router advertisements packets with wireshark..

wgtdkp commented 2 years ago

@caipiblack yeah, this seems to be a OpenWRT version issue :)

mrninhvn commented 2 years ago

@caipiblack I did some test with my router.

Working things:

NOT Working things:

Here is my Makefile: https://github.com/mrninhvn/ot-br-posix/blob/main/etc/openwrt/openthread-br/Makefile

otbr-agent log looks correct, maybe I misconfigured br-lan:

otbr-agent[10276]: 00:45:46.466 [I] BorderRouter--: Send OMR prefix fd56:b05a:bec7:3fc3::/64 in RIO (valid lifetime = 1800 seconds)
otbr-agent[10276]: 00:45:46.470 [I] BorderRouter--: Sent Router Advertisement on interface 17
otbr-agent[10276]: 00:45:46.471 [I] BorderRouter--: Router advertisement scheduled in 431 seconds
otbr-agent[10276]: 00:45:46.472 [I] BorderRouter--: Start evaluating routing policy, scheduled in 431000 milliseconds
otbr-agent[10276]: 00:45:46.473 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:286c:7ff:fe9d:53b7 on interface 17
otbr-agent[10276]: 00:45:58.507 [I] Mle-----------: Send Announce on channel 24
otbr-agent[10276]: 00:45:58.513 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:d37b, to:0xffff, sec:yes, prio:net
otbr-agent[10276]: 00:45:58.514 [I] MeshForwarder-:     src:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:45:58.515 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[10276]: 00:46:17.961 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[10276]: 00:46:17.968 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:bba2, to:0xffff, sec:no, prio:net
otbr-agent[10276]: 00:46:17.969 [I] MeshForwarder-:     src:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:46:17.970 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[10276]: 00:46:19.617 [I] Mle-----------: Send Announce on channel 25
otbr-agent[10276]: 00:46:19.624 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:8418, to:0xffff, sec:yes, prio:net
otbr-agent[10276]: 00:46:19.625 [I] MeshForwarder-:     src:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:46:19.626 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[10276]: 00:46:41.059 [I] Mle-----------: Send Announce on channel 26
otbr-agent[10276]: 00:46:41.065 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:6233, to:0xffff, sec:yes, prio:net
otbr-agent[10276]: 00:46:41.066 [I] MeshForwarder-:     src:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:46:41.067 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[10276]: 00:46:55.133 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
otbr-agent[10276]: 00:46:55.139 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:7cdc, to:0xffff, sec:no, prio:net
otbr-agent[10276]: 00:46:55.139 [I] MeshForwarder-:     src:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:46:55.139 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
otbr-agent[10276]: 00:46:57.805 [I] MeshForwarder-: Received IPv6 UDP msg, len:107, chksum:fb5e, from:4aa7159c8ee45160, sec:no, prio:net, rss:-71.0
otbr-agent[10276]: 00:46:57.805 [I] MeshForwarder-:     src:[fe80:0:0:0:48a7:159c:8ee4:5160]:19788
otbr-agent[10276]: 00:46:57.806 [I] MeshForwarder-:     dst:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:46:57.808 [I] Mle-----------: Receive Child Update Request from child (fe80:0:0:0:48a7:159c:8ee4:5160)
otbr-agent[10276]: 00:46:57.809 [I] Mle-----------: Child 0x3003 IPv6 address[1]=fd36:c8ec:ac8:7b0:f614:f6fe:6131:86ee
otbr-agent[10276]: 00:46:57.810 [I] Mle-----------: Child 0x3003 IPv6 address[2]=fd56:b05a:bec7:3fc3:d9a7:870f:2b58:26f5
otbr-agent[10276]: 00:46:57.811 [I] Mle-----------: Child 0x3003 has 2 registered IPv6 addresses, 2 addresses stored
otbr-agent[10276]: 00:46:57.812 [I] Mle-----------: Send Child Update Response to child (fe80:0:0:0:48a7:159c:8ee4:5160,0x3003)
otbr-agent[10276]: 00:46:57.822 [I] MeshForwarder-: Sent IPv6 UDP msg, len:98, chksum:173d, to:4aa7159c8ee45160, sec:no, prio:net
otbr-agent[10276]: 00:46:57.823 [I] MeshForwarder-:     src:[fe80:0:0:0:f4b2:5d82:a6a3:6be5]:19788
otbr-agent[10276]: 00:46:57.824 [I] MeshForwarder-:     dst:[fe80:0:0:0:48a7:159c:8ee4:5160]:19788

Command to run otbr: /usr/sbin/otbr-agent -I wpan0 -B br-lan spinel+hdlc+uart:///dev/ttyACM0 trel://br-lan -v My router connect to main router via wan port. ifconfig:

root@X-WRT:~# ifconfig
br-lan    Link encap:Ethernet  HWaddr 2A:6C:07:9D:53:B7
          inet addr:192.168.15.1  Bcast:192.168.15.255  Mask:255.255.255.0
          inet6 addr: fd09:2f8a:daec:0:286c:7ff:fe9d:53b7/64 Scope:Global
          inet6 addr: fe80::286c:7ff:fe9d:53b7/64 Scope:Link
          inet6 addr: fd23:5da7:eb50::1/60 Scope:Global
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:4129 errors:0 dropped:0 overruns:0 frame:0
          TX packets:5156 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:392506 (383.3 KiB)  TX bytes:1995076 (1.9 MiB)

br-lan:1  Link encap:Ethernet  HWaddr 2A:6C:07:9D:53:B7
          inet addr:169.254.254.254  Bcast:169.254.255.255  Mask:255.255.0.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1

eth0      Link encap:Ethernet  HWaddr 28:6C:07:9D:53:B7
          inet6 addr: fe80::2a6c:7ff:fe9d:53b7/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:81401 errors:0 dropped:0 overruns:0 frame:0
          TX packets:24843 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:24484213 (23.3 MiB)  TX bytes:9676646 (9.2 MiB)
          Interrupt:5

eth0.1    Link encap:Ethernet  HWaddr 2A:6C:07:9D:53:B7
          inet6 addr: fe80::286c:7ff:fe9d:53b7/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:7140 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:0 (0.0 B)  TX bytes:2070839 (1.9 MiB)

eth0.2    Link encap:Ethernet  HWaddr 28:6C:07:9D:53:B7
          inet addr:192.168.1.18  Bcast:192.168.1.255  Mask:255.255.255.0
          inet6 addr: fe80::2a6c:7ff:fe9d:53b7/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:80988 errors:0 dropped:0 overruns:0 frame:0
          TX packets:15578 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:22934656 (21.8 MiB)  TX bytes:7339317 (6.9 MiB)

lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:2277 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2277 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:183868 (179.5 KiB)  TX bytes:183868 (179.5 KiB)

wlan0     Link encap:Ethernet  HWaddr 28:6C:07:9D:53:B9
          inet6 addr: fe80::2a6c:7ff:fe9d:53b9/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:6486 errors:0 dropped:0 overruns:0 frame:0
          TX packets:10306 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:1151778 (1.0 MiB)  TX bytes:3960458 (3.7 MiB)

wlan1     Link encap:Ethernet  HWaddr 28:6C:07:9D:53:B8
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

wlan1-1   Link encap:Ethernet  HWaddr 28:6C:07:9D:53:B9
          inet6 addr: fe80::2a6c:7ff:fe9d:53b9/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:7034 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:0 (0.0 B)  TX bytes:2186715 (2.0 MiB)

wpan0     Link encap:UNSPEC  HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00
          inet6 addr: fd56:b05a:bec7:3fc3:6fb:6a12:e8a1:ff31/64 Scope:Global
          inet6 addr: fd36:c8ec:ac8:7b0:7c72:b56b:524a:1bad/64 Scope:Global
          inet6 addr: fd36:c8ec:ac8:7b0:0:ff:fe00:fc00/64 Scope:Global
          inet6 addr: fd36:c8ec:ac8:7b0:0:ff:fe00:3000/64 Scope:Global
          inet6 addr: fe80::f4b2:5d82:a6a3:6be5/64 Scope:Link
          UP POINTOPOINT RUNNING NOARP MULTICAST  MTU:1280  Metric:1
          RX packets:57 errors:0 dropped:1 overruns:0 frame:0
          TX packets:66 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:500
          RX bytes:5688 (5.5 KiB)  TX bytes:7116 (6.9 KiB)
mrninhvn commented 2 years ago

I have Google Nest Wifi, It has thread inside and has the same problem as ours.

caipiblack commented 2 years ago

@mrninhvn : The Matter pairing is now working for me:

Here is my new makefile:

#
#  Copyright (c) 2020, The OpenThread Authors.
#  All rights reserved.
#
#  Redistribution and use in source and binary forms, with or without
#  modification, are permitted provided that the following conditions are met:
#  1. Redistributions of source code must retain the above copyright
#     notice, this list of conditions and the following disclaimer.
#  2. Redistributions in binary form must reproduce the above copyright
#     notice, this list of conditions and the following disclaimer in the
#     documentation and/or other materials provided with the distribution.
#  3. Neither the name of the copyright holder nor the
#     names of its contributors may be used to endorse or promote products
#     derived from this software without specific prior written permission.
#
#  THIS SOFTWARE IS PROVIDED BY THE COPYRIGHT HOLDERS AND CONTRIBUTORS "AS IS"
#  AND ANY EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT LIMITED TO, THE
#  IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS FOR A PARTICULAR PURPOSE
#  ARE DISCLAIMED. IN NO EVENT SHALL THE COPYRIGHT HOLDER OR CONTRIBUTORS BE
#  LIABLE FOR ANY DIRECT, INDIRECT, INCIDENTAL, SPECIAL, EXEMPLARY, OR
#  CONSEQUENTIAL DAMAGES (INCLUDING, BUT NOT LIMITED TO, PROCUREMENT OF
#  SUBSTITUTE GOODS OR SERVICES; LOSS OF USE, DATA, OR PROFITS; OR BUSINESS
#  INTERRUPTION) HOWEVER CAUSED AND ON ANY THEORY OF LIABILITY, WHETHER IN
#  CONTRACT, STRICT LIABILITY, OR TORT (INCLUDING NEGLIGENCE OR OTHERWISE)
#  ARISING IN ANY WAY OUT OF THE USE OF THIS SOFTWARE, EVEN IF ADVISED OF THE
#  POSSIBILITY OF SUCH DAMAGE.
#

include $(TOPDIR)/rules.mk

PKG_NAME:=openthread-br
PKG_LICENSE:=BSD-3-Clause
PKG_LICENSE_FILES:=LICENSE
PKG_RELEASE:=1
PKG_VERSION:=1.0

PKG_SOURCE_PROTO:=git
PKG_SOURCE_URL:=https://github.com/openthread/ot-br-posix.git
PKG_SOURCE_DATE:=2022-03-01
PKG_SOURCE_VERSION:=452f1f252a3ecd9a874db91cf9043f6d3bfe3a11
PKG_MIRROR_HASH:=skip

PKG_SOURCE:=$(PKG_NAME)-$(PKG_SOURCE_DATE).tar.gz
PKG_SOURCE_SUBDIR:=$(PKG_NAME)-$(PKG_SOURCE_DATE)
PKG_BUILD_DIR:=$(BUILD_DIR)/$(PKG_NAME)-$(PKG_SOURCE_DATE)

include $(INCLUDE_DIR)/package.mk
include $(INCLUDE_DIR)/cmake.mk

CMAKE_OPTIONS+= \
    -DBUILD_TESTING=OFF \
    -DCMAKE_INSTALL_PREFIX=/usr \
    -DOTBR_BORDER_AGENT=ON \
    -DOT_READLINE=OFF \
    -DOTBR_OPENWRT=ON \
    -DOTBR_BORDER_ROUTING=ON \
    -DOTBR_SRP_ADVERTISING_PROXY=ON \
    -DOTBR_INFRA_IF_NAME="BR_LAN" \ 
    -DOTBR_MDNS="avahi" \
    -DOTBR_VENDOR_NAME="OpenThread" \
    -DOTBR_PRODUCT_NAME="Border Router" \
    -DOTBR_MESHCOP_SERVICE_INSTANCE_NAME="OpenThread Border Router"

define Package/openthread-br
    SECTION:=base
    CATEGORY:=Network
    TITLE:=OpenThread Border Router
    DEPENDS:=+libstdcpp +libjson-c +libubus +libblobmsg-json +libubox +libavahi-client
endef

define Package/openthread-br/description
    A Thread border router for POSIX-based platforms.
endef

define Package/openthread-br/install
    $(CP) ./files/* $(STAGING_DIR)/

    $(INSTALL_DIR) $(1)/usr/sbin
    $(INSTALL_BIN) $(PKG_INSTALL_DIR)/usr/sbin/otbr-agent $(1)/usr/sbin
    $(INSTALL_BIN) $(PKG_INSTALL_DIR)/usr/sbin/ot-ctl $(1)/usr/sbin

    $(INSTALL_DIR) $(1)/etc/init.d
    $(CP) ./files/etc/init.d/otbr-agent $(1)/etc/init.d

    $(INSTALL_DIR) $(1)/usr/bin
    $(CP) ./files/usr/bin/create_thread_network $(1)/usr/bin

    $(INSTALL_DIR) $(1)/usr/lib/lua/luci/controller/admin
    $(INSTALL_BIN) $(PKG_BUILD_DIR)/src/openwrt/controller/thread.lua $(1)/usr/lib/lua/luci/controller/admin

    $(INSTALL_DIR) $(1)/usr/lib/lua/luci/view
    $(CP) $(PKG_BUILD_DIR)/src/openwrt/view/admin_thread $(1)/usr/lib/lua/luci/view

    $(INSTALL_DIR) $(1)/www/luci-static/resources
    $(CP) $(PKG_BUILD_DIR)/src/openwrt/handle_error.js $(1)/www/luci-static/resources
endef

$(eval $(call BuildPackage,openthread-br))

NOTE:

Edit: I also had to change iptables configuration.

# tcpdump -i wpan0
tcpdump: verbose output suppressed, use -v or -vv for full protocol decode
listening on wpan0, link-type LINUX_SLL (Linux cooked), capture size 262144 bytes
12:11:53.906751 IP 192.168.1.34.32900 > 224.0.0.251.5353: 0 ANY (QU)? 8411ACC577325B07-0000000000000017._matter._tcp.local. (70)
12:11:53.908151 IP6 fe80::ccd1:be7a:cc5b:1273.49712 > ff02::fb.5353: 0 ANY (QU)? 8411ACC577325B07-0000000000000017._matter._tcp.local. (70)
12:11:54.908870 IP 192.168.1.34.32900 > 224.0.0.251.5353: 0 ANY (QU)? 8411ACC577325B07-0000000000000017._matter._tcp.local. (70)
12:11:54.908931 IP6 fe80::ccd1:be7a:cc5b:1273.49712 > ff02::fb.5353: 0 ANY (QU)? 8411ACC577325B07-0000000000000017._matter._tcp.local. (70)
12:11:56.431086 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.53535 > fd55:2420:9c36:7a9f:2aad:e29d:4af5:b804.49154: UDP, length 12
12:11:56.910779 IP 192.168.1.34.32900 > 224.0.0.251.5353: 0 ANY (QU)? 8411ACC577325B07-0000000000000017._matter._tcp.local. (70)
12:11:56.910838 IP6 fe80::ccd1:be7a:cc5b:1273.49712 > ff02::fb.5353: 0 ANY (QU)? 8411ACC577325B07-0000000000000017._matter._tcp.local. (70)
12:11:58.812747 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0 [2q] [3n] ANY (QM)? 8411ACC577325B07-0000000000000017._matter._tcp.local. ANY (QM)? 6AB847B93A03CD78.local. (174)
12:11:58.944608 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0 [2n] ANY (QM)? 298180F438EFB3C1._matterc._udp.local. (160)
12:11:59.066523 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0 [2q] [3n] ANY (QM)? 8411ACC577325B07-0000000000000017._matter._tcp.local. ANY (QM)? 6AB847B93A03CD78.local. (174)
12:11:59.194611 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0 [2n] ANY (QM)? 298180F438EFB3C1._matterc._udp.local. (160)
12:11:59.316523 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0 [2q] [3n] ANY (QM)? 8411ACC577325B07-0000000000000017._matter._tcp.local. ANY (QM)? 6AB847B93A03CD78.local. (174)
12:11:59.445604 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0 [2n] ANY (QM)? 298180F438EFB3C1._matterc._udp.local. (160)
12:11:59.525296 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 5/0/0 (Cache flush) TXT "CRI=5000" "CRA=300" "T=0", PTR 8411ACC577325B07-0000000000000017._matter._tcp.local., (Cache flush) SRV 6AB847B93A03CD78.local.:5540 0 0, PTR _matter._tcp.local., PTR 8411ACC577325B07-0000000000000017._matter._tcp.local. (223)
12:11:59.531988 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.53535 > fd55:2420:9c36:7a9f:2aad:e29d:4af5:b804.49154: UDP, length 12
12:11:59.537218 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 1/0/0 (Cache flush) AAAA fd11:22::2023:d541:1e18:d874 (62)
12:11:59.647604 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.53535 > fd55:2420:9c36:7a9f:2aad:e29d:4af5:b804.49154: UDP, length 12
12:11:59.663571 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 7/0/0 (Cache flush) TXT "VP=9050+19276" "CRI=5000" "CRA=300" "T=0" "D=3840" "CM=0" "PH=33" "PI=", PTR 298180F438EFB3C1._matterc._udp.local., (Cache flush) SRV 6AB847B93A03CD78.local.:5540 0 0, PTR _matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local. (266)
12:12:00.580834 IP6 fd11:22::bfb6:208f:68d1:b56b.5542 > fd11:22::2023:d541:1e18:d874.5540: UDP, length 168
12:12:00.589524 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 5/0/0 (Cache flush) TXT "CRI=5000" "CRA=300" "T=0", (Cache flush) SRV 6AB847B93A03CD78.local.:5540 0 0, (Cache flush) AAAA fd11:22::2023:d541:1e18:d874, PTR _matter._tcp.local., PTR 8411ACC577325B07-0000000000000017._matter._tcp.local. (237)
12:12:00.638920 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 1/0/0 PTR 8411ACC577325B07-0000000000000017._matter._tcp.local. (78)
12:12:00.715406 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 7/0/0 (Cache flush) TXT "VP=9050+19276" "CRI=5000" "CRA=300" "T=0" "D=3840" "CM=0" "PH=33" "PI=", PTR 298180F438EFB3C1._matterc._udp.local., (Cache flush) SRV 6AB847B93A03CD78.local.:5540 0 0, PTR _matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local. (266)
12:12:02.645476 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 5/0/0 (Cache flush) TXT "CRI=5000" "CRA=300" "T=0", PTR _matter._tcp.local., PTR 8411ACC577325B07-0000000000000017._matter._tcp.local., (Cache flush) SRV 6AB847B93A03CD78.local.:5540 0 0, (Cache flush) AAAA fd11:22::2023:d541:1e18:d874 (237)
12:12:02.697962 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 1/0/0 PTR 8411ACC577325B07-0000000000000017._matter._tcp.local. (78)
12:12:02.777518 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 6/0/0 (Cache flush) TXT "VP=9050+19276" "CRI=5000" "CRA=300" "T=0" "D=3840" "CM=0" "PH=33" "PI=", (Cache flush) SRV 6AB847B93A03CD78.local.:5540 0 0, PTR _matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local., PTR 298180F438EFB3C1._matterc._udp.local. (252)
12:12:02.824694 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.5353 > ff02::fb.5353: 0*- [0q] 1/0/0 PTR 298180F438EFB3C1._matterc._udp.local. (62)
12:12:04.162927 IP6 fd11:22::2023:d541:1e18:d874.5540 > fd11:22::bfb6:208f:68d1:b56b.5542: UDP, length 732
12:12:04.174343 IP6 fd11:22::bfb6:208f:68d1:b56b.5542 > fd11:22::2023:d541:1e18:d874.5540: UDP, length 599
12:12:10.967738 IP6 fd11:22::2023:d541:1e18:d874.5540 > fd11:22::bfb6:208f:68d1:b56b.5542: UDP, length 26
12:12:10.969031 IP6 fd11:22::bfb6:208f:68d1:b56b.5542 > fd11:22::2023:d541:1e18:d874.5540: UDP, length 56
12:12:10.969677 IP6 fd11:22::bfb6:208f:68d1:b56b.5542 > fd11:22::2023:d541:1e18:d874.5540: UDP, length 18
12:12:11.177675 IP6 fd11:22::2023:d541:1e18:d874.5540 > fd11:22::bfb6:208f:68d1:b56b.5542: UDP, length 64
12:12:11.179926 IP6 fd11:22::bfb6:208f:68d1:b56b.5542 > fd11:22::2023:d541:1e18:d874.5540: UDP, length 34
12:12:13.536890 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.53535 > fd55:2420:9c36:7a9f:2aad:e29d:4af5:b804.49154: UDP, length 12
12:12:13.657904 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.53535 > fd55:2420:9c36:7a9f:2aad:e29d:4af5:b804.49154: UDP, length 12

Did someone know what is this packet:

12:11:56.431086 IP6 fd55:2420:9c36:7a9f:103:bf76:f26d:f8db.53535 > fd55:2420:9c36:7a9f:2aad:e29d:4af5:b804.49154: UDP, length 12

Is it mDNS, SRP or Openthread related ? Is port 53535 and 49154 fixed ?

caipiblack commented 2 years ago

I try to optimize my Makefile to bypass the need of simlinks when we start otbr-agent.

To do this I have to set these variables:

The value of OT_POSIX_SETTINGS_PATH read from CMakeCache.txt is always "/var/lib/thread".

CMakeCache.txt
649:OT_POSIX_SETTINGS_PATH:STRING="/var/lib/thread"

Here is how I try to set it:

CMAKE_OPTIONS+= \
    -DBUILD_TESTING=OFF \
    -DCMAKE_INSTALL_PREFIX=/usr \
    -DOTBR_BORDER_AGENT=ON \
    -DOT_READLINE=OFF \
    -DOTBR_OPENWRT=ON \
    -DOTBR_BORDER_ROUTING=ON \
    -DOTBR_SRP_ADVERTISING_PROXY=ON \
    -DOTBR_INFRA_IF_NAME="BR_LAN" \ 
    -DOTBR_MDNS="avahi" \
    -DOTBR_VENDOR_NAME="OpenThread" \
    -DOTBR_PRODUCT_NAME="Border Router" \
    -DOTBR_MESHCOP_SERVICE_INSTANCE_NAME="OpenThread Border Router" \
    -DOPENTHREAD_POSIX_CONFIG_DAEMON_SOCKET_BASENAME="/var/run" \
    -DOT_POSIX_SETTINGS_PATH="/etc/openthread"

I also try to set OPENTHREAD_CONFIG_POSIX_SETTINGS_PATH but it doesn't help.

Did you know why I can't set OT_POSIX_SETTINGS_PATH and why I can set OPENTHREAD_POSIX_CONFIG_DAEMON_SOCKET_BASENAME ?

mrninhvn commented 2 years ago

@caipiblack I changed this in third_party/openthread/CMakeLists.txt and it worked.

mrninhvn commented 2 years ago

Something is wrong with me, I still can't scan the router.

root@X-WRT:~# /usr/sbin/otbr-agent -I wpan0 -B br-lan spinel+hdlc+uart:///dev/ttyACM0 trel://br-lan -v
otbr-agent[14817]: [INFO]-UTILS---: Running 0.3.0-007a3d07e-dirty
otbr-agent[14817]: [INFO]-UTILS---: Thread version: 1.2.0
otbr-agent[14817]: [INFO]-UTILS---: Thread interface: wpan0
otbr-agent[14817]: [INFO]-UTILS---: Backbone interface: br-lan
otbr-agent[14817]: [INFO]-UTILS---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0
otbr-agent[14817]: [INFO]-UTILS---: Radio URL: trel://br-lan
otbr-agent[14817]: 49d.17:26:18.061 [I] Platform------: RCP reset: RESET_POWER_ON
otbr-agent[14817]: 49d.17:26:18.071 [N] Platform------: RCP API Version: 5
otbr-agent[14817]: 00:00:00.023 [I] Settings------: Read NetworkInfo {rloc:0xd400, extaddr:3607db56199a0749, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[14817]: 00:00:00.023 [I] Settings------: ... pid:0x9c9d2f9, mlecntr:0x3eb, maccntr:0x3f3, mliid:7c37e2ba31feef81}
otbr-agent[14817]: 00:00:00.030 [I] Settings------: Read OmrPrefix fdfb:b35:ad2e:86d2::/64
otbr-agent[14817]: 00:00:00.031 [I] Settings------: Read OnLinkPrefix fd8c:f3ff:2a8e:0::/64
otbr-agent[14817]: 00:00:00.033 [I] BorderRouter--: Infra interface (23) state changed: NOT RUNNING -> RUNNING
otbr-agent[14817]: [INFO]-UTILS---: Set state callback: OK
otbr-agent[14817]: 00:00:00.035 [I] SrpServer-----: selected port 53535
otbr-agent[14817]: 00:00:00.040 [I] NetDataPublshr: Publishing DNS/SRP service unicast (ml-eid, port:53535)
otbr-agent[14817]: 00:00:00.041 [I] NetDataPublshr: DNS/SRP service - State: NoEntry -> ToAdd
otbr-agent[14817]: [INFO]-APP-----: Border router agent started.
otbr-agent[14817]: 00:00:00.044 [I] Notifier------: StateChanged (0x101fc300) [KeySeqCntr NetData Channel PanId NetName ExtPanId NetworkKey PSKc SecPolicy ...
otbr-agent[14817]: 00:00:00.045 [I] Notifier------: StateChanged (0x101fc300) ... ActDset]
otbr-agent[14817]: 00:00:00.046 [I] AnnounceSender: ChannelMask:{ 11-26 }, period:21500
otbr-agent[14817]: 00:00:00.047 [I] AnnounceSender: StartingChannel:25
otbr-agent[14817]: 00:00:00.048 [I] AnnounceSender: StartingChannel:25
otbr-agent[14817]: [INFO]-UBUS----: Connected as 5b225f12
otbr-agent[14817]: [INFO]-UBUS----: Uloop run
otbr-agent[14817]: 00:00:00.057 [I] Platform------: [netif] Host netif is down
otbr-agent[14817]: 00:00:00.065 [I] Platform------: [netif] Added multicast address ff02::1
otbr-agent[14817]: 00:00:00.065 [I] Platform------: [netif] Added multicast address ff03::1
otbr-agent[14817]: 00:00:00.065 [I] Platform------: [netif] Added multicast address ff03::fc
otbr-agent[14817]: 00:00:00.066 [I] Platform------: [netif] Sent request#1 to add fe80::3407:db56:199a:749/64
otbr-agent[14817]: 00:00:00.066 [N] Mle-----------: Role disabled -> detached
otbr-agent[14817]: 00:00:00.066 [I] Platform------: [netif] Sent request#2 to add fd7c:4d67:2e2a:8366:7c37:e2ba:31fe:ef81/64
otbr-agent[14817]: 00:00:00.067 [I] Platform------: [netif] Added multicast address ff32:40:fd7c:4d67:2e2a:8366:0:1
otbr-agent[14817]: 00:00:00.067 [I] Platform------: [netif] Added multicast address ff33:40:fd7c:4d67:2e2a:8366:0:1
otbr-agent[14817]: 00:00:00.067 [I] Platform------: [netif] Sent request#3 to add fd7c:4d67:2e2a:8366:0:ff:fe00:d400/64
otbr-agent[14817]: 00:00:00.067 [I] Mle-----------: Attempt to become router
otbr-agent[14817]: 00:00:00.076 [I] Settings------: Read NetworkInfo {rloc:0xd400, extaddr:3607db56199a0749, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[14817]: 00:00:00.083 [I] Settings------: ... pid:0x9c9d2f9, mlecntr:0x3eb, maccntr:0x3f3, mliid:7c37e2ba31feef81}
otbr-agent[14817]: 00:00:00.097 [I] Settings------: Saved NetworkInfo {rloc:0xd400, extaddr:3607db56199a0749, role:leader, mode:0x0f, version:3, keyseq:0x0, ...
otbr-agent[14817]: 00:00:00.098 [I] Settings------: ... pid:0x9c9d2f9, mlecntr:0x7d4, maccntr:0x7db, mliid:7c37e2ba31feef81}
otbr-agent[14817]: 00:00:00.099 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
otbr-agent[14817]: 00:00:00.103 [I] Notifier------: StateChanged (0x0100103d) [Ip6+ Role LLAddr MLAddr Rloc+ Ip6Mult+ NetifState]
otbr-agent[14817]: 00:00:00.104 [I] AnnounceSender: Stopped
otbr-agent[14817]: 00:00:00.106 [N] Platform------: [netif] Changing interface state to up.
otbr-agent[14817]: 00:00:00.155 [N] Platform------: [netif] Message dropped by Thread
otbr-agent[14817]: 00:00:00.158 [I] Platform------: [netif] Succeeded to process request#1
otbr-agent[14817]: 00:00:00.160 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb
otbr-agent[14817]: 00:00:00.165 [I] Platform------: [netif] ADD [M] ff33:40:fd7c:4d67:2e2a:8366:0:1 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.166 [I] Platform------: [netif] ADD [M] ff32:40:fd7c:4d67:2e2a:8366:0:1 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.178 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.185 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.186 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16
otbr-agent[14817]: 00:00:00.187 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
otbr-agent[14817]: 00:00:00.189 [I] Notifier------: StateChanged (0x00001000) [Ip6Mult+]
otbr-agent[14817]: 00:00:00.192 [N] Platform------: [netif] Message dropped by Thread
otbr-agent[14817]: 00:00:00.193 [I] Platform------: [netif] Succeeded to process request#2
otbr-agent[14817]: 00:00:00.196 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:fb (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.200 [I] Platform------: [netif] ADD [M] ff33:40:fd7c:4d67:2e2a:8366:0:1 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.201 [I] Platform------: [netif] ADD [M] ff32:40:fd7c:4d67:2e2a:8366:0:1 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.202 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.208 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.218 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored)
otbr-agent[14817]: 00:00:00.220 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected)
...
otbr-agent[14817]: 00:00:07.751 [I] NetDataPublshr: DNS/SRP service - State: Adding -> Added
otbr-agent[14817]: 00:00:07.752 [I] SrpServer-----: start listening on port 53535
otbr-agent[14817]: 00:00:07.753 [I] NetworkData---: Sent server data notification
otbr-agent[14817]: 00:00:07.754 [I] NetworkData---: Received network data registration
otbr-agent[14817]: 00:00:07.755 [I] NetworkData---: Allocated Service ID = 0
otbr-agent[14817]: 00:00:07.756 [I] NetworkData---: Sent network data registration acknowledgment
otbr-agent[14817]: 00:00:07.757 [I] Notifier------: StateChanged (0x00000200) [NetData]
otbr-agent[14817]: 00:00:07.759 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
otbr-agent[14817]: 00:00:07.760 [I] BbrLeader-----: PBBR state: None
otbr-agent[14817]: 00:00:07.760 [I] BbrLeader-----: Domain Prefix: ::/0, state: None
otbr-agent[14817]: 00:00:07.765 [I] Platform------: [netif] Sent request#8 to add fd7c:4d67:2e2a:8366:0:ff:fe00:fc10/64
otbr-agent[14817]: 00:00:07.766 [I] BorderRouter--: Start evaluating routing policy, scheduled in 363 milliseconds
otbr-agent[14817]: 00:00:07.767 [I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2
otbr-agent[14817]: 00:00:07.768 [I] Notifier------: StateChanged (0x00000001) [Ip6+]
otbr-agent[14817]: 00:00:07.772 [I] Platform------: [netif] Succeeded to process request#8
caipiblack commented 2 years ago

@mrninhvn : What do you mean by “i can’t scan the router” ? Scan with what ? avahi-browse ?

mrninhvn commented 2 years ago

@mrninhvn : What do you mean by “i can’t scan the router” ? Scan with what ? avahi-browse ?

I'm using Service Browser on Android. https://play.google.com/store/apps/details?id=com.druk.servicebrowser

caipiblack commented 2 years ago

@mrninhvn : Please have a look on my makefile https://github.com/openthread/ot-br-posix/issues/1250#issuecomment-1060515773

I think you don’t enable some features.

mrninhvn commented 2 years ago

@caipiblack Thank you for your help, I/m missing -DOTBR_BORDER_AGENT=ON. Now i can scan the router, but matter device got some error when commissioning. I have configured forwarding and ip6tables, is it the problem that i put -DOT_FIREWALL=OFF?

D: 18068 [DL]OpenThread State Changed (Flags: 0x301132b7)
D: 18073 [DL]   Device Role: CHILD
D: 18076 [DL]   Network Name: Matter
D: 18080 [DL]   PAN Id: 0x1234
D: 18083 [DL]   Extended PAN Id: 0x1111111122222222
D: 18088 [DL]   Channel: 15
D: 18090 [DL]   Mesh Prefix: fd11:1111:1122::/64
D: 18095 [DL]   Partition Id: 0x7C3AC667
D: 18099 [DL]   Thread Unicast Addresses:
D: 18102 [DL]        fd35:c1b3:ee05:3172:6708:de7:1a4f:dbf6/64 valid preferred
D: 18110 [DL]        fd11:1111:1122::ff:fe00:9c01/64 valid rloc
D: 18116 [DL]        fd11:1111:1122:0:7ce5:d3d5:53bd:b033/64 valid
D: 18122 [DL]        fe80::646f:1dc7:d850:2a26/64 valid preferred
E: 18130 [DL]Long dispatch time: 102 ms, for event type 32780
D: 18138 [DL]OpenThread State Changed (Flags: 0x00000001)
D: 18143 [DL]   Thread Unicast Addresses:
D: 18147 [DL]        fd35:c1b3:ee05:3172:6708:de7:1a4f:dbf6/64 valid preferred
D: 18155 [DL]        fd11:1111:1122::ff:fe00:9c01/64 valid rloc
D: 18160 [DL]        fd11:1111:1122:0:7ce5:d3d5:53bd:b033/64 valid
D: 18166 [DL]        fe80::646f:1dc7:d850:2a26/64 valid preferred
D: 18176 [DMG]ICR moving to [AddingComm]
D: 18182 [DMG]ICR moving to [AddedComma]
D: 18186 [DL]Using Thread extended MAC for hostname.
I: 18191 [DIS]Advertise operational node 15CF4CA6C84D132B-000000000000001B
E: 18198 [SVR]Operational advertising enabled
D: 18202 [DMG]Decreasing reference count for CommandHandler, remaining 0
I: 18209 [IN]Prepared secure message 0x20028d58 to 0xFFFFFFFB00000000 (1)  of type 0x9 and protocolId (0, 1) on exchange 54085r with MessageCounter:4719209.
I: 18223 [IN]Sending encrypted msg 0x20028d58 with MessageCounter:4719209 to 0xFFFFFFFB00000000 (1) at monotonic time: 18223 msec
D: 18235 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 74)
D: 18241 [DMG]ICR moving to [CommandSen]
D: 18245 [DMG]ICR moving to [AwaitingDe]
D: 18260 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
I: 18339 [DL]OnSrpClientNotification: Last requested operation completed successfully
D: 18347 [DIS]DNS-SD StartServer modeHasValue=0 modeValue=0
D: 18352 [DL]Using Thread extended MAC for hostname.
I: 18357 [DIS]Advertise operational node 15CF4CA6C84D132B-000000000000001B
I: 18365 [DL]advertising srp service: 15CF4CA6C84D132B-000000000000001B._matter._tcp
I: 18372 [DIS]Have operational credentials
D: 18418 [DIS]GetExtendedDiscoveryTimeoutSecs 20
D: 18422 [DL]Using Thread extended MAC for hostname.
I: 18444 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15
I: 18456 [DL]advertising srp service: 980B72DEBAE2871D._matterc._udp
D: 18508 [DIS]GetExtendedDiscoveryTimeoutSecs 20
D: 18512 [DIS]Scheduling Extended Discovery timeout in secs=20
E: 18518 [DL]Long dispatch time: 171 ms, for event type 32786
I: 19750 [DL]OnSrpClientNotification: Last requested operation completed successfully
D: 20780 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 23286 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 23315 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 25835 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 28341 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 28370 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 30890 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 33396 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 33424 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 35930 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 38435 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 38464 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 38518 [DIS]OnExpiration - valid time out
D: 38522 [DL]Using Thread extended MAC for hostname.
I: 38527 [DIS]Advertise operational node 15CF4CA6C84D132B-000000000000001B
I: 38534 [DL]advertising srp service: 15CF4CA6C84D132B-000000000000001B._matter._tcp
I: 38542 [DL]removing srp service: 980B72DEBAE2871D._matterc._udp
D: 38576 [DIS]OnExtendedDiscoveryExpiration callback for valid session
I: 38923 [DL]OnSrpClientNotification: Last requested operation completed successfully
D: 40985 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 43490 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 43519 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 46040 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 48545 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 48574 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 51094 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 53601 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 53629 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 56149 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 58656 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 58684 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 61234 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 63741 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 63769 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 66304 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
D: 68811 [DL]Sending notification for CHIPoBLE TX (ConnId 00, len 3)
D: 68854 [DL]Notification for CHIPoBLE TX done (ConnId 0x00)
D: 71404 [DL]Write request received for CHIPoBLE RX (ConnId 0x00)
E: 72308 [SVR]Commissioning failed with error 32
E: 72313 [SVR]Commissioning failed (attempt 1): Error CHIP:0x00000032
D: 72319 [IN]Clearing BLE pending packets.
I: 72323 [BLE]Releasing end point's BLE connection back to application.
I: 72330 [DL]Closing BLE GATT connection (ConnId 00)
caipiblack commented 2 years ago

@mrninhvn : Do you have the logs from the matter controller ? I want to check at which point of the commissioning you get this error.

Also do you have the logs of the openthread daemon?

Also, during the test make sure your firewall is opened (ip6tables) If the problem appear at the point where the device enter in thread network and the controller try to discovr it, you can try to ping the device to know the link is ok, and you can try to discover him.

i’m note sure about the command because i am not near my computer but it’s something like this:

avahi-browse _matter._tcp -r

mrninhvn commented 2 years ago

@mrninhvn : Do you have the logs from the matter controller ? I want to check at which point of the commissioning you get this error.

Also do you have the logs of the openthread daemon?

Also, during the test make sure your firewall is opened (ip6tables) If the problem appear at the point where the device enter in thread network and the controller try to discovr it, you can try to ping the device to know the link is ok, and you can try to discover him.

i’m note sure about the command because i am not near my computer but it’s something like this:

avahi-browse _matter._tcp -r

I opened the firewall, I can also see the device's matter tcp service, but I can't ping it. I use android chiptool to commissioning the device and as you can see, it got error 32 at the last stage of commissioning. I will try to get the android log in the next few days, seems a real router will have more problems than raspberry pi

mrninhvn commented 2 years ago

This is my log: border-router-log.txt chiptool-android-log.txt matter-device-log.txt

Seems the problem is caused by CHIP MessageCounter, but I don't understand why.

mrninhvn commented 2 years ago

@caipiblack I solved the problem, the error came from chiptool app, otbr now works as expected on my xiaomi router.

mrninhvn commented 2 years ago
#!/bin/sh /etc/rc.common
#
### BEGIN INIT INFO
# Provides:          otbr-firewall
# Required-Start:
# Required-Stop:
# Should-Start:
# Should-Stop:
# Default-Start:     2 3 4 5
# Default-Stop:
# Short-Description: OTBR firewall
# Description:       This service sets up firewall for OTBR.
### END INIT INFO

START=89
USE_PROCD=1

THREAD_IF="wpan0"
OTBR_FORWARD_INGRESS_CHAIN="OTBR_FORWARD_INGRESS"

start_service() {
  ipset create -exist otbr-ingress-deny-src hash:net family inet6
  ipset create -exist otbr-ingress-deny-src-swap hash:net family inet6
  ipset create -exist otbr-ingress-allow-dst hash:net family inet6
  ipset create -exist otbr-ingress-allow-dst-swap hash:net family inet6

  ip6tables -N $OTBR_FORWARD_INGRESS_CHAIN
  ip6tables -I FORWARD 1 -o $THREAD_IF -j $OTBR_FORWARD_INGRESS_CHAIN

  ip6tables -A $OTBR_FORWARD_INGRESS_CHAIN -m pkttype --pkt-type unicast -i $THREAD_IF -p ip -j DROP
  ip6tables -A $OTBR_FORWARD_INGRESS_CHAIN -m set --match-set otbr-ingress-deny-src src -p ip -j DROP
  ip6tables -A $OTBR_FORWARD_INGRESS_CHAIN -m set --match-set otbr-ingress-allow-dst dst -p ip -j ACCEPT
  ip6tables -A $OTBR_FORWARD_INGRESS_CHAIN -m pkttype --pkt-type unicast -p ip -j DROP
  ip6tables -A $OTBR_FORWARD_INGRESS_CHAIN -p ip -j ACCEPT
}

This is my script to set iptable at startup, do you have any idea how to compile it together with otbr?

caipiblack commented 2 years ago

@mrninhvn Yes

  1. Create a folder near the Makefile used to build otbr (‘files/etc/init.d’)
  2. Put your script inside
  3. In the install part of the Makefile, put something like this:
define Package/openthread-br/install
    $(CP) ./files/* $(STAGING_DIR)/

    $(INSTALL_DIR) $(1)/etc/init.d
    $(CP) ./files/etc/init.d/name-of-your-script $(1)/etc/init.d
endef
  1. You can check in the generated package that it contains the script.

I plan to make a PR to propose openwrt otbr update, i need to find some time.

caipiblack commented 2 years ago

@mrninhvn : I also made some changes, you can find it here: https://github.com/caipiblack/ot-br-posix

With these changes, we can easily integrate ot-br-posix on OpenWRT and it's compatible with Matter :+1:

Also, I explain on a message how I test it (here), so it can be a good base if someone needs something like a getting started to make ot-br-posix working with OpenWRT.

mrninhvn commented 2 years ago

Nice work! I'm also using otbr on my xiaomi router for a few months and it's been fine.

jwhui commented 2 years ago

Closing stale issue.

kaizirlewagen commented 6 months ago

I setup my openwrt router (v23.05.3) with all informations posted here, but my nordic cli device does not get the fd11 ipv6 address.

> ipaddr
fdde:ad00:beef:0:0:ff:fe00:fc00
fdde:ad00:beef:0:0:ff:fe00:7c00
fdde:ad00:beef:0:6ea3:65a:b3f7:e4c3
fe80:0:0:0:6468:e0be:d327:1058
Done

I don't find what package or what setting is missing. No errors in logs, and no error at start otbr-agent and when i join a device.

Any ideas?