OTBR: Can ping IPv4 (via NAT64) but not plain IPv6 #12302

schildbach · 2026-01-15T10:26:20Z

schildbach
Jan 15, 2026

I'm running OTBR in Docker on a Debian host with a ZBT-1 connected. It is fully IPv4+IPv6 (dualstack) enabled. From the host:

# ping -c1 9.9.9.9
PING 9.9.9.9 (9.9.9.9) 56(84) bytes of data.
64 bytes from 9.9.9.9: icmp_seq=1 ttl=57 time=26.3 ms

--- 9.9.9.9 ping statistics ---
1 packets transmitted, 1 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 26.255/26.255/26.255/0.000 ms

# ping -c1 2620:fe::9
PING 2620:fe::9 (2620:fe::9) 56 data bytes
64 bytes from 2620:fe::9: icmp_seq=1 ttl=60 time=12.1 ms

--- 2620:fe::9 ping statistics ---
1 packets transmitted, 1 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 12.122/12.122/12.122/0.000 ms

Now, when trying this within ot-ctl (in the container), I get this:

IPv4 + NAT64 appears to work as expected:

> ping 9.9.9.9
Pinging synthesized IPv6 address: fdc4:8ef3:402e:2:0:0:909:909
16 bytes from fdc4:8ef3:402e:2:0:0:909:909: icmp_seq=1 hlim=56 time=11ms
1 packets transmitted, 1 packets received. Packet loss = 0.0%. Round-trip min/avg/max = 11/11.000/11 ms.
Done

I can also ping the synthesized IPv6:

> ping fdc4:8ef3:402e:2:0:0:909:909
16 bytes from fdc4:8ef3:402e:2:0:0:909:909: icmp_seq=7 hlim=56 time=26ms
1 packets transmitted, 1 packets received. Packet loss = 0.0%. Round-trip min/avg/max = 26/26.000/26 ms.
Done

But pinging a plain IPv6 doesn't work:

> ping 2620:fe::9
1 packets transmitted, 0 packets received. Packet loss = 100.0%.
Done

Is this expected?

I have more or less followed the official instructions:
https://openthread.io/guides/border-router/build-docker and
https://openthread.io/guides/border-router/form-network

I did not yet bring up a second Thread node, as I don't have any. In future, I want to try add one of each Ikea Bilresa, Myggbett and Myggspray devices as well as a Tado "Smart Radiator Thermostat X".

My home router is an AVM FritzBox 7590. It obtains a /56 prefix from my Internet provider, and splits it into two /64 subnets (main and guests). I followed these instructions to operate a router behind the FritzBox, because I assume that's what OTBR is/needs. In a nutshell:

Enabled "Allow IPv6 prefixes announced by other IPv6 routers in the home network"
Enabled "DHCPv6 server in the FRITZ!Box for the home network" (was enabled already)
Selected "Assign DNS server, prefix (IA_PD) and IPv6 address (IA_NA)"

Within seconds after I changed these settings, my FritzBox allocated two /62 prefixes (labelled as "Delegated"). Sadly I cannot see where they are delegated to. These prefixes appear in none of my server's "ip a" command outputs and there is no mention in my router logs either.

Back on my host, both IPv4 and IPv6 forwarding is enabled by Docker already – I did not change this:

# cat /proc/sys/net/ipv4/ip_forward
1
# cat /proc/sys/net/ipv6/conf/all/forwarding 
1

I manually enabled IPv6 router advertisements for my host network (yes, it's wireless not wired):

# cat /proc/sys/net/ipv6/conf/wifi0/accept_ra
2
# cat /proc/sys/net/ipv6/conf/wifi0/accept_ra_rt_info_max_plen
64

Two OTBR states that might be of interest:

> nat64 state
PrefixManager: Active
Translator: Active
Done

> state
leader
Done

Here is my Docker Compose. I even enabled IPv6 on the bridge network, although I guess that is useless for now as I need to use network_mode: host anyway.

services:
  otbr:
    image: openthread/border-router:latest
    cap_add:
      - NET_ADMIN
    network_mode: "host"
    environment:
      - OT_RCP_DEVICE=spinel+hdlc+uart:///dev/ttyUSB0
      - OT_INFRA_IF=wifi0
      - OT_THREAD_IF=wpan0
      - OT_LOG_LEVEL=7
    devices:
      - /dev/serial/by-id/usb-Nabu_Casa_Home_Assistant_Connect_ZBT-1_redacted-if00-port0:/dev/ttyUSB0
      - /dev/net/tun
    volumes:
      - ./otbr-data:/data
    restart: unless-stopped

networks:
  default:
    enable_ipv6: true

(Side question: many examples add the baud rate and flow control params to the OT_RCP_DEVICE url. Is this recommended? In my case it seems to be auto-detected just fine.)

And here is my log file:

s6-rc: info: service s6rc-oneshot-runner: starting
s6-rc: info: service s6rc-oneshot-runner successfully started
s6-rc: info: service fix-attrs: starting
s6-rc: info: service fix-attrs successfully started
s6-rc: info: service legacy-cont-init: starting
s6-rc: info: service legacy-cont-init successfully started
s6-rc: info: service otbr-agent: starting
Configuring OpenThread firewall...
Configuring OpenThread NAT64...
Starting otbr-agent...
[NOTE]-AGENT---: Running 0.3.0-35c4f28
[NOTE]-AGENT---: Thread version: 1.4.0
[NOTE]-AGENT---: Thread interface: wpan0
[NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/ttyUSB0
[NOTE]-AGENT---: Radio URL: trel://wifi0
[NOTE]-ILS-----: Infra link selected: wifi0
[INFO]-RCP_HOS-: OpenThread log level changed to 5
53d.07:09:35.404 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:0, cmd:RESET
53d.07:09:35.404 [D] P-SpinelDrive-: Waiting response: key=0
53d.07:09:35.407 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:RESET_POWER_ON
53d.07:09:35.407 [I] P-SpinelDrive-: co-processor reset: RESET_POWER_ON
53d.07:09:35.407 [C] P-SpinelDrive-: Software reset co-processor successfully
53d.07:09:35.407 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:PROTOCOL_VERSION
53d.07:09:35.407 [D] P-SpinelDrive-: Waiting response: key=1
53d.07:09:35.410 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:PROTOCOL_VERSION, major:4, minor:3
53d.07:09:35.410 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:NCP_VERSION
53d.07:09:35.410 [D] P-SpinelDrive-: Waiting response: key=2
53d.07:09:35.414 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:NCP_VERSION, version:SL-OPENTHREAD/2.4.4.0_GitHub-7074a43e4; EFR32; Oct 21 2024 14:40:57
53d.07:09:35.414 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:CAPS
53d.07:09:35.414 [D] P-SpinelDrive-: Waiting response: key=5
53d.07:09:35.417 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:CAPS, caps:COUNTERS UNSOL_UPDATE_FILTER 802_15_4_2450MHZ_OQPSK CONFIG_RADIO MAC_RAW RCP_API_VERSION RCP_MIN_HOST_API_VERSION RCP_RESET_TO_BOOTLOADER OPENTHREAD_LOG_METADATA 
53d.07:09:35.422 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:HWADDR
53d.07:09:35.422 [D] P-RadioSpinel-: Wait response: tid=1 key=8
53d.07:09:35.425 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:HWADDR, eui64:6c5cb1fffe29eb22
53d.07:09:35.425 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_GET, key:RCP_API_VERSION
53d.07:09:35.425 [D] P-RadioSpinel-: Wait response: tid=2 key=176
53d.07:09:35.428 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:RCP_API_VERSION, version:9
53d.07:09:35.428 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_GET, key:RCP_MIN_HOST_API_VERSION
53d.07:09:35.428 [D] P-RadioSpinel-: Wait response: tid=3 key=177
53d.07:09:35.430 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:RCP_MIN_HOST_API_VERSION, min-host-version:4
53d.07:09:35.431 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_GET, key:RADIO_CAPS
53d.07:09:35.431 [D] P-RadioSpinel-: Wait response: tid=4 key=4619
53d.07:09:35.433 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:RADIO_CAPS, caps:255
53d.07:09:35.433 [D] P-Trel--------: platformTrelInit(aTrelUrl:"trel://wifi0")
53d.07:09:35.433 [D] P-Trel--------: otSysTrelInit(aInterfaceName:"wifi0")
53d.07:09:35.435 [I] P-Netif-------: Sent request#1 to set addr_gen_mode to 1
53d.07:09:35.435 [I] P-Resolver----: Got nameserver #0: 192.168.178.1
53d.07:09:35.435 [I] P-Resolver----: Got nameserver #1: fda1:e58f:849e:0:6b4:feff:fe14:327f
53d.07:09:35.435 [I] P-Resolver----: Got nameserver #2: 2a02:3100:23ec:5900:6b4:feff:fe14:327f
00:00:00.000 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_GET, key:PHY_CHAN_SUPPORTED
00:00:00.000 [D] P-RadioSpinel-: Wait response: tid=5 key=34
00:00:00.003 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:PHY_CHAN_SUPPORTED, channelMask:0x07fff800
00:00:00.003 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:PHY_ENABLED, enabled:1
00:00:00.003 [D] P-RadioSpinel-: Wait response: tid=6 key=32
00:00:00.006 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:PHY_ENABLED, enabled:1
00:00:00.006 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:MAC_15_4_PANID, panid:0xffff
00:00:00.006 [D] P-RadioSpinel-: Wait response: tid=7 key=54
00:00:00.009 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:MAC_15_4_PANID, panid:0xffff
00:00:00.009 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0x0000
00:00:00.009 [D] P-RadioSpinel-: Wait response: tid=8 key=53
00:00:00.012 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0x0000
00:00:00.012 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_GET, key:PHY_RX_SENSITIVITY
00:00:00.012 [D] P-RadioSpinel-: Wait response: tid=9 key=39
00:00:00.015 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:PHY_RX_SENSITIVITY, sensitivity:-100
00:00:00.015 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:RCP_MAC_KEY, keyIdMode:8, keyId:1, prevKey:***, currKey:***, nextKey:***
00:00:00.015 [D] P-RadioSpinel-: Wait response: tid=10 key=2048
00:00:00.019 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:00.019 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:MAC_15_4_LADDR, laddr:5a2a07262146713a
00:00:00.019 [D] P-RadioSpinel-: Wait response: tid=11 key=52
00:00:00.022 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:MAC_15_4_LADDR, laddr:5a2a07262146713a
00:00:00.022 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0xfffe
00:00:00.022 [D] P-RadioSpinel-: Wait response: tid=12 key=53
00:00:00.025 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0xfffe
00:00:00.025 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:MAC_SRC_MATCH_SHORT_ADDRESSES, saddr:none
00:00:00.025 [D] P-RadioSpinel-: Wait response: tid=13 key=4868
00:00:00.030 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:00.030 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:MAC_SRC_MATCH_EXTENDED_ADDRESSES, extaddr:none
00:00:00.030 [D] P-RadioSpinel-: Wait response: tid=14 key=4869
00:00:00.033 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:00.033 [I] CslTxScheduler: Set frame request ahead: 7605 usec
00:00:00.033 [I] ChildSupervsn-: Timeout: 0 -> 190
00:00:00.034 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:RCP_MAC_KEY, keyIdMode:8, keyId:1, prevKey:***, currKey:***, nextKey:***
00:00:00.034 [D] P-RadioSpinel-: Wait response: tid=15 key=2048
00:00:00.038 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:00.038 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:MAC_15_4_PANID, panid:0x5556
00:00:00.038 [D] P-RadioSpinel-: Wait response: tid=1 key=54
00:00:00.040 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:MAC_15_4_PANID, panid:0x5556
00:00:00.041 [I] Settings------: Read NetworkInfo {rloc:0x9800, extaddr:e61f260f00b6d0e4, role:leader, mode:0x0f, version:5, keyseq:0x0, ...
00:00:00.041 [I] Settings------: ... pid:0x645f6dfa, mlecntr:0x30be, maccntr:0x1bb7, mliid:d5d2f716a540b072}
00:00:00.041 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:RCP_MAC_FRAME_COUNTER, counter:7095
00:00:00.041 [D] P-RadioSpinel-: Wait response: tid=2 key=2049
00:00:00.043 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:00.043 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0x9800
00:00:00.043 [D] P-RadioSpinel-: Wait response: tid=3 key=53
00:00:00.046 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0x9800
00:00:00.046 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:MAC_15_4_LADDR, laddr:e61f260f00b6d0e4
00:00:00.046 [D] P-RadioSpinel-: Wait response: tid=4 key=52
00:00:00.049 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:MAC_15_4_LADDR, laddr:e61f260f00b6d0e4
00:00:00.049 [D] P-Trel--------: PrepareSocket()
00:00:00.049 [I] TrelInterface-: Enabled interface, local port:38271
00:00:00.050 [I] InfraIf-------: Init infra netif 6
00:00:00.050 [I] Settings------: Read BrUlaPrefix fdc4:8ef3:402e::/48
00:00:00.050 [N] BorderRouting-: BR ULA prefix: fdc4:8ef3:402e::/48 (loaded)
00:00:00.050 [I] BorderRouting-: Generated local OMR prefix: fdc4:8ef3:402e:1::/64
00:00:00.050 [I] BorderRouting-: Generated local NAT64 prefix: fdc4:8ef3:402e:2:0:0::/96
00:00:00.050 [N] BorderRouting-: Local on-link prefix: fd4d:d7b0:b48c:e44e::/64
00:00:00.050 [I] InfraIf-------: State changed: NOT RUNNING -> RUNNING
00:00:00.050 [I] P-MdnsSocket--: Successfully opened IPv4 socket
00:00:00.050 [I] P-MdnsSocket--: Successfully opened IPv6 socket
00:00:00.052 [I] P-MdnsSocket--: Enabled
00:00:00.052 [I] MulticastDns--: Auto-enabling on infra-if-index 6
00:00:00.052 [I] Settings------: Read BorderAgentId {id:ede321443bd3d97f929de9756d16071a}
00:00:00.052 [I] BorderRouting-: Enabling
00:00:00.052 [I] Nat64---------: IPv4 CIDR for NAT64: 192.168.255.0/24 (254 addresses)
[INFO]-HELPER--: Set state callback: OK
00:00:00.052 [I] Nat64---------: State: Disabled -> NotRunning
00:00:00.052 [I] BbrLocal------: Add Domain Prefix: ::/0, NotFound
00:00:00.052 [I] BbrLocal------: Add BBR Service: seqno (60), delay (5s), timeout (3600s), InvalidState
[WARN]-UTILS---: /usr/src/ot-br-posix/src/rest/network_diag_handler.cpp:1300 lacking some attributes for deviceId e61f260f00b6d0e4
[WARN]-UTILS---: /usr/src/ot-br-posix/src/rest/network_diag_handler.cpp:1244 cannot get LeaderData while detached
[WARN]-UTILS---: /usr/src/ot-br-posix/src/rest/rest_devices_coll.cpp:232 - AddItem - e61f260f00b6d0e4
[INFO]-APP-----: Radio Co-processor version: SL-OPENTHREAD/2.4.4.0_GitHub-7074a43e4; EFR32; Oct 21 2024 14:40:57
[INFO]-REST----: RestWebServer listening on 127.0.0.1:8081
00:00:00.052 [I] Notifier------: StateChanged (0x521fc310) [MLAddr KeySeqCntr NetData Channel PanId NetName ExtPanId NetworkKey PSKc ...
00:00:00.053 [I] Notifier------: StateChanged (0x521fc310) ... SecPolicy BbrState ActDset Nat64]
00:00:00.053 [I] BbrLeader-----: PBBR state: None
00:00:00.053 [I] Bbr-----------: Start listening on port 61631
00:00:00.053 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fd75:edf9:c135:67c8:0:3: OK
00:00:00.053 [I] BbrManager----: Start Backbone TMF agent: OK
00:00:00.053 [I] AnnounceSender: ChannelMask:{ 11-26 }, period:21500
00:00:00.053 [I] AnnounceSender: StartingChannel:11
00:00:00.053 [I] AnnounceSender: StartingChannel:11
00:00:00.056 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:00.058 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:00.059 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:00.062 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:00.063 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
00:00:00.063 [I] P-Netif-------: NAT64 CIDR updated to 192.168.255.0/24.
00:00:00.063 [I] P-Netif-------: Sent request#2 to delete route 192.168.255.0/24
00:00:00.063 [I] P-Netif-------: Deleting route for NAT64
00:00:00.063 [I] P-McastRtMgr--: Disable: OK
[DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=1, mBackboneRouterState=0
00:00:00.064 [I] RouterTable---: Route table
00:00:00.064 [I] TrelDiscoverer: Registering service otTRELe61f260f00b6d0e4._trel._udp
00:00:00.064 [I] TrelDiscoverer:     port:38271, ext-addr:e61f260f00b6d0e4, ext-panid:4dd7b0b48c65e44e
00:00:00.064 [I] MulticastDns--: Adding host address 192.168.178.4
00:00:00.064 [I] MulticastDns--: Adding host address fe80:0:0:0:2a0c:50ff:fe6d:3fe4
00:00:00.064 [I] MulticastDns--: Adding host address fda1:e58f:849e:0:2a0c:50ff:fe6d:3fe4
00:00:00.064 [I] MulticastDns--: Adding host address 2a02:3100:287d:c100:2a0c:50ff:fe6d:3fe4
00:00:00.064 [I] P-Netif-------: Host netif is down
00:00:00.064 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:PHY_CHAN, channel:11
00:00:00.064 [D] P-RadioSpinel-: Wait response: tid=5 key=33
00:00:00.067 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:PHY_CHAN, channel:11
00:00:00.067 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:MAC_RAW_STREAM_ENABLED, enabled:1
00:00:00.067 [D] P-RadioSpinel-: Wait response: tid=6 key=55
00:00:00.069 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:MAC_RAW_STREAM_ENABLED, enabled:1
00:00:00.069 [I] P-Netif-------: Added multicast address ff02::1
00:00:00.070 [I] P-Netif-------: Added multicast address ff03::1
00:00:00.070 [I] P-Netif-------: Added multicast address ff03::fc
00:00:00.070 [I] P-Netif-------: Sent request#3 to add fe80::e41f:260f:b6:d0e4/64
00:00:00.070 [I] Mle-----------: PeriodicParentSearch: (Re)starting timer for check interval
00:00:00.070 [I] DnssdServer---: Started
00:00:00.070 [I] DnssdServer---: Started discovery proxy
00:00:00.070 [I] BbrLocal------: Remove BBR Service: seqno (60), delay (5s), timeout (3600s), NotFound
00:00:00.070 [N] Mle-----------: Role disabled -> detached
00:00:00.070 [I] P-Netif-------: Sent request#4 to add fd75:edf9:c135:67c8:d5d2:f716:a540:b072/64
00:00:00.070 [I] P-Netif-------: Added multicast address ff32:40:fd75:edf9:c135:67c8:0:1
00:00:00.070 [I] P-Netif-------: Added multicast address ff33:40:fd75:edf9:c135:67c8:0:1
00:00:00.070 [I] P-Netif-------: Sent request#5 to add fd75:edf9:c135:67c8:0:ff:fe00:9800/64
00:00:00.070 [I] Mle-----------: Attempting to restore prev role: leader
00:00:00.071 [I] Notifier------: StateChanged (0x1100102d) [Ip6+ Role LLAddr Rloc+ Ip6Mult+ NetifState ActDset]
00:00:00.071 [I] AnnounceSender: Stopped
00:00:00.071 [N] P-Netif-------: Changing interface state to up.
00:00:00.071 [I] P-Netif-------: Sent request#6 to delete route 192.168.255.0/24
00:00:00.071 [I] P-Netif-------: Deleting route for NAT64
00:00:00.071 [D] P-RadioSpinel-: Trying to get RCP time offset
00:00:00.072 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_GET, key:TIMESTAMP
00:00:00.072 [D] P-RadioSpinel-: Wait response: tid=7 key=2050
00:00:00.075 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:TIMESTAMP, timestamp:458674493
00:00:00.075 [I] Settings------: Read NetworkInfo {rloc:0x9800, extaddr:e61f260f00b6d0e4, role:leader, mode:0x0f, version:5, keyseq:0x0, ...
00:00:00.075 [I] Settings------: ... pid:0x645f6dfa, mlecntr:0x30be, maccntr:0x1bb7, mliid:d5d2f716a540b072}
00:00:00.078 [I] Settings------: Saved NetworkInfo {rloc:0x9800, extaddr:e61f260f00b6d0e4, role:leader, mode:0x0f, version:5, keyseq:0x0, ...
00:00:00.078 [I] Settings------: ... pid:0x645f6dfa, mlecntr:0x34a7, maccntr:0x1f9f, mliid:d5d2f716a540b072}
00:00:00.078 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
00:00:00.078 [I] P-Netif-------: Succeeded to process request#1
00:00:00.079 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:61, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:00.079 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:00.079 [W] P-Netif-------: Failed to process request#2: No such process
00:00:00.079 [I] P-Netif-------: Succeeded to process request#3
00:00:00.080 [I] P-Netif-------: ADD [U] fe80::e41f:260f:b6:d0e4
00:00:00.080 [D] P-Netif-------: Unexpected address type (6).
00:00:00.080 [D] P-Netif-------: Unexpected address type (8).
00:00:00.080 [I] P-Netif-------: Succeeded to process request#4
00:00:00.080 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:d5d2:f716:a540:b072
00:00:00.080 [D] P-Netif-------: Unexpected address type (6).
00:00:00.080 [D] P-Netif-------: Unexpected address type (8).
00:00:00.080 [I] P-Netif-------: Succeeded to process request#5
00:00:00.080 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:0:ff:fe00:9800
00:00:00.081 [D] P-Netif-------: Unexpected address type (6).
00:00:00.081 [D] P-Netif-------: Unexpected address type (8).
00:00:00.081 [I] P-Netif-------: Host netif is up
00:00:00.081 [W] P-Netif-------: Failed to process request#6: No such process
00:00:00.084 [I] P-Netif-------: Message dropped by Thread
00:00:00.085 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.085 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.085 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:00.085 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:00.085 [I] P-Netif-------: ADD [M] ff02::16
00:00:00.085 [I] P-Netif-------: ADD [M] ff02::2 (rejected)
00:00:00.085 [I] Notifier------: StateChanged (0x00001000) [Ip6Mult+]
00:00:00.086 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.086 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.086 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:00.086 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:00.086 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:00.086 [I] P-Netif-------: ADD [M] ff02::2 (rejected)
00:00:00.086 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:00.086 [I] MeshForwarder-: Sent IPv6 UDP msg, len:82, chksum:c642, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:00.086 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:00.086 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
s6-rc: info: service otbr-agent successfully started
s6-rc: info: service legacy-services: starting
s6-rc: info: service legacy-services successfully started
00:00:00.360 [I] P-Netif-------: Message dropped by Thread
00:00:00.360 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.360 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.360 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:00.360 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:00.360 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:00.360 [I] P-Netif-------: ADD [M] ff02::2 (rejected)
00:00:00.361 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.361 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.361 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:00.361 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:00.361 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:00.361 [I] P-Netif-------: ADD [M] ff02::2 (rejected)
00:00:00.584 [I] P-Netif-------: Message dropped by Thread
00:00:00.584 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.584 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.584 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:00.584 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff02::2 (rejected)
00:00:00.585 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:00.585 [I] P-Netif-------: ADD [M] ff02::2 (rejected)
00:00:01.045 [I] TrelDiscoverer: DNS-SD service registered successfully
00:00:01.046 [I] TrelPeerTable-: Added peer otTRELe61f260f00b6d0e4, dnssd-state:resolving
00:00:01.046 [I] TrelDiscoverer: Peer otTRELe61f260f00b6d0e4 is this device itself
00:00:01.046 [I] TrelPeerTable-: Deleted peer otTRELe61f260f00b6d0e4, dnssd-state:resolving
00:00:04.631 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
00:00:04.631 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:61, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:04.631 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:04.639 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:04.639 [I] MeshForwarder-: Sent IPv6 UDP msg, len:82, chksum:b3c9, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:04.639 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:04.639 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:09.577 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
00:00:09.578 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:61, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:09.578 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:09.587 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:09.587 [I] MeshForwarder-: Sent IPv6 UDP msg, len:82, chksum:a822, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:09.587 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:09.587 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:14.486 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
00:00:14.486 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:61, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:14.486 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:14.496 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:14.496 [I] MeshForwarder-: Sent IPv6 UDP msg, len:82, chksum:b352, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:14.496 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:14.496 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:19.630 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
00:00:19.630 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:61, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:19.630 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:19.638 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:19.638 [I] MeshForwarder-: Sent IPv6 UDP msg, len:82, chksum:eb2e, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:19.639 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:19.639 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:25.112 [I] Mle-----------: Send Link Request (ff02:0:0:0:0:0:0:2)
00:00:25.112 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:61, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:25.112 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:25.121 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:25.121 [I] MeshForwarder-: Sent IPv6 UDP msg, len:82, chksum:08ea, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:25.121 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:25.121 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:27.112 [I] Mle-----------: Failed to restore prev role
00:00:27.112 [I] BbrLocal------: Remove BBR Service: seqno (60), delay (5s), timeout (3600s), NotFound
00:00:27.112 [N] Mle-----------: RLOC16 9800 -> fffe
00:00:27.112 [I] P-Netif-------: Sent request#7 to remove fd75:edf9:c135:67c8:0:ff:fe00:9800/64
00:00:27.112 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0xfffe
00:00:27.112 [D] P-RadioSpinel-: Wait response: tid=14 key=53
00:00:27.115 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0xfffe
00:00:27.115 [I] Mle-----------: AttachState Idle -> Start
00:00:27.116 [I] Notifier------: StateChanged (0x00000040) [Rloc-]
00:00:27.116 [I] P-Netif-------: DEL [U] fd75:edf9:c135:67c8:0:ff:fe00:9800 (not found, ignored)
00:00:27.116 [D] P-Netif-------: Unexpected address type (6).
00:00:27.116 [D] P-Netif-------: Unexpected address type (8).
00:00:27.116 [I] P-Netif-------: Succeeded to process request#7
00:00:27.473 [N] Mle-----------: Attach attempt 1, AnyPartition reattaching with Active Dataset
00:00:27.473 [I] Mle-----------: AttachState Start -> ParentReq
00:00:27.473 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2)
00:00:27.473 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:63, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:27.473 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:27.481 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:27.481 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:cb87, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:27.481 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:27.481 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:28.223 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2)
00:00:28.223 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:63, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:28.223 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:28.234 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:28.234 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:58fe, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:28.234 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:28.234 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:28.973 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2)
00:00:28.973 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:63, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:28.973 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:28.982 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:28.982 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:4ac0, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:28.982 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:28.982 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:30.223 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2)
00:00:30.223 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:63, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:30.223 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:30.233 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:30.234 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:5b45, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:30.234 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:30.234 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:31.473 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2)
00:00:31.474 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:63, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:31.474 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:31.487 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:31.487 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:f088, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:31.487 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:31.487 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:32.723 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2)
00:00:32.723 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:63, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:32.723 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:32.731 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:32.731 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:74c3, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:32.731 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:32.731 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:2]:19788
00:00:33.973 [I] Mle-----------: AttachState ParentReq -> Idle
00:00:33.973 [N] RouterTable---: Allocate router id 38
00:00:33.973 [N] Mle-----------: RLOC16 fffe -> 9800
00:00:33.973 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0x9800
00:00:33.973 [D] P-RadioSpinel-: Wait response: tid=6 key=53
00:00:33.976 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0x9800
00:00:33.976 [I] P-Netif-------: Sent request#8 to add fd75:edf9:c135:67c8:0:ff:fe00:9800/64
00:00:33.976 [N] Mle-----------: Role detached -> leader
00:00:33.976 [I] P-Netif-------: Added multicast address ff02::2
00:00:33.976 [I] P-Netif-------: Added multicast address ff03::2
00:00:33.976 [I] P-Netif-------: Sent request#9 to add fd75:edf9:c135:67c8:0:ff:fe00:fc00/64
00:00:33.976 [N] Mle-----------: Partition ID 0x45b287b5
00:00:33.976 [I] RouterTable---: Route table
00:00:33.976 [I] RouterTable---:     38 0x9800 - me - leader
00:00:33.976 [I] Notifier------: StateChanged (0x100012a5) [Ip6+ Role Rloc+ PartitionId NetData Ip6Mult+ ActDset]
00:00:33.976 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
00:00:33.982 [I] Settings------: Saved NetworkInfo {rloc:0x9800, extaddr:e61f260f00b6d0e4, role:leader, mode:0x0f, version:5, keyseq:0x0, ...
00:00:33.982 [I] Settings------: ... pid:0x45b287b5, mlecntr:0x34b3, maccntr:0x1f9f, mliid:d5d2f716a540b072}
00:00:33.982 [I] BbrLeader-----: PBBR state: None
00:00:33.982 [I] AnnounceSender: Started
00:00:33.982 [I] BorderAgent---: Border Agent start listening on port 49154
00:00:33.982 [I] BorderRouting-: RIO Preference changed: low -> medium
00:00:33.982 [I] BorderRouting-: Starting
00:00:33.982 [I] BorderRouting-: RsSender: Starting - will send first RS in 998 msec
00:00:33.982 [I] BorderRouting-: Starting Nat64PrefixManager
00:00:33.982 [I] BorderRouting-: Will evaluate routing policy in 00:03.467 (3467 msec)
00:00:33.982 [I] MultiAilDetect: State: Stopped -> Running
00:00:33.984 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:33.986 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:33.988 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:33.990 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:33.992 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
[INFO]-BA------: Start Thread Border Agent
00:00:33.992 [I] BorderRouting-: Will evaluate routing policy in 00:02.422 (2422 msec)
00:00:33.992 [I] BorderRouting-: NAT64 prefix timer scheduled in 300 seconds
00:00:33.992 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:0:ff:fe00:9800
00:00:33.992 [D] P-Netif-------: Unexpected address type (6).
00:00:33.992 [D] P-Netif-------: Unexpected address type (8).
00:00:33.992 [I] P-Netif-------: Message dropped by Thread
00:00:33.992 [I] P-Netif-------: Succeeded to process request#8
00:00:33.993 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:33.993 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:0:ff:fe00:fc00
00:00:33.993 [D] P-Netif-------: Unexpected address type (6).
00:00:33.993 [D] P-Netif-------: Unexpected address type (8).
00:00:33.994 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:33.994 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:33.994 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:33.994 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:33.994 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:33.994 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:33.994 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:33.994 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:75, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:33.994 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:33.994 [I] P-Netif-------: Succeeded to process request#9
00:00:34.003 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:34.004 [I] MeshForwarder-: Sent IPv6 UDP msg, len:96, chksum:9eda, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:34.004 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:34.004 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:34.261 [I] BbrLocal------: Add BBR Service: seqno (60), delay (5s), timeout (3600s), OK
00:00:34.261 [I] NetworkData---: Sent ServerData
00:00:34.261 [I] NetworkData---: Received ServerData
00:00:34.261 [I] NetworkData---: Allocated Service ID = 0
00:00:34.261 [I] NetworkData---: Sent ServerData ack
00:00:34.261 [I] Notifier------: StateChanged (0x00000200) [NetData]
00:00:34.262 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
00:00:34.262 [I] P-Netif-------: Sent request#10 to add fd75:edf9:c135:67c8:0:ff:fe00:fc10/64
00:00:34.262 [I] BbrLeader-----: PBBR state: Added
00:00:34.262 [I] BbrLeader-----: Rloc16:0x9800, seqno:60, delay:5, timeout:3600
00:00:34.262 [I] P-Netif-------: Sent request#11 to add fd75:edf9:c135:67c8:0:ff:fe00:fc38/64
00:00:34.262 [I] BorderRouting-: Will evaluate routing policy in 00:03.245 (3245 msec)
00:00:34.267 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:34.276 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:34.282 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:34.287 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:34.295 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
00:00:34.296 [I] Notifier------: StateChanged (0x02000001) [Ip6+ BbrState]
00:00:34.296 [I] Bbr-----------: Start listening on port 61631
00:00:34.296 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fd75:edf9:c135:67c8:0:3: OK
00:00:34.296 [I] BbrManager----: Start Backbone TMF agent: OK
00:00:34.308 [I] P-McastRtMgr--: Enable: OK
[DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=2, mBackboneRouterState=1
[NOTE]-BBA-----: BackboneAgent: Backbone Router becomes Primary!
00:00:34.308 [I] P-Netif-------: Message dropped by Thread
00:00:34.308 [I] P-Netif-------: Succeeded to process request#10
00:00:34.311 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:34.311 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.311 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.311 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:34.311 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:34.311 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:34.311 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:34.311 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:91, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:34.311 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:34.311 [I] P-Netif-------: Succeeded to process request#11
00:00:34.313 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:34.313 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.313 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.313 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:34.313 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:34.314 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:34.314 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:34.314 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:0:ff:fe00:fc10
00:00:34.314 [D] P-Netif-------: Unexpected address type (6).
00:00:34.314 [D] P-Netif-------: Unexpected address type (8).
00:00:34.315 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:0:ff:fe00:fc38
00:00:34.315 [D] P-Netif-------: Unexpected address type (6).
00:00:34.315 [D] P-Netif-------: Unexpected address type (8).
00:00:34.321 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:34.321 [I] MeshForwarder-: Sent IPv6 UDP msg, len:112, chksum:ec6f, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:34.321 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:34.321 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:34.517 [I] P-Netif-------: Message dropped by Thread
00:00:34.518 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:34.518 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.518 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.518 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:34.518 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:34.518 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:34.518 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:34.519 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:34.894 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
00:00:34.894 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:34.895 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:34.903 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:34.903 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:f9d0, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:34.903 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:34.903 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:34.980 [I] BorderRouting-: RsSender: Sent RS 1/3
00:00:34.980 [I] BorderRouting-: Received RS from fe80:0:0:0:2a0c:50ff:fe6d:3fe4 on infra netif 6
00:00:35.039 [I] BorderRouting-: Received RA from fe80:0:0:0:6b4:feff:fe14:327f on infra netif 6 
00:00:35.039 [I] BorderRouting-: - RA Header - flags - M:0 O:1 S:0
00:00:35.039 [I] BorderRouting-: - RA Header - default route - lifetime:1800
00:00:35.039 [I] BorderRouting-: - PIO 2a02:3100:287d:c100::/64 (valid:7200, preferred:3600, flags - L:1 A:1 P:0)
00:00:35.039 [I] BorderRouting-: - PIO fda1:e58f:849e:0::/64 (valid:7200, preferred:3600, flags - L:1 A:1 P:0)
00:00:35.039 [I] BorderRouting-: - PIO fd4d:d7b0:b48c:e44e::/64 (valid:0, preferred:0, flags - L:1 A:1 P:0)
00:00:35.039 [I] BorderRouting-: - RDNSS fda1:e58f:849e:0:6b4:feff:fe14:327f (lifetime:1200)
00:00:35.039 [I] BorderRouting-: - RDNSS 2a02:3100:287d:c100:6b4:feff:fe14:327f (lifetime:1200)
00:00:35.039 [I] BorderRouting-: - RIO ::/0 (lifetime:1800, prf:medium)
00:00:35.039 [I] BorderRouting-: - RIO 2a02:3100:287d:c100::/56 (lifetime:1800, prf:medium)
00:00:35.039 [I] BorderRouting-: - RIO fda1:e58f:849e:0::/64 (lifetime:1800, prf:medium)
00:00:35.040 [I] P-Resolver----: Set recursive DNS server list, count: 2
00:00:36.325 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
00:00:36.325 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:36.325 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:36.335 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:36.335 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:a1ae, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:36.335 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:36.335 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:36.414 [I] BorderRouting-: Evaluating routing policy
00:00:36.414 [I] BorderRouting-: Setting local OMR prefix to generated prefix: fdc4:8ef3:402e:1::/64
00:00:36.414 [I] BorderRouting-: Added local OMR prefix fdc4:8ef3:402e:1::/64 (def-route:no) in Thread Network Data
00:00:36.414 [I] BorderRouting-: Favored OMR prefix: (none) -> fdc4:8ef3:402e:1::/64 (prf:low, local)
00:00:36.414 [I] BorderRouting-: RoutePublisher state: none -> def-route
00:00:36.414 [I] NetDataPublshr: Publishing ExternalRoute ::/0
00:00:36.414 [I] NetDataPublshr: ExternalRoute ::/0 - State: NoEntry -> ToAdd
00:00:36.414 [I] NetDataPublshr: ExternalRoute ::/0 (state:ToAdd) in netdata - total:0, preferred:0, desired:10
00:00:36.414 [I] NetDataPublshr: ExternalRoute ::/0 - State: ToAdd -> Adding
00:00:36.414 [I] NetDataPublshr: ExternalRoute ::/0 (state:Adding) - update in 1713 msec
00:00:36.414 [I] BorderRouting-: Updated local OMR prefix fdc4:8ef3:402e:1::/64 (def-route:yes) in Thread Network Data
00:00:36.414 [I] BorderRouting-: Evaluating NAT64 prefix
00:00:36.414 [I] NetDataPublshr: Publishing ExternalRoute fdc4:8ef3:402e:2:0:0::/96
00:00:36.414 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 - State: NoEntry -> ToAdd
00:00:36.414 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 (state:ToAdd) in netdata - total:0, preferred:0, desired:10
00:00:36.414 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 - State: ToAdd -> Adding
00:00:36.414 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 (state:Adding) - update in 1891 msec
00:00:36.414 [I] Nat64---------: NAT64 Prefix: ::/0 -> fdc4:8ef3:402e:2:0:0::/96
00:00:36.414 [I] Nat64---------: State: NotRunning -> Active
00:00:36.414 [I] BorderRouting-: Will evaluate routing policy in 00:14.808 (14808 msec)
00:00:36.414 [I] NetworkData---: Sent ServerData
00:00:36.414 [I] Notifier------: StateChanged (0x40000000) [Nat64]
00:00:36.414 [I] P-Netif-------: Sent request#12 to add route 192.168.255.0/24
00:00:36.414 [I] P-Netif-------: Adding route for NAT64
00:00:36.414 [I] NetworkData---: Received ServerData
00:00:36.414 [I] NetworkData---: Sent ServerData ack
00:00:36.414 [I] P-Netif-------: Succeeded to process request#12
00:00:36.414 [I] Notifier------: StateChanged (0x00000200) [NetData]
00:00:36.415 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
00:00:36.415 [I] BbrLeader-----: PBBR state: Unchanged
00:00:36.415 [I] BbrLeader-----: Rloc16:0x9800, seqno:60, delay:5, timeout:3600
00:00:36.415 [I] Settings------: Read SlaacIidSecretKey
00:00:36.415 [I] Slaac---------: Adding fdc4:8ef3:402e:1:8901:2c40:cd2b:b341
00:00:36.415 [I] P-Netif-------: Sent request#13 to add fdc4:8ef3:402e:1:8901:2c40:cd2b:b341/64
00:00:36.415 [I] BorderRouting-: Will evaluate routing policy in 00:03.867 (3867 msec)
00:00:36.415 [I] NetDataPublshr: ExternalRoute ::/0 (state:Adding) in netdata - total:0, preferred:0, desired:10
00:00:36.415 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 (state:Adding) in netdata - total:0, preferred:0, desired:10
00:00:36.417 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:36.418 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:36.420 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:36.421 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:36.422 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:36.424 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:36.425 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
00:00:36.425 [I] Notifier------: StateChanged (0x00000001) [Ip6+]
00:00:36.425 [I] P-Netif-------: Message dropped by Thread
00:00:36.425 [I] P-Netif-------: Succeeded to process request#13
00:00:36.426 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:36.426 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.426 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.426 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:36.426 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:36.426 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:36.426 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:36.427 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:113, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:36.427 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:36.427 [I] P-Netif-------: ADD [U] fdc4:8ef3:402e:1:8901:2c40:cd2b:b341
00:00:36.427 [D] P-Netif-------: Unexpected address type (6).
00:00:36.427 [D] P-Netif-------: Unexpected address type (8).
00:00:36.428 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:36.428 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.428 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.428 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:36.428 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:36.428 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:36.428 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:36.439 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:36.439 [I] MeshForwarder-: Sent IPv6 UDP msg, len:134, chksum:cf5e, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:36.439 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:36.439 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:36.905 [I] P-Netif-------: Message dropped by Thread
00:00:36.906 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:36.906 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.906 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.906 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:36.906 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:36.906 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:36.906 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:36.907 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:38.127 [I] NetDataPublshr: ExternalRoute ::/0 - State: Adding -> Added
00:00:38.127 [I] NetworkData---: Sent ServerData
00:00:38.127 [I] NetworkData---: Received ServerData
00:00:38.127 [I] NetworkData---: Sent ServerData ack
00:00:38.127 [I] Notifier------: StateChanged (0x00000200) [NetData]
00:00:38.127 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
00:00:38.127 [I] BbrLeader-----: PBBR state: Unchanged
00:00:38.127 [I] BbrLeader-----: Rloc16:0x9800, seqno:60, delay:5, timeout:3600
00:00:38.127 [I] BorderRouting-: Will evaluate routing policy in 00:02.025 (2025 msec)
00:00:38.127 [I] NetDataPublshr: ExternalRoute ::/0 (state:Added) in netdata - total:1, preferred:0, desired:10
00:00:38.127 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 (state:Adding) in netdata - total:0, preferred:0, desired:10
00:00:38.129 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:38.130 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:38.132 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:38.133 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:38.134 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:38.135 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:38.137 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
00:00:38.137 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:38.137 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:38.151 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:38.151 [I] MeshForwarder-: Sent IPv6 UDP msg, len:143, chksum:1224, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:38.151 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:38.151 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:38.262 [I] MlrManager----: MLR Reregister!
00:00:38.305 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 - State: Adding -> Added
00:00:38.305 [I] NetworkData---: Sent ServerData
00:00:38.305 [I] NetworkData---: Received ServerData
00:00:38.305 [I] NetworkData---: Sent ServerData ack
00:00:38.305 [I] Notifier------: StateChanged (0x00000200) [NetData]
00:00:38.305 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
00:00:38.306 [I] BbrLeader-----: PBBR state: Unchanged
00:00:38.306 [I] BbrLeader-----: Rloc16:0x9800, seqno:60, delay:5, timeout:3600
00:00:38.306 [I] BorderRouting-: Will evaluate routing policy in 00:03.884 (3884 msec)
00:00:38.306 [I] NetDataPublshr: ExternalRoute ::/0 (state:Added) in netdata - total:1, preferred:0, desired:10
00:00:38.306 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 (state:Added) in netdata - total:1, preferred:0, desired:10
00:00:38.310 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:38.312 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:38.317 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:38.326 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:38.331 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:38.338 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:38.345 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
00:00:38.346 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:121, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:38.346 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:38.360 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:38.363 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:68, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:38.363 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:38.369 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:38.369 [I] MeshForwarder-: Sent IPv6 UDP msg, len:164, chksum:5687, ecn:no, to:0xffff, sec:yes, prio:net, radio:all
00:00:38.369 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:38.369 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:38.980 [I] BorderRouting-: RsSender: Sent RS 2/3
00:00:38.981 [I] BorderRouting-: Received RS from fe80:0:0:0:2a0c:50ff:fe6d:3fe4 on infra netif 6
00:00:39.040 [I] BorderRouting-: Received RA from fe80:0:0:0:6b4:feff:fe14:327f on infra netif 6 
00:00:39.040 [I] BorderRouting-: - RA Header - flags - M:0 O:1 S:0
00:00:39.040 [I] BorderRouting-: - RA Header - default route - lifetime:1800
00:00:39.040 [I] BorderRouting-: - PIO 2a02:3100:287d:c100::/64 (valid:7200, preferred:3600, flags - L:1 A:1 P:0)
00:00:39.040 [I] BorderRouting-: - PIO fda1:e58f:849e:0::/64 (valid:7200, preferred:3600, flags - L:1 A:1 P:0)
00:00:39.040 [I] BorderRouting-: - PIO fd4d:d7b0:b48c:e44e::/64 (valid:0, preferred:0, flags - L:1 A:1 P:0)
00:00:39.040 [I] BorderRouting-: - RDNSS fda1:e58f:849e:0:6b4:feff:fe14:327f (lifetime:1200)
00:00:39.040 [I] BorderRouting-: - RDNSS 2a02:3100:287d:c100:6b4:feff:fe14:327f (lifetime:1200)
00:00:39.040 [I] BorderRouting-: - RIO ::/0 (lifetime:1800, prf:medium)
00:00:39.040 [I] BorderRouting-: - RIO 2a02:3100:287d:c100::/56 (lifetime:1800, prf:medium)
00:00:39.040 [I] BorderRouting-: - RIO fda1:e58f:849e:0::/64 (lifetime:1800, prf:medium)
00:00:39.760 [I] P-Daemon------: Session socket is ready
00:00:39.760 [D] Cli-----------: Input: ping 9.9.9.9
00:00:39.761 [D] Cli-----------: Output: Pinging synthesized IPv6 address: fdc4:8ef3:402e:2:0:0:909:909
00:00:39.761 [I] Icmp6---------: Sent echo request: (seq = 1)
00:00:39.761 [I] Nat64---------: Mapping created: fdc4:8ef3:402e:1:8901:2c40:cd2b:b341 -> 192.168.255.1
00:00:39.772 [D] Cli-----------: Output: 16 bytes from fdc4:8ef3:402e:2:0:0:909:909: icmp_seq=1 hlim=56 time=11ms
00:00:39.772 [D] Cli-----------: Output: 1 packets transmitted, 1 packets received. Packet loss = 0.0%. Round-trip min/avg/max = 11/11.000/11 ms.
00:00:39.772 [D] Cli-----------: Output: Done
00:00:40.152 [I] BorderRouting-: Evaluating routing policy
00:00:40.152 [I] BorderRouting-: Evaluating NAT64 prefix
00:00:40.152 [I] BorderRouting-: Will evaluate routing policy in 00:16.515 (16515 msec)
00:00:40.345 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
00:00:40.345 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:40.345 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:40.355 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:40.355 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:ad88, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:40.355 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:40.356 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:41.676 [I] P-Daemon------: Session socket is ready
00:00:41.676 [D] Cli-----------: Input: ping 9.9.9.9
00:00:41.676 [D] Cli-----------: Output: Pinging synthesized IPv6 address: fdc4:8ef3:402e:2:0:0:909:909
00:00:41.676 [I] Icmp6---------: Sent echo request: (seq = 2)
00:00:41.687 [D] Cli-----------: Output: 16 bytes from fdc4:8ef3:402e:2:0:0:909:909: icmp_seq=2 hlim=56 time=11ms
00:00:41.687 [D] Cli-----------: Output: 1 packets transmitted, 1 packets received. Packet loss = 0.0%. Round-trip min/avg/max = 11/11.000/11 ms.
00:00:41.687 [D] Cli-----------: Output: Done
00:00:42.599 [I] P-Daemon------: Session socket is ready
00:00:42.599 [D] Cli-----------: Input: ping 9.9.9.9
00:00:42.599 [D] Cli-----------: Output: Pinging synthesized IPv6 address: fdc4:8ef3:402e:2:0:0:909:909
00:00:42.599 [I] Icmp6---------: Sent echo request: (seq = 3)
00:00:42.610 [D] Cli-----------: Output: 16 bytes from fdc4:8ef3:402e:2:0:0:909:909: icmp_seq=3 hlim=56 time=11ms
00:00:42.610 [D] Cli-----------: Output: 1 packets transmitted, 1 packets received. Packet loss = 0.0%. Round-trip min/avg/max = 11/11.000/11 ms.
00:00:42.610 [D] Cli-----------: Output: Done
00:00:42.610 [W] P-Daemon------: Daemon read: Connection reset by peer
00:00:42.981 [I] BorderRouting-: RsSender: Sent RS 3/3
00:00:42.981 [I] BorderRouting-: Received RS from fe80:0:0:0:2a0c:50ff:fe6d:3fe4 on infra netif 6
00:00:43.035 [I] BorderRouting-: Received RA from fe80:0:0:0:6b4:feff:fe14:327f on infra netif 6 
00:00:43.035 [I] BorderRouting-: - RA Header - flags - M:0 O:1 S:0
00:00:43.035 [I] BorderRouting-: - RA Header - default route - lifetime:1800
00:00:43.035 [I] BorderRouting-: - PIO 2a02:3100:287d:c100::/64 (valid:7200, preferred:3600, flags - L:1 A:1 P:0)
00:00:43.035 [I] BorderRouting-: - PIO fda1:e58f:849e:0::/64 (valid:7200, preferred:3600, flags - L:1 A:1 P:0)
00:00:43.035 [I] BorderRouting-: - PIO fd4d:d7b0:b48c:e44e::/64 (valid:0, preferred:0, flags - L:1 A:1 P:0)
00:00:43.035 [I] BorderRouting-: - RDNSS fda1:e58f:849e:0:6b4:feff:fe14:327f (lifetime:1200)
00:00:43.035 [I] BorderRouting-: - RDNSS 2a02:3100:287d:c100:6b4:feff:fe14:327f (lifetime:1200)
00:00:43.035 [I] BorderRouting-: - RIO ::/0 (lifetime:1800, prf:medium)
00:00:43.035 [I] BorderRouting-: - RIO 2a02:3100:287d:c100::/56 (lifetime:1800, prf:medium)
00:00:43.035 [I] BorderRouting-: - RIO fda1:e58f:849e:0::/64 (lifetime:1800, prf:medium)
00:00:43.981 [I] BorderRouting-: RsSender: Finished sending RS msgs and waiting for RAs
00:00:43.981 [I] BorderRouting-: Will evaluate routing policy in 00:02.829 (2829 msec)
00:00:43.981 [I] BorderRouting-: Will evaluate routing policy immediately
00:00:43.981 [I] BorderRouting-: Evaluating routing policy
00:00:43.981 [I] BorderRouting-: AIL on-link prefix (from processed RAs) changed: ::/0 -> 2a02:3100:287d:c100::/64
00:00:43.981 [I] BorderRouting-: Favored on-link prefix: ::/0 -> 2a02:3100:287d:c100::/64
00:00:43.981 [I] BorderRouting-: Evaluating NAT64 prefix
00:00:43.981 [I] BorderRouting-: Preparing RA
00:00:43.981 [I] BorderRouting-: - RA Header - flags - M:0 O:1 S:1
00:00:43.981 [I] BorderRouting-: - RA Header - default route - lifetime:0
00:00:43.982 [I] BorderRouting-: - RIO fdc4:8ef3:402e:1::/64 (lifetime:1800, prf:medium)
00:00:43.982 [I] BorderRouting-: Sent RA on infra netif 6
00:00:43.982 [I] SrpServer-----: Selected port 53536
00:00:43.982 [I] NetDataPublshr: Publishing DNS/SRP service unicast (ml-eid, port:53536, ver:0)
00:00:43.982 [I] NetDataPublshr: DNS/SRP service - State: NoEntry -> ToAdd
00:00:43.982 [I] NetDataPublshr: DNS/SRP service (state:ToAdd) in netdata - total:0, preferred:0, desired:2
00:00:43.982 [I] NetDataPublshr: DNS/SRP service - State: ToAdd -> Adding
00:00:43.982 [I] NetDataPublshr: DNS/SRP service (state:Adding) - update in 292 msec
00:00:43.982 [I] BorderRouting-: Will evaluate routing policy in 00:15.476 (15476 msec)
00:00:43.987 [I] BorderRouting-: Received RA from fe80:0:0:0:2a0c:50ff:fe6d:3fe4 on infra netif 6 (this BR routing-manager)
00:00:44.274 [I] NetDataPublshr: DNS/SRP service - State: Adding -> Added
00:00:44.274 [I] SrpServer-----: Start listening on port 53536
00:00:44.274 [I] SrpAdvProxy---: Started
00:00:44.274 [I] NetworkData---: Sent ServerData
00:00:44.274 [I] NetworkData---: Received ServerData
00:00:44.274 [I] NetworkData---: Allocated Service ID = 1
00:00:44.274 [I] NetworkData---: Sent ServerData ack
00:00:44.274 [I] Notifier------: StateChanged (0x00000200) [NetData]
00:00:44.274 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1)
00:00:44.275 [I] P-Netif-------: Sent request#14 to add fd75:edf9:c135:67c8:0:ff:fe00:fc11/64
00:00:44.275 [I] BbrLeader-----: PBBR state: Unchanged
00:00:44.275 [I] BbrLeader-----: Rloc16:0x9800, seqno:60, delay:5, timeout:3600
00:00:44.275 [I] BorderRouting-: Will evaluate routing policy in 00:03.468 (3468 msec)
00:00:44.275 [I] SrpClient-----: AutoStartState 1stTimeSelect -> Unicast
00:00:44.275 [I] SrpClient-----: Requesting max tx jitter 10000 (OnServerStart)
00:00:44.275 [I] SrpClient-----: Auto-starting, server [fd75:edf9:c135:67c8:d5d2:f716:a540:b072]:53536
00:00:44.275 [I] SrpClient-----: State Stopped -> Updated
00:00:44.275 [I] NetDataPublshr: DNS/SRP service (state:Added) in netdata - total:1, preferred:0, desired:2
00:00:44.275 [I] NetDataPublshr: ExternalRoute ::/0 (state:Added) in netdata - total:1, preferred:0, desired:10
00:00:44.275 [I] NetDataPublshr: ExternalRoute fdc4:8ef3:402e:2:0:0::/96 (state:Added) in netdata - total:1, preferred:0, desired:10
00:00:44.280 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
00:00:44.282 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
00:00:44.284 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:44.287 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fd75:edf9:c135:67c8::/64 -exist` = 0
00:00:44.288 [I] Platform------: Execute command `ipset add otbr-ingress-allow-dst-swap fdc4:8ef3:402e:1::/64 -exist` = 0
00:00:44.290 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
00:00:44.292 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
00:00:44.293 [I] Notifier------: StateChanged (0x00000001) [Ip6+]
00:00:44.293 [I] P-Netif-------: Message dropped by Thread
00:00:44.293 [I] P-Netif-------: Succeeded to process request#14
00:00:44.294 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:44.294 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:44.294 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:44.294 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:44.294 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:44.294 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:44.294 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:44.294 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:121, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:44.294 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:44.294 [I] P-Netif-------: ADD [U] fd75:edf9:c135:67c8:0:ff:fe00:fc11
00:00:44.294 [D] P-Netif-------: Unexpected address type (6).
00:00:44.294 [D] P-Netif-------: Unexpected address type (8).
00:00:44.295 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:44.295 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:44.295 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:44.295 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:44.295 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:44.295 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:44.295 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:44.307 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:44.307 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:95, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:44.307 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:44.319 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:44.319 [I] MeshForwarder-: Sent IPv6 UDP msg, len:191, chksum:0337, ecn:no, to:0xffff, sec:yes, prio:net, radio:all
00:00:44.319 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:44.319 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:45.130 [I] P-Netif-------: Message dropped by Thread
00:00:45.133 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:45.133 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:45.133 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:45.133 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:45.133 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:45.133 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:45.133 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff03::2 (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff33:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff32:40:fd75:edf9:c135:67c8:0:1 (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff03::fc (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff03::1 (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff02::16 (already subscribed, ignored)
00:00:45.135 [I] P-Netif-------: ADD [M] ff02::2 (already subscribed, ignored)
00:00:47.433 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:78, rssi:-79 ...
00:00:47.433 [D] P-SpinelDrive-: ... noise:-128, flags:0x0000, channel:11, lqi:255, timestamp:73520465249, rxerr:0
00:00:47.433 [I] MeshForwarder-: Received IPv6 UDP msg, len:83, chksum:494c, ecn:no, from:7286cdd2e447f3a6, sec:no, prio:net, rss:-79.0, radio:15.4
00:00:47.433 [I] MeshForwarder-:     src:[fe80:0:0:0:7086:cdd2:e447:f3a6]:19788
00:00:47.433 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788
00:00:47.743 [I] BorderRouting-: Evaluating routing policy
00:00:47.743 [I] BorderRouting-: Evaluating NAT64 prefix
00:00:47.743 [I] BorderRouting-: Preparing RA
00:00:47.743 [I] BorderRouting-: - RA Header - flags - M:0 O:1 S:1
00:00:47.743 [I] BorderRouting-: - RA Header - default route - lifetime:0
00:00:47.743 [I] BorderRouting-: - RIO fdc4:8ef3:402e:1::/64 (lifetime:1800, prf:medium)
00:00:47.743 [I] BorderRouting-: Sent RA on infra netif 6
00:00:47.743 [I] BorderRouting-: Will evaluate routing policy in 00:14.124 (14124 msec)
00:00:47.748 [I] BorderRouting-: Received RA from fe80:0:0:0:2a0c:50ff:fe6d:3fe4 on infra netif 6 (this BR routing-manager)
00:00:48.429 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1)
00:00:48.429 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:11, maxbackoffs:4, maxretries:15 ...
00:00:48.429 [D] P-SpinelDrive-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0
00:00:48.437 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
00:00:48.437 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:46a4, ecn:no, to:0xffff, sec:no, prio:net, radio:all
00:00:48.437 [I] MeshForwarder-:     src:[fe80:0:0:0:e41f:260f:b6:d0e4]:19788
00:00:48.437 [I] MeshForwarder-:     dst:[ff02:0:0:0:0:0:0:1]:19788

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

OpenThread

OTBR: Can ping IPv4 (via NAT64) but not plain IPv6 #12302

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{editor}}'s edit

{{editor}}'s edit

Uh oh!

Replies: 0 comments

Select a reply

Uh oh!

OpenThread

OTBR: Can ping IPv4 (via NAT64) but not plain IPv6 #12302

Uh oh!

Uh oh!

schildbach Jan 15, 2026

Replies: 0 comments

schildbach
Jan 15, 2026