Open chatref opened 1 year ago
It appears that your Linux kernel does not have multicast routing support.
You can either enable multicast routing kernel module or disable multicast routing feature (OPENTHREAD_CONFIG_BACKBONE_ROUTER_MULTICAST_ROUTING_ENABLE
) in OTBR build.
otbr docker arm64 image shutting down wpan0 interface after network is formed successfully.
I am using arm64 docker image from docker hub, with below image id
858bc1fc0888 sha256:858bc1fc0888d83d47a9b2f650d848c23c00c4c42393522e58d79d2cdc0668f6
docker command i used is
docker run -d --network=host --sysctl "net.ipv6.conf.all.disable_ipv6=0 net.ipv4.conf.all.forwarding=1 net.ipv6.conf.all.forwarding=1" -p 8081:80 -it --volume /dev/ttyACM0:/dev/ttyACM0 --privileged -e BACKBONE_INTERFACE=br1 latest-300mb-image:matter-test --radio-url spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=460800
i am running it on machine with below details
uname -r
4.19.183
uname -a
Linux dsldevice 4.19.183 #2 SMP PREEMPT Mon Feb 20 11:45:42 EST 2023 aarch64
everything is working fine until thread start command or network is successfully formed, as soon as i start the thread network, it is getting started and after that it is shutting down wpan0, as per my view from docker logs, there is a signal coming from kernel,
docker logs e77d350d1cb6
RADIO_URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=460800 TREL_URL: TUN_INTERFACE_NAME: wpan0 BACKBONE_INTERFACE: br1 NAT64_PREFIX: 64:ff9b::/96 +++ 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
ipset flush otbr-ingress-allow-dst-swap
= 0 Jan 2 02:54:56 dsldevice otbr-agent[197]: 00:00:00.098 [I] Platform------: Execute commandipset flush otbr-ingress-deny-src-swap
= 0 Jan 2 02:54:56 dsldevice otbr-agent[197]: 00:00:00.105 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist
= 0 Jan 2 02:54:56 dsldevice otbr-agent[197]: 00:00:00.108 [I] Platform------: Execute commandipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src
= 0 Jan 2 02:54:56 dsldevice otbr-agent[197]: 00:00:00.112 [I] Platform------: Execute commandipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst
= 0 Jan 2 02:54:56 dsldevice otbr-agent[197]: 00:00:00.112 [I] Platform------: MulticastRoutingManager: Disable: OK Jan 2 02:54:56 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #F498._meshcop._udp.local. Jan 2 02:54:56 dsldevice otbr-agent[197]: [DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=1, mBackboneRouterState=0 Jan 2 02:54:56 dsldevice otbr-agent[197]: 00:00:00.126 [I] Platform------: [netif] Host netif is down Jan 2 02:54:57 dsldevice rsyslogd: rsyslogd's groupid changed to 101 Jan 2 02:54:57 dsldevice rsyslogd: rsyslogd's userid changed to 101 Jan 2 02:54:57 dsldevice rsyslogd: [origin software="rsyslogd" swVersion="8.32.0" x-pid="102" x-info="http://www.rsyslog.com"] start Jan 2 02:54:57 dsldevice avahi-daemon[169]: Server startup complete. Host name is dsldevice.local. Local service cookie is 1059031180. Jan 2 02:54:57 dsldevice otbr-agent[197]: [INFO]-MDNS----: Received reply for service OpenThread BorderRouter #F498._meshcop._udp., serviceRef = 0x55861005d0 Jan 2 02:54:57 dsldevice otbr-agent[197]: [INFO]-MDNS----: Successfully registered service OpenThread BorderRouter #F498._meshcop._udp. Jan 2 02:54:57 dsldevice otbr-agent[197]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #F498._meshcop._udp.local: OK Jan 2 02:54:57 dsldevice otbr-agent[197]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #F498._meshcop._udp.local: OK Jan 2 02:59:07 dsldevice otbr-agent[197]: 00:04:11.376 [I] Platform------: Session socket is ready Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:04:11.652 [I] Platform------: > #012factoryreset Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:04:11.652 [D] Cli-----------: Input: factoryreset Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:04:11.652 [I] Settings------: Wiped all info Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-BA------: Stop Thread Border Agent Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-ADPROXY-: Stopped Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-DPROXY--: Stopped Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-BA------: Unpublish meshcop service OpenThread BorderRouter #F498._meshcop._udp.local Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #F498._meshcop._udp Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-BA------: Result of unpublish meshcop service OpenThread BorderRouter #F498._meshcop._udp.local: OK Jan 2 02:59:08 dsldevice kernel: [97149.471685] mesh: Jan 2 02:59:08 dsldevice kernel: [97149.471689] NETDEV_UNREGISTER: wpan0 Jan 2 02:59:08 dsldevice kernel: [97149.471697] (netdevice_notifier_call mesh.c:951) Jan 2 02:59:08 dsldevice otbr-agent: [NOTE]-AGENT---: Backbone interface: br1 Jan 2 02:59:08 dsldevice otbr-agent[197]: [NOTE]-AGENT---: Running 0.3.0-80d4c7b Jan 2 02:59:08 dsldevice otbr-agent[197]: [NOTE]-AGENT---: Thread version: 1.3.0 Jan 2 02:59:08 dsldevice otbr-agent[197]: [NOTE]-AGENT---: Thread interface: wpan0 Jan 2 02:59:08 dsldevice otbr-agent[197]: [NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0?uart-baudrate=460800 Jan 2 02:59:08 dsldevice otbr-agent[197]: [NOTE]-ILS-----: Infra link selected: br1 Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-NCP-----: OpenThread log level changed to 5 Jan 2 02:59:08 dsldevice otbr-agent[197]: 50d.20:01:55.621 [I] Platform------: RCP reset: RESET_POWER_ON Jan 2 02:59:08 dsldevice otbr-agent[197]: 50d.20:01:55.666 [I] Platform------: Backbone interface is configured to br1 (29) Jan 2 02:59:08 dsldevice kernel: [97149.545874] mesh::error: Jan 2 02:59:08 dsldevice kernel: [97149.545879] [wpan0] unknown event (0x10) received Jan 2 02:59:08 dsldevice kernel: [97149.545885] (netdevice_notifier_call mesh.c:1148) Jan 2 02:59:08 dsldevice kernel: [97149.546348] mesh: Jan 2 02:59:08 dsldevice kernel: [97149.546355] NETDEV_REGISTER: wpan0 (unknown) Jan 2 02:59:08 dsldevice kernel: [97149.546408] (netdevice_notifier_call mesh.c:984) Jan 2 02:59:08 dsldevice kernel: [97149.546413] mesh: Jan 2 02:59:08 dsldevice kernel: [97149.546414] dev: wpan0 does not belong to a bridge Jan 2 02:59:08 dsldevice kernel: [97149.546417] (mesh_nodes_add_dev_by_name nodes.c:108) Jan 2 02:59:08 dsldevice kernel: [97149.546418] mesh: Jan 2 02:59:08 dsldevice kernel: [97149.546420] dev: wpan0 is not added as a mesh device Jan 2 02:59:08 dsldevice kernel: [97149.546422] (netdevice_notifier_call mesh.c:1002) Jan 2 02:59:08 dsldevice kernel: [97149.550615] mesh::error: Jan 2 02:59:08 dsldevice kernel: [97149.550619] [wpan0] unknown event (0x16) received Jan 2 02:59:08 dsldevice kernel: [97149.550627] (netdevice_notifier_call mesh.c:1148) Jan 2 02:59:08 dsldevice kernel: [97149.555571] mesh: Jan 2 02:59:08 dsldevice kernel: [97149.555574] NETDEV_CHANGEMTU: wpan0 Jan 2 02:59:08 dsldevice kernel: [97149.555581] (netdevice_notifier_call mesh.c:1071) Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.076 [I] InfraIf-------: Init infra netif 29 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.076 [N] BorderRouter--: No valid /48 BR ULA prefix found in settings, generating new one Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.080 [I] Settings------: Saved BrUlaPrefix fd75:4d32:e31d::/48 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.080 [N] BorderRouter--: BR ULA prefix: fd75:4d32:e31d::/48 (generated) Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.080 [I] BorderRouter--: Generated OMR prefix: fd75:4d32:e31d:1::/64 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.080 [I] BorderRouter--: Generated local NAT64 prefix: fd75:4d32:e31d:2:0:0::/96 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.080 [N] BorderRouter--: Local on-link prefix: fdde:ad00:beef:cafe::/64 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.080 [I] InfraIf-------: State changed: NOT RUNNING -> RUNNING Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-UTILS---: Set state callback: OK Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.081 [I] Nat64---------: NAT64 translator is now NotRunning Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #317C._meshcop._udp.local, serviceRef = (nil) Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-ADPROXY-: Publish all hosts and services Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-ADPROXY-: Started Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-DPROXY--: Started Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-BA------: Start Thread Border Agent: OK Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.082 [I] BbrLocal------: Add Domain Prefix: ::/0, NotFound Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.082 [I] BbrLocal------: Add BBR Service: seqno (36), delay (5s), timeout (3600s), InvalidState Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-APP-----: Thread Border Router started on AIL br1. Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.086 [I] Notifier------: StateChanged (0x42038200) [NetData PanId NetName ExtPanId BbrState (unknown)] Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.086 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fdde:ad00:beef:0:0:3: OK Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.086 [I] BbrManager----: Start Backbone TMF agent: OK Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.090 [I] Platform------: Execute commandipset flush otbr-ingress-allow-dst-swap
= 0 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.093 [I] Platform------: Execute commandipset flush otbr-ingress-deny-src-swap
= 0 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.097 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist
= 0 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.100 [I] Platform------: Execute commandipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src
= 0 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.105 [I] Platform------: Execute commandipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst
= 0 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.105 [I] Platform------: MulticastRoutingManager: Disable: OK Jan 2 02:59:08 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:08 dsldevice otbr-agent[197]: [DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=1, mBackboneRouterState=0 Jan 2 02:59:08 dsldevice otbr-agent[197]: 00:00:00.115 [I] Platform------: [netif] Host netif is down Jan 2 02:59:09 dsldevice otbr-agent[197]: [INFO]-MDNS----: Received reply for service OpenThread BorderRouter #317C._meshcop._udp., serviceRef = 0x55acef45d0 Jan 2 02:59:09 dsldevice otbr-agent[197]: [INFO]-MDNS----: Successfully registered service OpenThread BorderRouter #317C._meshcop._udp. Jan 2 02:59:09 dsldevice otbr-agent[197]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local: OK Jan 2 02:59:09 dsldevice otbr-agent[197]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local: OK Jan 2 02:59:09 dsldevice mDNSResponder: Default: mDNS_Execute: SendResponses didn't send all its responses; will try again in one second Jan 2 02:59:11 dsldevice mDNSResponder: message repeated 2 times: [ Default: mDNS_Execute: SendResponses didn't send all its responses; will try again in one second] Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.883 [I] Platform------: Session socket is ready Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.884 [I] Platform------: > #012version Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.884 [D] Cli-----------: Input: version Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.884 [D] Cli-----------: Output: OPENTHREAD/eb6377f; POSIX; Feb 6 2023 19:25:28 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.884 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.884 [I] Platform------: > #012dataset init new Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.884 [D] Cli-----------: Input: dataset init new Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [I] Platform------: > #012dataset networkkey 00112233445566778899aabbccddeeff Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Input: dataset networkkey 00112233445566778899aabbccddeeff Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [I] Platform------: > #012dataset networkname OpenThreadDemo Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Input: dataset networkname OpenThreadDemo Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [I] Platform------: > #012dataset pskc DD09EF8B0BA3EC698EB629C2694C480C Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Input: dataset pskc DD09EF8B0BA3EC698EB629C2694C480C Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [I] Platform------: > #012dataset channel 15 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Input: dataset channel 15 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [I] Platform------: > #012dataset extpanid 1111111199999999 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Input: dataset extpanid 1111111199999999 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [I] Platform------: > #012dataset panid 25957 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Input: dataset panid 25957 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.891 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.892 [I] Platform------: > #012dataset commit active Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.892 [D] Cli-----------: Input: dataset commit active Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.895 [I] Settings------: Saved ActiveDataset Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.895 [I] DatasetLocal--: Active dataset set Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.917 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.917 [I] Notifier------: StateChanged (0x101fc100) [KeySeqCntr Channel PanId NetName ExtPanId NetworkKey PSKc SecPolicy ActDset] Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.917 [I] AnnounceSender: ChannelMask:{ 11-26 }, period:21500 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.917 [I] AnnounceSender: StartingChannel:15 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.917 [I] AnnounceSender: StartingChannel:15 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.917 [N] BorderRouter--: Local on-link prefix: fd11:1111:1199:9999::/64 Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing existing service OpenThread BorderRouter #317C._meshcop._udp: outdated Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #317C._meshcop._udp Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #317C._meshcop._udp.local, serviceRef = (nil) Jan 2 02:59:12 dsldevice otbr-agent[197]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.ActiveDatasetTlvs Jan 2 02:59:12 dsldevice otbr-agent[197]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ ActiveDatasetTlvs:{ [ 14, 8, 0, 0, 0, 0, 0, 1, 0, 0, 0, 3, 0, 0, 15, 53, 6, 0, 4, 0, 31, 255, 224, 2, 8, 17, 17, 17, 17, 153, 153, 153, 153, 7, 8, 253, 146, 40, 31, 34, 106, 118, 74, 5, 16, 0, 17, 34, 51, 68, 85, 102, 119, 136, 153, 170, 187, 204, 221, 238, 255, 3, 14, 79, 112, 101, 110, 84, 104, 114, 101, 97, 100, 68, 101, 109, 111, 1, 2, 101, 101, 4, 16, 221, 9, 239, 139, 11, 163, 236, 105, 142, 182, 41, 194, 105, 76, 72, 12, 12, 4, 2, 160, 247, 248, ], }, ], [ ], } Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.918 [I] Platform------: > #012ifconfig up Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.918 [D] Cli-----------: Input: ifconfig up Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [I] Platform------: [netif] Added multicast address ff02::1 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [I] Platform------: [netif] Added multicast address ff03::1 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [I] Platform------: [netif] Added multicast address ff03::fc Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [I] Platform------: [netif] Sent request#1 to add fe80::4c27:5d1b:1c49:317c/64 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [I] DnssdServer---: started: OK Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [I] Notifier------: StateChanged (0x01001009) [Ip6+ LLAddr Ip6Mult+ NetifState] Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.932 [N] Platform------: [netif] Changing interface state to up. Jan 2 02:59:12 dsldevice kernel: [97153.491325] mesh::error: Jan 2 02:59:12 dsldevice kernel: [97153.491332] [wpan0] unknown event (0xd) received Jan 2 02:59:12 dsldevice kernel: [97153.491340] (netdevice_notifier_call mesh.c:1148) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.933 [I] Platform------: > #012thread start Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.933 [D] Cli-----------: Input: thread start Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.933 [I] BbrLocal------: Remove BBR Service: seqno (36), delay (5s), timeout (3600s), NotFound Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.933 [N] Mle-----------: Role disabled -> detached Jan 2 02:59:12 dsldevice kernel: [97153.492004] mesh: Jan 2 02:59:12 dsldevice kernel: [97153.492006] NETDEV_UP: wpan0 Jan 2 02:59:12 dsldevice kernel: [97153.492013] (netdevice_notifier_call mesh.c:1059) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.934 [I] Platform------: [netif] Sent request#2 to add fd92:281f:226a:764a:754a:d7c8:1732:7b59/64 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.934 [I] Platform------: [netif] Added multicast address ff32:40:fd92:281f:226a:764a:0:1 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.934 [I] Platform------: [netif] Added multicast address ff33:40:fd92:281f:226a:764a:0:1 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.934 [I] Bbr-----------: Backbone TMF unsubscribes ff32:40:fdde:ad00:beef:0:0:3: OK Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.934 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fd92:281f:226a:764a:0:3: OK Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.935 [I] Mle-----------: AttachState Idle -> Start Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.935 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.935 [I] Platform------: [netif] Succeeded to process request#1 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.935 [I] Notifier------: StateChanged (0x10001015) [Ip6+ Role MLAddr Ip6Mult+ ActDset] Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.935 [I] AnnounceSender: Stopped Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-BA------: Thread is down Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing existing service OpenThread BorderRouter #317C._meshcop._udp: outdated Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #317C._meshcop._udp Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-BA------: Cancelled previous publishing meshcop service OpenThread BorderRouter #317C._meshcop._udp.local Jan 2 02:59:12 dsldevice otbr-agent[197]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #317C._meshcop._udp.local, serviceRef = (nil) Jan 2 02:59:12 dsldevice otbr-agent[197]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.DeviceRole Jan 2 02:59:12 dsldevice otbr-agent[197]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ DeviceRole:{ "detached", }, ], [ ], } Jan 2 02:59:12 dsldevice otbr-agent[197]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.ActiveDatasetTlvs Jan 2 02:59:12 dsldevice otbr-agent[197]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ ActiveDatasetTlvs:{ [ 14, 8, 0, 0, 0, 0, 0, 1, 0, 0, 0, 3, 0, 0, 15, 53, 6, 0, 4, 0, 31, 255, 224, 2, 8, 17, 17, 17, 17, 153, 153, 153, 153, 7, 8, 253, 146, 40, 31, 34, 106, 118, 74, 5, 16, 0, 17, 34, 51, 68, 85, 102, 119, 136, 153, 170, 187, 204, 221, 238, 255, 3, 14, 79, 112, 101, 110, 84, 104, 114, 101, 97, 100, 68, 101, 109, 111, 1, 2, 101, 101, 4, 16, 221, 9, 239, 139, 11, 163, 236, 105, 142, 182, 41, 194, 105, 76, 72, 12, 12, 4, 2, 160, 247, 248, ], }, ], [ ], } Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.939 [I] Platform------: > #012prefix add fd11:22::/64 pasor Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.939 [D] Cli-----------: Input: prefix add fd11:22::/64 pasor Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.939 [D] Cli-----------: Output: Done Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.941 [I] Platform------: [netif] Message dropped by Thread Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.942 [I] Platform------: [netif] Host netif is up Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.942 [I] Nat64---------: IPv4 CIDR for NAT64: 192.168.255.0/24 (actual address pool: 192.168.255.1 - 192.168.255.254, 254 addresses) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.942 [I] Platform------: [netif] Succeeded to enable NAT64 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.944 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.944 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.944 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.944 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.944 [I] Notifier------: StateChanged (0x00001000) [Ip6Mult+] Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.946 [I] Platform------: [netif] Message dropped by Thread Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.946 [I] Platform------: [netif] Succeeded to process request#2 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.948 [I] Platform------: [netif] ADD [M] ff33:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.948 [I] Platform------: [netif] ADD [M] ff32:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.948 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.948 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.948 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.948 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.950 [I] Platform------: [netif] ADD [U] fe80:0:0:0:4c27:5d1b:1c49:317c (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:03.950 [I] Platform------: [netif] ADD [U] fd92:281f:226a:764a:754a:d7c8:1732:7b59 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.003 [N] Mle-----------: Attach attempt 1, AnyPartition reattaching with Active Dataset Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.003 [I] Mle-----------: AttachState Start -> ParentReq Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.003 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.031 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:bc4e, ecn:no, to:0xffff, sec:no, prio:net Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.031 [I] MeshForwarder-: src:[fe80:0:0:0:4c27:5d1b:1c49:317c]:19788 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.031 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.120 [I] Platform------: [netif] Message dropped by Thread Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.122 [I] Platform------: [netif] ADD [M] ff33:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.122 [I] Platform------: [netif] ADD [M] ff32:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.122 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.122 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.122 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.122 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.296 [I] Platform------: [netif] Message dropped by Thread Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.298 [I] Platform------: [netif] ADD [M] ff33:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.298 [I] Platform------: [netif] ADD [M] ff32:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.298 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.298 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.298 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Jan 2 02:59:12 dsldevice otbr-agent[197]: 00:00:04.298 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (rejected) Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:04.753 [I] Mle-----------: Send Parent Request to routers (ff02:0:0:0:0:0:0:2) Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:04.786 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:d107, ecn:no, to:0xffff, sec:no, prio:net Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:04.786 [I] MeshForwarder-: src:[fe80:0:0:0:4c27:5d1b:1c49:317c]:19788 Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:04.786 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Jan 2 02:59:13 dsldevice otbr-agent[197]: [INFO]-MDNS----: Received reply for service OpenThread BorderRouter #317C._meshcop._udp., serviceRef = 0x55aceffb10 Jan 2 02:59:13 dsldevice otbr-agent[197]: [INFO]-MDNS----: Successfully registered service OpenThread BorderRouter #317C._meshcop._udp. Jan 2 02:59:13 dsldevice otbr-agent[197]: [INFO]-BA------: Result of publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local: OK Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:05.503 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:05.529 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:7376, ecn:no, to:0xffff, sec:no, prio:net Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:05.529 [I] MeshForwarder-: src:[fe80:0:0:0:4c27:5d1b:1c49:317c]:19788 Jan 2 02:59:13 dsldevice otbr-agent[197]: 00:00:05.529 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Jan 2 02:59:15 dsldevice otbr-agent[197]: 00:00:06.753 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Jan 2 02:59:15 dsldevice otbr-agent[197]: 00:00:06.788 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:30da, ecn:no, to:0xffff, sec:no, prio:net Jan 2 02:59:15 dsldevice otbr-agent[197]: 00:00:06.789 [I] MeshForwarder-: src:[fe80:0:0:0:4c27:5d1b:1c49:317c]:19788 Jan 2 02:59:15 dsldevice otbr-agent[197]: 00:00:06.789 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Jan 2 02:59:16 dsldevice otbr-agent[197]: 00:00:08.004 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Jan 2 02:59:16 dsldevice otbr-agent[197]: 00:00:08.068 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:a108, ecn:no, to:0xffff, sec:no, prio:net Jan 2 02:59:16 dsldevice otbr-agent[197]: 00:00:08.068 [I] MeshForwarder-: src:[fe80:0:0:0:4c27:5d1b:1c49:317c]:19788 Jan 2 02:59:16 dsldevice otbr-agent[197]: 00:00:08.068 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Jan 2 02:59:17 dsldevice otbr-agent[197]: 00:00:09.254 [I] Mle-----------: Send Parent Request to routers and REEDs (ff02:0:0:0:0:0:0:2) Jan 2 02:59:17 dsldevice otbr-agent[197]: 00:00:09.283 [I] MeshForwarder-: Sent IPv6 UDP msg, len:84, chksum:fbaf, ecn:no, to:0xffff, sec:no, prio:net Jan 2 02:59:17 dsldevice otbr-agent[197]: 00:00:09.283 [I] MeshForwarder-: src:[fe80:0:0:0:4c27:5d1b:1c49:317c]:19788 Jan 2 02:59:17 dsldevice otbr-agent[197]: 00:00:09.283 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.504 [I] Mle-----------: AttachState ParentReq -> Idle Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.504 [N] RouterTable---: Allocate router id 58 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.504 [N] Mle-----------: RLOC16 fffe -> e800 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.513 [I] Platform------: [netif] Sent request#3 to add fd92:281f:226a:764a:0:ff:fe00:e800/64 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.513 [N] Mle-----------: Role detached -> leader Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.513 [I] Platform------: [netif] Sent request#4 to add fd92:281f:226a:764a:0:ff:fe00:fc00/64 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.513 [I] Platform------: [netif] Added multicast address ff02::2 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.513 [I] Platform------: [netif] Added multicast address ff03::2 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.514 [N] Mle-----------: Leader partition id 0x2416bf83 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.514 [I] Notifier------: StateChanged (0x100012a5) [Ip6+ Role Rloc+ PartitionId NetData Ip6Mult+ ActDset] Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] Settings------: Saved NetworkInfo {rloc:0xe800, extaddr:4e275d1b1c49317c, role:leader, mode:0x0f, version:4, keyseq:0x0, ... Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] Settings------: ... pid:0x2416bf83, mlecntr:0x3ef, maccntr:0x3e8, mliid:754ad7c817327b59} Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] BbrLeader-----: PBBR state: None Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] Settings------: Re-saved NetworkInfo {rloc:0xe800, extaddr:4e275d1b1c49317c, role:leader, mode:0x0f, version:4, keyseq:0x0, ... Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] Settings------: ... pid:0x2416bf83, mlecntr:0x3ef, maccntr:0x3e8, mliid:754ad7c817327b59} Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] NetworkData---: Sent server data notification Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] AnnounceSender: Started Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.519 [I] BorderAgent---: Border Agent start listening on port 49153 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.520 [I] BorderRouter--: RIO Preference changed: low -> med Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.520 [I] BorderRouter--: Border Routing manager started Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.520 [I] BorderRouter--: Scheduled Router Solicitation in 343 milliseconds Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.520 [I] BorderRouter--: Starting Nat64PrefixManager Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.520 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2842 milliseconds Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.525 [I] Platform------: Execute commandipset flush otbr-ingress-allow-dst-swap
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.530 [I] Platform------: Execute commandipset flush otbr-ingress-deny-src-swap
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.534 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fd92:281f:226a:764a::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.538 [I] Platform------: Execute commandipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.541 [I] Platform------: Execute commandipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-BA------: Thread is up Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing existing service OpenThread BorderRouter #317C._meshcop._udp: outdated Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #317C._meshcop._udp Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #317C._meshcop._udp.local, serviceRef = (nil) Jan 2 02:59:18 dsldevice otbr-agent[197]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.DeviceRole Jan 2 02:59:18 dsldevice otbr-agent[197]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ DeviceRole:{ "leader", }, ], [ ], } Jan 2 02:59:18 dsldevice otbr-agent[197]: [DEBG]-UTILS---: Signal io.openthread.BorderRouter.ActiveDatasetTlvs Jan 2 02:59:18 dsldevice otbr-agent[197]: [DEBG]-DBUS----: { "io.openthread.BorderRouter", [ ActiveDatasetTlvs:{ [ 14, 8, 0, 0, 0, 0, 0, 1, 0, 0, 0, 3, 0, 0, 15, 53, 6, 0, 4, 0, 31, 255, 224, 2, 8, 17, 17, 17, 17, 153, 153, 153, 153, 7, 8, 253, 146, 40, 31, 34, 106, 118, 74, 5, 16, 0, 17, 34, 51, 68, 85, 102, 119, 136, 153, 170, 187, 204, 221, 238, 255, 3, 14, 79, 112, 101, 110, 84, 104, 114, 101, 97, 100, 68, 101, 109, 111, 1, 2, 101, 101, 4, 16, 221, 9, 239, 139, 11, 163, 236, 105, 142, 182, 41, 194, 105, 76, 72, 12, 12, 4, 2, 160, 247, 248, ], }, ], [ ], } Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] Platform------: getaddrinfo_a requested for ipv4only.arpa Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] BorderRouter--: Discovering infraif NAT64 prefix Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] BorderRouter--: NAT64 prefix timer scheduled in 300 seconds Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] Platform------: [netif] Succeeded to process request#3 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] NetworkData---: Received network data registration Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] NetworkData---: Allocated Context ID = 1 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.544 [I] NetworkData---: Sent network data registration acknowledgment Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.547 [I] BbrLocal------: Add BBR Service: seqno (36), delay (5s), timeout (3600s), OK Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.547 [I] Platform------: [netif] Message dropped by Thread Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.547 [I] Platform------: [netif] Succeeded to process request#4 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.548 [I] Platform------: Handling host address response for ipv4only.arpa Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.548 [I] BorderRouter--: Infraif NAT64 prefix: none Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.548 [I] BorderRouter--: Start evaluating routing policy, scheduled in 3778 milliseconds Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff33:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff32:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Notifier------: StateChanged (0x00000200) [NetData] Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] BbrLeader-----: PBBR state: None Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.550 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.551 [I] NetworkData---: Sent server data notification Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.555 [I] Settings------: Saved SlaacIidSecretKey Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.555 [I] Slaac---------: Generated and saved secret key Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.555 [I] Slaac---------: Adding address fd11:22:0:0:460c:6abc:936f:1541 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.555 [I] Platform------: [netif] Sent request#5 to add fd11:22::460c:6abc:936f:1541/64 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.556 [I] BorderRouter--: Allow default route in netdata: no -> yes Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.556 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2808 milliseconds Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.560 [I] Platform------: Execute commandipset flush otbr-ingress-allow-dst-swap
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.564 [I] Platform------: Execute commandipset flush otbr-ingress-deny-src-swap
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.568 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fd11:22:0:0::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.572 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fd92:281f:226a:764a::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.576 [I] Platform------: Execute commandipset add otbr-ingress-allow-dst-swap fd11:22:0:0::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.580 [I] Platform------: Execute commandipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.584 [I] Platform------: Execute commandipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.584 [I] NetworkData---: Received network data registration Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.584 [I] NetworkData---: Allocated Service ID = 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.584 [I] NetworkData---: Sent network data registration acknowledgment Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.587 [I] Platform------: [netif] ADD [U] fd92:281f:226a:764a:0:ff:fe00:e800 (already subscribed, ignored) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.587 [I] Notifier------: StateChanged (0x00000201) [Ip6+ NetData] Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.587 [I] Mle-----------: Send Data Response (ff02:0:0:0:0:0:0:1) Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.587 [I] BbrLeader-----: PBBR state: Added Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.587 [I] BbrLeader-----: Rloc16:0xe800, seqno:36, delay:5, timeout:3600 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.588 [I] Platform------: [netif] Sent request#6 to add fd92:281f:226a:764a:0:ff:fe00:fc38/64 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.588 [I] BbrLeader-----: Domain Prefix: ::/0, state: None Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.588 [I] Platform------: [netif] Sent request#7 to add fd92:281f:226a:764a:0:ff:fe00:fc10/64 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.588 [I] BorderRouter--: Start evaluating routing policy, scheduled in 2206 milliseconds Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.592 [I] Platform------: Execute commandipset flush otbr-ingress-allow-dst-swap
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.596 [I] Platform------: Execute commandipset flush otbr-ingress-deny-src-swap
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.600 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fd11:22:0:0::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.604 [I] Platform------: Execute commandipset add otbr-ingress-deny-src-swap fd92:281f:226a:764a::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.608 [I] Platform------: Execute commandipset add otbr-ingress-allow-dst-swap fd11:22:0:0::/64 -exist
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.612 [I] Platform------: Execute commandipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: 00:00:10.616 [I] Platform------: Execute commandipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst
= 0 Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-BA------: Publish meshcop service OpenThread BorderRouter #317C._meshcop._udp.local. Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing existing service OpenThread BorderRouter #317C._meshcop._udp: outdated Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-MDNS----: Removing service OpenThread BorderRouter #317C._meshcop._udp Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-BA------: Cancelled previous publishing meshcop service OpenThread BorderRouter #317C._meshcop._udp.local Jan 2 02:59:18 dsldevice otbr-agent[197]: [INFO]-BA------: Cancelled previous publishing meshcop service OpenThread BorderRouter #317C._meshcop._udp.local Jan 2 02:59:19 dsldevice otbr-agent[197]: [INFO]-MDNS----: Registering new service OpenThread BorderRouter #317C._meshcop._udp.local, serviceRef = (nil) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.619 [I] Platform------: [netif] Message dropped by Thread Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.619 [I] Platform------: [netif] ADD [U] fd92:281f:226a:764a:0:ff:fe00:fc00 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:2 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff33:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff32:40:fd92:281f:226a:764a:0:1 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:fc (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff03:0:0:0:0:0:0:1 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:16 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.621 [I] Platform------: [netif] ADD [M] ff02:0:0:0:0:0:0:2 (already subscribed, ignored) Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.622 [I] Notifier------: StateChanged (0x02000001) [Ip6+ BbrState] Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.622 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fd92:281f:226a:764a:0:3: OK Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.622 [I] BbrManager----: Start Backbone TMF agent: OK Jan 2 02:59:19 dsldevice otbr-agent[197]: 00:00:10.622 [C] Platform------: InitMulticastRouterSock() at multicast_routing.cpp:225: Protocol not available Jan 2 02:59:19 dsldevice kernel: [97160.204312] mesh: Jan 2 02:59:19 dsldevice kernel: [97160.204317] NETDEV_GOING_DOWN: wpan0 Jan 2 02:59:19 dsldevice kernel: [97160.204326] (netdevice_notifier_call mesh.c:1065) Jan 2 02:59:19 dsldevice kernel: [97160.210039] mesh: Jan 2 02:59:19 dsldevice kernel: [97160.210043] NETDEV_DOWN: wpan0 Jan 2 02:59:19 dsldevice kernel: [97160.210052] (netdevice_notifier_call mesh.c:1062) Jan 2 02:59:19 dsldevice kernel: [97160.214274] mesh: Jan 2 02:59:19 dsldevice kernel: [97160.214277] NETDEV_UNREGISTER: wpan0 Jan 2 02:59:19 dsldevice kernel: [97160.214284] (netdevice_notifier_call mesh.c:951)can anyone please let me know why is this happening and if anyone has faced this issue int he past.