RADIO_URL: spinel+hdlc+uart:///dev/ttyACM0 TUN_INTERFACE_NAME: wpan0 BACKBONE_INTERFACE: eth0 NAT64_PREFIX: 64:ff9b::/96 AUTO_PREFIX_ROUTE: true AUTO_PREFIX_SLAAC: true sed: can't read /etc/bind/named.conf.options: No such file or directory +++ dirname /app/script/server ++ cd /app/script/.. ++ [[ ! -n x ]] ++ echo 'Current platform is ubuntu' Current platform is ubuntu ++ with BORDER_ROUTING ++ local value +++ printenv BORDER_ROUTING ++ value=1 ++ [[ -z 1 ]] ++ [[ 1 == 1 ]] ++ with DHCPV6_PD ++ local value +++ printenv DHCPV6_PD ++ value= ++ [[ -z '' ]] ++ [[ -f examples/platforms/ubuntu/default ]] ++ [[ '' == 1 ]] ++ with BORDER_ROUTING ++ local value +++ printenv BORDER_ROUTING ++ value=1 ++ [[ -z 1 ]] ++ [[ 1 == 1 ]] ++ with NETWORK_MANAGER ++ local value +++ printenv NETWORK_MANAGER ++ value= ++ [[ -z '' ]] ++ [[ -f examples/platforms/ubuntu/default ]] ++ [[ '' == 1 ]] ++ STAGE_DIR=/app/stage ++ BUILD_DIR=/app/build ++ [[ -d /app/stage ]] ++ mkdir -v -p /app/stage mkdir: created directory '/app/stage' ++ [[ -d /app/build ]] ++ mkdir -v -p /app/build mkdir: created directory '/app/build' ++ export PATH=/app/stage/usr/bin:/app/stage/usr/sbin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin ++ PATH=/app/stage/usr/bin:/app/stage/usr/sbin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin +++ basename /app/script/server ++ TASKNAME=server ++ BEFORE_HOOK=examples/platforms/ubuntu/before_server ++ AFTER_HOOK=examples/platforms/ubuntu/after_server ++ [[ ! -f examples/platforms/ubuntu/before_server ]] ++ BEFORE_HOOK=/dev/null ++ [[ ! -f examples/platforms/ubuntu/after_server ]] ++ AFTER_HOOK=/dev/null + . script/_nat64 ++ NAT64_SERVICE=tayga ++ TAYGA_DEFAULT=/etc/default/tayga ++ TAYGA_CONF=/etc/tayga.conf ++ TAYGA_IPV4_ADDR=192.168.255.1 ++ TAYGA_IPV6_ADDR=fdaa:bb:1::1 ++ TAYGA_TUN_V6_ADDR=fdaa:bb:1::2 ++ NAT64_PREFIX=64:ff9b::/96 ++ DYNAMIC_POOL=192.168.255.0/24 ++ NAT44_SERVICE=/etc/init.d/otbr-nat44 ++ WLAN_IFNAMES=eth0 + . script/_dns64 ++ BIND_CONF_OPTIONS=/etc/bind/named.conf.options ++ NAT64_PREFIX=64:ff9b::/96 ++ DNS64_NAMESERVER_ADDR=127.0.0.1 +++ echo 64:ff9b::/96 +++ tr '"/"' '"/"' ++ DNS64_CONF='dns64 64:ff9b::/96 { clients { thread; }; recursive-only yes; };' ++ without NAT64 ++ with NAT64 ++ local value +++ printenv NAT64 ++ value=1 ++ [[ -z 1 ]] ++ [[ 1 == 1 ]] ++ test ubuntu = ubuntu ++ '[' ubuntu = raspbian ']' ++ '[' ubuntu = beagleboneblack ']' ++ '[' ubuntu = ubuntu ']' ++ RESOLV_CONF_HEAD=/etc/resolvconf/resolv.conf.d/head + . script/_firewall ++ FIREWALL_SERVICE=/etc/init.d/otbr-firewall ++ sudo modprobe ip6table_filter sudo: modprobe: command not found ++ true + main + . /dev/null + sudo sysctl --system * Applying /etc/sysctl.d/10-console-messages.conf ... kernel.printk = 4 4 1 7 * Applying /etc/sysctl.d/10-ipv6-privacy.conf ... net.ipv6.conf.all.use_tempaddr = 2 net.ipv6.conf.default.use_tempaddr = 2 * Applying /etc/sysctl.d/10-kernel-hardening.conf ... kernel.kptr_restrict = 1 * Applying /etc/sysctl.d/10-link-restrictions.conf ... fs.protected_hardlinks = 1 fs.protected_symlinks = 1 * Applying /etc/sysctl.d/10-magic-sysrq.conf ... kernel.sysrq = 176 * Applying /etc/sysctl.d/10-network-security.conf ... net.ipv4.conf.default.rp_filter = 1 net.ipv4.conf.all.rp_filter = 1 net.ipv4.tcp_syncookies = 1 * Applying /etc/sysctl.d/10-ptrace.conf ... * Applying /etc/sysctl.d/10-zeropage.conf ... vm.mmap_min_addr = 32768 * Applying /etc/sysctl.d/60-otbr-accept-ra.conf ... net.ipv6.conf.eth0.accept_ra = 2 net.ipv6.conf.eth0.accept_ra_rt_info_max_plen = 64 * Applying /etc/sysctl.d/60-otbr-ip-forward.conf ... net.ipv6.conf.all.forwarding = 1 net.ipv4.ip_forward = 1 * Applying /etc/sysctl.conf ... net.ipv6.conf.all.disable_ipv6 = 0 net.ipv6.conf.all.forwarding = 1 net.ipv4.conf.all.forwarding = 1 + nat64_start + with NAT64 + local value ++ printenv NAT64 + value=1 + [[ -z 1 ]] + [[ 1 == 1 ]] + '[' tayga = tayga ']' + tayga_start + with DOCKER + local value ++ printenv DOCKER + value=1 + [[ -z 1 ]] + [[ 1 == 1 ]] + service tayga start * Starting userspace NAT64 tayga  [ OK ] + nat44_start + '[' tayga = tayga ']' + have systemctl + command -v systemctl + sudo iptables -t nat -A POSTROUTING -s 192.168.255.0/24 -j MASQUERADE + dns64_start + with NAT64 + local value ++ printenv NAT64 + value=1 + [[ -z 1 ]] + [[ 1 == 1 ]] + with DNS64 + local value ++ printenv DNS64 + value=0 + [[ -z 0 ]] + [[ 0 == 1 ]] + return 0 + firewall_start + with DOCKER + local value ++ printenv DOCKER + value=1 + [[ -z 1 ]] + [[ 1 == 1 ]] + service otbr-firewall start + case "$1" in + firewall_start + firewall_stop + ip6tables -C FORWARD -o wpan0 -j OTBR_FORWARD_INGRESS ip6tables v1.6.1: Couldn't load target `OTBR_FORWARD_INGRESS':No such file or directory Try `ip6tables -h' or 'ip6tables --help' for more information. + ip6tables -L OTBR_FORWARD_INGRESS ip6tables: No chain/target/match by that name. + ipset_destroy_if_exist otbr-ingress-deny-src + ipset list otbr-ingress-deny-src ipset v6.34: Kernel support protocol versions 6-7 while userspace supports protocol versions 6-6 The set with the given name does not exist + ipset_destroy_if_exist otbr-ingress-deny-src-swap + ipset list otbr-ingress-deny-src-swap ipset v6.34: Kernel support protocol versions 6-7 while userspace supports protocol versions 6-6 The set with the given name does not exist + ipset_destroy_if_exist otbr-ingress-allow-dst + ipset list otbr-ingress-allow-dst ipset v6.34: Kernel support protocol versions 6-7 while userspace supports protocol versions 6-6 The set with the given name does not exist + ipset_destroy_if_exist otbr-ingress-allow-dst-swap + ipset list otbr-ingress-allow-dst-swap ipset v6.34: Kernel support protocol versions 6-7 while userspace supports protocol versions 6-6 The set with the given name does not exist + 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 + ip6tables -I FORWARD 1 -o wpan0 -j OTBR_FORWARD_INGRESS + ip6tables -A OTBR_FORWARD_INGRESS -m pkttype --pkt-type unicast -i wpan0 -j DROP + ip6tables -A OTBR_FORWARD_INGRESS -m set --match-set otbr-ingress-deny-src src -j DROP + ip6tables -A OTBR_FORWARD_INGRESS -m set --match-set otbr-ingress-allow-dst dst -j ACCEPT + ip6tables -A OTBR_FORWARD_INGRESS -m pkttype --pkt-type unicast -j DROP + ip6tables -A OTBR_FORWARD_INGRESS -j ACCEPT + have systemctl + command -v systemctl + have service + command -v service + sudo service rsyslog status * rsyslogd is not running + sudo service rsyslog start * Starting enhanced syslogd rsyslogd  [ OK ] + sudo service dbus status * dbus is not running + sudo service dbus start * Starting system message bus dbus  [ OK ] + sudo service mdns status Usage: /etc/init.d/mDNS {start|stop|reload|restart} + sudo service mdns start Starting Apple Darwin Multicast DNS / DNS Service Discovery daemon: mdnsd. + sudo service avahi-daemon status Avahi mDNS/DNS-SD Daemon is not running + sudo service avahi-daemon start * Starting Avahi mDNS/DNS-SD Daemon avahi-daemon  [ OK ] + sudo service otbr-agent status * otbr-agent is not running + sudo service otbr-agent start * Starting thread border agent otbr-agent  [ OK ] + without WEB_GUI + with WEB_GUI + local value ++ printenv WEB_GUI + value=1 + [[ -z 1 ]] + [[ 1 == 1 ]] + sudo service otbr-web status * otbr-web is not running + sudo service otbr-web start * Starting thread web interface otbr-web  [ OK ] + . /dev/null Done Done Done Done Done Done Done Done Done Done Done Done Starting MQTT-SN Gateway listening on: ff33:40:fd8c:aaac:41b5:ab54::1 Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.604 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.604 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.604 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.604 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.604 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.616 [I] Platform------: Session socket is ready Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.617 [I] Platform------: > dataset meshlocalprefix Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.617 [D] Cli-----------: Input: dataset meshlocalprefix Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.617 [D] Cli-----------: Output: Mesh Local Prefix: fd8c:aaac:41b5:ab54::/64 Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.617 [D] Cli-----------: Output: Done nohup: appending output to 'nohup.out' Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.666 [N] Mle-----------: Attach attempt 1, AnyPartition reattaching with Active Dataset Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.667 [I] Mle-----------: AttachState Start -> ParentReq Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.667 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.674 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:795a, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.674 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:19.674 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.196 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.197 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.197 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.197 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.197 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.197 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:09 46aa62994f58 otbr-agent[216]: 00:00:20.197 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected) Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:20.417 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2) Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:20.423 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:f9ce, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:20.423 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:20.424 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Received reply for service OpenThread BorderRouter #7B59._meshcop._udp., serviceRef = 0xf3b198 Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Successfully registered service OpenThread BorderRouter #7B59._meshcop._udp. Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #7B59._meshcop._udp.local: OK Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:21.167 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:21.173 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:33f4, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:21.174 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:10 46aa62994f58 otbr-agent[216]: 00:00:21.174 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Sep 23 08:22:12 46aa62994f58 otbr-agent[216]: 00:00:22.418 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Sep 23 08:22:12 46aa62994f58 otbr-agent[216]: 00:00:22.424 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:dd09, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:12 46aa62994f58 otbr-agent[216]: 00:00:22.425 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:12 46aa62994f58 otbr-agent[216]: 00:00:22.425 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Sep 23 08:22:13 46aa62994f58 otbr-agent[216]: 00:00:23.670 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Sep 23 08:22:13 46aa62994f58 otbr-agent[216]: 00:00:23.682 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:82d2, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:13 46aa62994f58 otbr-agent[216]: 00:00:23.682 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:13 46aa62994f58 otbr-agent[216]: 00:00:23.683 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Sep 23 08:22:14 46aa62994f58 otbr-agent[216]: 00:00:24.921 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Sep 23 08:22:14 46aa62994f58 otbr-agent[216]: 00:00:24.927 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:8db5, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:14 46aa62994f58 otbr-agent[216]: 00:00:24.928 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:14 46aa62994f58 otbr-agent[216]: 00:00:24.928 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.171 [I] Mle-----------: AttachState ParentReq -> Idle Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.171 [N] RouterTable---: Allocate router id 4 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.172 [N] Mle-----------: RLOC16 fffe -> 1000 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.174 [I] Platform------: [netif] Sent request#3 to add fd8c:aaac:41b5:ab54:0:ff:fe00:1000/64 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.174 [N] Mle-----------: Role detached -> leader Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.175 [I] Platform------: [netif] Sent request#4 to add fd8c:aaac:41b5:ab54:0:ff:fe00:fc00/64 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.175 [I] Platform------: [netif] Added multicast address ff02::2 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.176 [I] Platform------: [netif] Added multicast address ff03::2 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.176 [N] Mle-----------: Leader partition id 0x60cab8db Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.177 [I] Notifier------: StateChanged (0x100012a5) [Ip6+ Role Rloc+ PartitionId NetData Ip6Mult+ ActDset] Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.187 [I] Settings------: Saved NetworkInfo {rloc:0x1000, extaddr:3a67a508e3dc7b59, role:leader, mode:0x0f, version:4, keyseq:0x0, ... Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.188 [I] Settings------: ... pid:0x60cab8db, mlecntr:0x3ef, maccntr:0x3e8, mliid:6b84275b87f8cf7a} Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.188 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.189 [I] BbrLeader-----: PBBR state: None Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.189 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.189 [I] Settings------: Re-saved NetworkInfo {rloc:0x1000, extaddr:3a67a508e3dc7b59, role:leader, mode:0x0f, version:4, keyseq:0x0, ... Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.189 [I] Settings------: ... pid:0x60cab8db, mlecntr:0x3ef, maccntr:0x3e8, mliid:6b84275b87f8cf7a} Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.190 [I] AnnounceSender: Started Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.190 [I] BorderAgent---: Border Agent start listening on port 49153 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.190 [I] BorderRouter--: Border Routing manager started Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.191 [I] BorderRouter--: Scheduled Router Solicitation in 928 milliseconds Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.191 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2839 milliseconds Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.191 [I] NetDataPublshr: DNS/SRP service (state:ToAdd) in netdata - total:0, preferred:0, desired:2 Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.191 [I] NetDataPublshr: DNS/SRP service - State: ToAdd -> Adding Sep 23 08:22:15 46aa62994f58 otbr-agent[216]: 00:00:26.192 [I] NetDataPublshr: DNS/SRP service (state:Adding) - update in 2591 msec Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.205 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.218 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.250 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd8c:aaac:41b5:ab54::/64 -exist` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.268 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.285 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-BA------: Thread is up Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #7B59._meshcop._udp.local. Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Removing existing service OpenThread BorderRouter #7B59._meshcop._udp: outdated Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #7B59._meshcop._udp Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #7B59._meshcop._udp.local, serviceRef = (nil) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.DeviceRole Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ DeviceRole:{ "leader", }, ], [ ], } Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.ActiveDatasetTlvs Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ ActiveDatasetTlvs:{ [ 14, 8, 0, 0, 99, 45, 108, 177, 0, 0, 0, 3, 0, 0, 11, 53, 6, 0, 4, 0, 16, 0, 0, 2, 8, 222, 173, 0, 190, 239, 0, 202, 254, 7, 8, 253, 140, 170, 172, 65, 181, 171, 84, 5, 16, 0, 17, 34, 51, 68, 85, 102, 119, 136, 153, 170, 187, 204, 221, 238, 255, 3, 12, 79, 84, 66, 82, 45, 77, 81, 84, 84, 45, 83, 78, 1, 2, 171, 205, 4, 16, 92, 230, 109, 4, 157, 0, 112, 136, 173, 144, 13, 252, 194, 165, 94, 227, 12, 4, 2, 160, 247, 248, ], }, ], [ ], } Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.291 [I] Platform------: [netif] ADD [U] fd8c:aaac:41b5:ab54:0:ff:fe00:1000 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.292 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.292 [I] Platform------: [netif] Succeeded to process request#3 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.293 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.293 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.293 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.293 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.293 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.294 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.295 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.295 [I] Platform------: [netif] ADD [U] fd8c:aaac:41b5:ab54:0:ff:fe00:fc00 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.296 [I] Platform------: [netif] Succeeded to process request#4 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.301 [I] MeshForwarder-: Sent IPv6 UDP msg, len:96, chksum:5ed4, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.302 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.302 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.806 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.807 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.808 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.808 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.808 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.809 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.809 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.809 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.856 [I] BbrLocal------: Add BBR Service: seqno (116), delay (5s), timeout (3600s), OK Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.857 [I] NetworkData---: Sent server data notification Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.857 [I] NetworkData---: Received network data registration Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.858 [I] NetworkData---: Allocated Service ID = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.858 [I] NetworkData---: Sent network data registration acknowledgment Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.858 [I] Notifier------: StateChanged (0x00000200) [NetData] Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.859 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.859 [I] BbrLeader-----: PBBR state: Added Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.859 [I] BbrLeader-----: Rloc16: 0x1000, seqno: 116, delay: 5, timeout 3600 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.860 [I] Platform------: [netif] Sent request#5 to add fd8c:aaac:41b5:ab54:0:ff:fe00:fc38/64 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.860 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.861 [I] Platform------: [netif] Sent request#6 to add fd8c:aaac:41b5:ab54:0:ff:fe00:fc10/64 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.861 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2729 milliseconds Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.861 [I] NetDataPublshr: DNS/SRP service (state:Adding) in netdata - total:0, preferred:0, desired:2 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.874 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.887 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.899 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd8c:aaac:41b5:ab54::/64 -exist` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.912 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.924 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.924 [I] Notifier------: StateChanged (0x02000001) [Ip6+ BbrState] Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.924 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fd8c:aaac:41b5:ab54:0:3: OK Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.924 [I] BbrManager----: Start Backbone TMF agent: OK Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.925 [I] Platform------: MulticastRoutingManager: Enable: OK Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #7B59._meshcop._udp.local. Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Removing existing service OpenThread BorderRouter #7B59._meshcop._udp: outdated Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #7B59._meshcop._udp Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-BA------: Cancelled previous publishing meshcop service OpenThread BorderRouter #7B59._meshcop._udp.local Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #7B59._meshcop._udp.local, serviceRef = (nil) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=2, mBackboneRouterState=1 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: [NOTE]-BBA-----: BackboneAgent: Backbone Router becomes Primary! Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.928 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.928 [I] Platform------: [netif] ADD [U] fd8c:aaac:41b5:ab54:0:ff:fe00:fc38 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.929 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.930 [I] Platform------: [netif] Succeeded to process request#5 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.930 [I] Platform------: [netif] ADD [U] fd8c:aaac:41b5:ab54:0:ff:fe00:fc10 (already subscribed, ignored) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.931 [I] Platform------: [netif] Succeeded to process request#6 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.935 [I] MeshForwarder-: Sent IPv6 UDP msg, len:112, chksum:f28c, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.935 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:26.935 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.028 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.034 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:aaa7, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.034 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.034 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.119 [I] BorderRouter--: Successfully sent RS 1/3 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.120 [I] BorderRouter--: Received Router Solicitation from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:16 46aa62994f58 otbr-agent[216]: 00:00:27.120 [I] BorderRouter--: Start evaluating routing policy, scheduled in 362 milliseconds Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.426 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.427 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.482 [I] BorderRouter--: Evaluating routing policy Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.482 [I] BorderRouter--: EvaluateOmrPrefix: No preferred OMR prefix found in Thread network Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.482 [I] BorderRouter--: Added local OMR prefix fd69:6d95:c150:1::/64 in Thread Network Data Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.482 [I] BorderRouter--: RouterAdvert: Added RIO for fd69:6d95:c150:1::/64 (lifetime=1800) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.483 [I] BorderRouter--: Sent Router Advertisement on infra netif 9 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.483 [I] BorderRouter--: Start evaluating routing policy, scheduled in 16000 milliseconds Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.483 [I] NetworkData---: Sent server data notification Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.483 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.485 [I] NetworkData---: Received network data registration Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.486 [I] NetworkData---: Allocated Context ID = 1 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.486 [I] NetworkData---: Sent network data registration acknowledgment Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.486 [I] Notifier------: StateChanged (0x00000200) [NetData] Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.486 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.486 [I] BbrLeader-----: PBBR state: Unchanged Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.486 [I] BbrLeader-----: Rloc16: 0x1000, seqno: 116, delay: 5, timeout 3600 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.487 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.497 [I] Settings------: Saved SlaacIidSecretKey Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.497 [I] Slaac---------: Generated and saved secret key Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.497 [I] Slaac---------: Adding address fd69:6d95:c150:1:af44:4295:77c8:3b32 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.497 [I] Platform------: [netif] Sent request#7 to add fd69:6d95:c150:1:af44:4295:77c8:3b32/64 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.497 [I] BorderRouter--: Start evaluating routing policy, scheduled in 3690 milliseconds Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.497 [I] NetDataPublshr: DNS/SRP service (state:Adding) in netdata - total:0, preferred:0, desired:2 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.511 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.523 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.538 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd69:6d95:c150:1::/64 -exist` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.550 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd8c:aaac:41b5:ab54::/64 -exist` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.562 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fd69:6d95:c150:1::/64 -exist` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.574 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.586 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.587 [I] Notifier------: StateChanged (0x00000001) [Ip6+] Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.588 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.588 [I] Platform------: [netif] ADD [U] fd69:6d95:c150:1:af44:4295:77c8:3b32 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.588 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.588 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.588 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.588 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.589 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.589 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.589 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Received reply for service OpenThread BorderRouter #7B59._meshcop._udp., serviceRef = 0xf3b198 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: [INFO]-MDNS----: Successfully registered service OpenThread BorderRouter #7B59._meshcop._udp. Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #7B59._meshcop._udp.local: OK Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.589 [I] Platform------: [netif] Succeeded to process request#7 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.595 [I] MeshForwarder-: Sent IPv6 UDP msg, len:134, chksum:82c0, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.595 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.595 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.854 [I] MlrManager----: MLR Reregister! Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.854 [I] DuaManager----: PerformNextRegistration: NotFound Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.866 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.867 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.867 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.867 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.867 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.867 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.868 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:17 46aa62994f58 otbr-agent[216]: 00:00:27.868 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.571 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.578 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:b3d9, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.578 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.579 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.783 [I] NetDataPublshr: DNS/SRP service - State: Adding -> Added Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.784 [I] SrpServer-----: Start listening on port 53535 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.784 [I] NetworkData---: Sent server data notification Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.785 [I] NetworkData---: Received network data registration Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.785 [I] NetworkData---: Allocated Service ID = 1 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.785 [I] NetworkData---: Sent network data registration acknowledgment Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.785 [I] Notifier------: StateChanged (0x00000200) [NetData] Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.786 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.786 [I] BbrLeader-----: PBBR state: Unchanged Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.786 [I] BbrLeader-----: Rloc16: 0x1000, seqno: 116, delay: 5, timeout 3600 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.787 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.787 [I] Platform------: [netif] Sent request#8 to add fd8c:aaac:41b5:ab54:0:ff:fe00:fc11/64 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.787 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2601 milliseconds Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.788 [I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.802 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.817 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.833 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd69:6d95:c150:1::/64 -exist` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.847 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd8c:aaac:41b5:ab54::/64 -exist` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.877 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fd69:6d95:c150:1::/64 -exist` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.893 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.909 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.910 [I] Notifier------: StateChanged (0x00000001) [Ip6+] Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.911 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.911 [I] Platform------: [netif] ADD [U] fd8c:aaac:41b5:ab54:0:ff:fe00:fc11 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.912 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.913 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.913 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.914 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.914 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.914 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.914 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.915 [I] Platform------: [netif] Succeeded to process request#8 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.933 [I] MeshForwarder-: Sent IPv6 UDP msg, len:161, chksum:32a9, ecn:no, to:0xffff, sec:yes, prio:net Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.934 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:28.934 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.086 [I] Platform------: [netif] Message dropped by Thread Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.087 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.087 [I] Platform------: [netif] ADD [M] ff33:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.088 [I] Platform------: [netif] ADD [M] ff32:40:fd8c:aaac:41b5:ab54:0:1 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.088 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.088 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.088 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Sep 23 08:22:18 46aa62994f58 otbr-agent[216]: 00:00:29.088 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.119 [I] BorderRouter--: Successfully sent RS 2/3 Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.120 [I] BorderRouter--: Received Router Solicitation from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.120 [I] BorderRouter--: Start evaluating routing policy, scheduled in 206 milliseconds Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.187 [I] BorderRouter--: Evaluating routing policy Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.187 [I] BorderRouter--: RouterAdvert: Added RIO for fd69:6d95:c150:1::/64 (lifetime=1800) Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.188 [I] BorderRouter--: Sent Router Advertisement on infra netif 9 Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.189 [I] BorderRouter--: Start evaluating routing policy, scheduled in 16000 milliseconds Sep 23 08:22:20 46aa62994f58 otbr-agent[216]: 00:00:31.189 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:22 46aa62994f58 otbr-agent[216]: 00:00:32.368 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) Sep 23 08:22:22 46aa62994f58 otbr-agent[216]: 00:00:32.375 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:ddf5, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:22 46aa62994f58 otbr-agent[216]: 00:00:32.375 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:22 46aa62994f58 otbr-agent[216]: 00:00:32.376 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:24 46aa62994f58 otbr-agent[216]: 00:00:35.120 [I] BorderRouter--: Successfully sent RS 3/3 Sep 23 08:22:24 46aa62994f58 otbr-agent[216]: 00:00:35.121 [I] BorderRouter--: Received Router Solicitation from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:24 46aa62994f58 otbr-agent[216]: 00:00:35.121 [I] BorderRouter--: Start evaluating routing policy, scheduled in 272 milliseconds Sep 23 08:22:25 46aa62994f58 otbr-agent[216]: 00:00:35.393 [I] BorderRouter--: Evaluating routing policy Sep 23 08:22:25 46aa62994f58 otbr-agent[216]: 00:00:35.393 [I] BorderRouter--: RouterAdvert: Added RIO for fd69:6d95:c150:1::/64 (lifetime=1800) Sep 23 08:22:25 46aa62994f58 otbr-agent[216]: 00:00:35.394 [I] BorderRouter--: Sent Router Advertisement on infra netif 9 Sep 23 08:22:25 46aa62994f58 otbr-agent[216]: 00:00:35.394 [I] BorderRouter--: Start evaluating routing policy, scheduled in 16000 milliseconds Sep 23 08:22:25 46aa62994f58 otbr-agent[216]: 00:00:35.394 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:25 46aa62994f58 otbr-agent[216]: 00:00:36.120 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2274 milliseconds Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.394 [I] BorderRouter--: Evaluating routing policy Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.394 [I] NetDataPublshr: Publishing ExternalRoute fdde:ad00:beef:cafe::/64 Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.395 [I] NetDataPublshr: ExternalRoute fdde:ad00:beef:cafe::/64 - State: NoEntry -> ToAdd Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.395 [I] NetDataPublshr: ExternalRoute fdde:ad00:beef:cafe::/64 (state:ToA in netdata - total:0, preferred:0, desired:10 Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.395 [I] NetDataPublshr: ExternalRoute fdde:ad00:beef:cafe::/64 - State: ToAdd -> Adding Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.395 [I] NetDataPublshr: ExternalRoute fdde:ad00:beef:cafe::/64 (state:Add - update in 1757 msec Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.395 [I] BorderRouter--: Start advertising on-link prefix fdde:ad00:beef:cafe::/64 Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.395 [I] BorderRouter--: RouterAdvert: Added PIO for fdde:ad00:beef:cafe::/64 (valid=1800, preferred=1800) Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.396 [I] BorderRouter--: RouterAdvert: Added RIO for fd69:6d95:c150:1::/64 (lifetime=1800) Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.396 [I] BorderRouter--: Sent Router Advertisement on infra netif 9 Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.397 [I] BorderRouter--: Start evaluating routing policy, scheduled in 494447 milliseconds Sep 23 08:22:28 46aa62994f58 otbr-agent[216]: 00:00:38.397 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:39.266 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:39.274 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:5eef, ecn:no, to:0xffff, sec:no, prio:net Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:39.274 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:39.275 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::42:acff:fe11:2. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Joining mDNS multicast group on interface eth0.IPv6 with address fdde:ad00:beef:cafe:42:acff:fe11:2. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0.*. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for fe80::42:acff:fe11:2 on eth0. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for 172.17.0.2 on eth0. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for ::1 on lo. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for 127.0.0.1 on lo. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Host name conflict, retrying with 46aa62994f58-2 Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0.*. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for 172.17.0.2 on eth0.IPv4. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for ::1 on lo.*. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for ::1 on lo. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Withdrawing address record for 127.0.0.1 on lo. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Host name conflict, retrying with 46aa62994f58-3 Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0.*. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for 172.17.0.2 on eth0.IPv4. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for ::1 on lo.*. Sep 23 08:22:29 46aa62994f58 avahi-daemon[188]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.152 [I] NetDataPublshr: ExternalRoute fdde:ad00:beef:cafe::/64 - State: Adding -> Added Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.153 [I] NetworkData---: Sent server data notification Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.153 [I] NetworkData---: Received network data registration Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.154 [I] NetworkData---: Sent network data registration acknowledgment Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.154 [I] Notifier------: StateChanged (0x00000200) [NetData] Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.155 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.155 [I] BbrLeader-----: PBBR state: Unchanged Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.155 [I] BbrLeader-----: Rloc16: 0x1000, seqno: 116, delay: 5, timeout 3600 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.155 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.156 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2601 milliseconds Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.156 [I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.156 [I] NetDataPublshr: ExternalRoute fdde:ad00:beef:cafe::/64 (state:Add in netdata - total:1, preferred:0, desired:10 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.170 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.184 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0 Sep 23 08:22:29 46aa62994f58 otbr-agent[216]: 00:00:40.197 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd69:6d95:c150:1::/64 -exist` = 0 Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.212 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd8c:aaac:41b5:ab54::/64 -exist` = 0 Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.228 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fd69:6d95:c150:1::/64 -exist` = 0 Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.246 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0 Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.260 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0 Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.276 [I] MeshForwarder-: Sent IPv6 UDP msg, len:178, chksum:ea4e, ecn:no, to:0xffff, sec:yes, prio:net Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.276 [I] MeshForwarder-: src:[fe80:0:0:0:3867:a508:e3dc:7b59]:19788 Sep 23 08:22:30 46aa62994f58 otbr-agent[216]: 00:00:40.276 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Withdrawing address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0. Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Withdrawing address record for ::1 on lo. Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Withdrawing address record for 127.0.0.1 on lo. Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Host name conflict, retrying with 46aa62994f58-4 Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Registering new address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0.*. Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Registering new address record for 172.17.0.2 on eth0.IPv4. Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Registering new address record for ::1 on lo.*. Sep 23 08:22:30 46aa62994f58 avahi-daemon[188]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Withdrawing address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Withdrawing address record for ::1 on lo. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Withdrawing address record for 127.0.0.1 on lo. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Host name conflict, retrying with 46aa62994f58-5 Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Registering new address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0.*. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Registering new address record for 172.17.0.2 on eth0.IPv4. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Registering new address record for ::1 on lo.*. Sep 23 08:22:31 46aa62994f58 avahi-daemon[188]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 23 08:22:32 46aa62994f58 otbr-agent[216]: 00:00:42.757 [I] BorderRouter--: Evaluating routing policy Sep 23 08:22:32 46aa62994f58 otbr-agent[216]: 00:00:42.758 [I] BorderRouter--: RouterAdvert: Added PIO for fdde:ad00:beef:cafe::/64 (valid=1800, preferred=1800) Sep 23 08:22:32 46aa62994f58 otbr-agent[216]: 00:00:42.758 [I] BorderRouter--: RouterAdvert: Added RIO for fd69:6d95:c150:1::/64 (lifetime=1800) Sep 23 08:22:32 46aa62994f58 otbr-agent[216]: 00:00:42.759 [I] BorderRouter--: Sent Router Advertisement on infra netif 9 Sep 23 08:22:32 46aa62994f58 otbr-agent[216]: 00:00:42.759 [I] BorderRouter--: Start evaluating routing policy, scheduled in 209508 milliseconds Sep 23 08:22:32 46aa62994f58 otbr-agent[216]: 00:00:42.760 [I] BorderRouter--: Received Router Advertisement from fe80:0:0:0:42:acff:fe11:2 on infra netif 9 Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Withdrawing address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0. Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Withdrawing address record for ::1 on lo. Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Withdrawing address record for 127.0.0.1 on lo. Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Host name conflict, retrying with 46aa62994f58-6 Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Registering new address record for fdde:ad00:beef:cafe:42:acff:fe11:2 on eth0.*. Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Registering new address record for 172.17.0.2 on eth0.IPv4. Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Registering new address record for ::1 on lo.*. Sep 23 08:22:33 46aa62994f58 avahi-daemon[188]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 23 08:22:34 46aa62994f58 mDNSResponder: Default: mDNSCoreReceiveResponse: Received from 172.17.0.2:5353 22 2.0.17.172.in-addr.arpa. PTR 46aa62994f58-6.local. Sep 23 08:22:34 46aa62994f58 mDNSResponder: Default: mDNSCoreReceiveResponse: Unexpected conflict discarding 20 2.0.17.172.in-addr.arpa. PTR 46aa62994f58.local. ^C