1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
| otbr | s6-rc: info: service s6rc-oneshot-runner: starting
otbr | s6-rc: info: service s6rc-oneshot-runner successfully started
otbr | s6-rc: info: service fix-attrs: starting
otbr | s6-rc: info: service fix-attrs successfully started
otbr | s6-rc: info: service legacy-cont-init: starting
otbr | s6-rc: info: service legacy-cont-init successfully started
otbr | s6-rc: info: service otbr-agent: starting
otbr | Configuring OpenThread firewall...
otbr | ip6tables: Chain already exists.
otbr | Configuring OpenThread NAT64...
otbr | Starting otbr-agent...
otbr | [NOTE]-AGENT---: Running 0.3.0-ceffb2f
otbr | [NOTE]-AGENT---: Thread version: 1.4.0
otbr | [NOTE]-AGENT---: Thread interface: wpan0
otbr | [NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/thread?uart-baudrate=460800
otbr | [NOTE]-AGENT---: Radio URL: trel://enp2s0
otbr | [NOTE]-ILS-----: Infra link selected: enp2s0
otbr | [INFO]-RCP_HOS-: OpenThread log level changed to 5
otbr | 49d.18:48:32.789 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:0, cmd:RESET
otbr | 49d.18:48:32.789 [D] P-SpinelDrive-: Waiting response: key=0
otbr | 49d.18:48:32.791 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:RESET_POWER_ON
otbr | 49d.18:48:32.791 [I] P-SpinelDrive-: co-processor reset: RESET_POWER_ON
otbr | 49d.18:48:32.791 [C] P-SpinelDrive-: Software reset co-processor successfully
otbr | 49d.18:48:32.791 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:PROTOCOL_VERSION
otbr | 49d.18:48:32.791 [D] P-SpinelDrive-: Waiting response: key=1
otbr | 49d.18:48:32.794 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:PROTOCOL_VERSION, major:4, minor:3
otbr | 49d.18:48:32.794 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:NCP_VERSION
otbr | 49d.18:48:32.794 [D] P-SpinelDrive-: Waiting response: key=2
otbr | 49d.18:48:32.798 [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
otbr | 49d.18:48:32.798 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:CAPS
otbr | 49d.18:48:32.798 [D] P-SpinelDrive-: Waiting response: key=5
otbr | 49d.18:48:32.801 [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
otbr | 49d.18:48:32.803 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_GET, key:HWADDR
otbr | 49d.18:48:32.803 [D] P-RadioSpinel-: Wait response: tid=1 key=8
otbr | 49d.18:48:32.806 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:HWADDR, eui64:00be44fffe714526
otbr | 49d.18:48:32.806 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_GET, key:RCP_API_VERSION
otbr | 49d.18:48:32.806 [D] P-RadioSpinel-: Wait response: tid=2 key=176
otbr | 49d.18:48:32.808 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:RCP_API_VERSION, version:9
otbr | 49d.18:48:32.808 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_GET, key:RCP_MIN_HOST_API_VERSION
otbr | 49d.18:48:32.808 [D] P-RadioSpinel-: Wait response: tid=3 key=177
otbr | 49d.18:48:32.811 [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
otbr | 49d.18:48:32.811 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_GET, key:RADIO_CAPS
otbr | 49d.18:48:32.811 [D] P-RadioSpinel-: Wait response: tid=4 key=4619
otbr | 49d.18:48:32.814 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:RADIO_CAPS, caps:255
otbr | 49d.18:48:32.814 [D] P-Trel--------: platformTrelInit(aTrelUrl:"trel://enp2s0")
otbr | 49d.18:48:32.814 [D] P-Trel--------: otSysTrelInit(aInterfaceName:"enp2s0")
otbr | 49d.18:48:32.814 [I] P-Netif-------: Sent request#1 to set addr_gen_mode to 1
otbr | 49d.18:48:32.814 [I] P-Resolver----: Got nameserver #0: 172.17.0.1
otbr | 00:00:00.000 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_GET, key:PHY_CHAN_SUPPORTED
otbr | 00:00:00.000 [D] P-RadioSpinel-: Wait response: tid=5 key=34
otbr | 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
otbr | 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
otbr | 00:00:00.003 [D] P-RadioSpinel-: Wait response: tid=6 key=32
otbr | 00:00:00.005 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:PHY_ENABLED, enabled:1
otbr | 00:00:00.005 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:MAC_15_4_PANID, panid:0xffff
otbr | 00:00:00.005 [D] P-RadioSpinel-: Wait response: tid=7 key=54
otbr | 00:00:00.008 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:MAC_15_4_PANID, panid:0xffff
otbr | 00:00:00.008 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0x0000
otbr | 00:00:00.008 [D] P-RadioSpinel-: Wait response: tid=8 key=53
otbr | 00:00:00.011 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0x0000
otbr | 00:00:00.011 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_GET, key:PHY_RX_SENSITIVITY
otbr | 00:00:00.011 [D] P-RadioSpinel-: Wait response: tid=9 key=39
otbr | 00:00:00.014 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:PHY_RX_SENSITIVITY, sensitivity:-100
otbr | 00:00:00.014 [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:***
otbr | 00:00:00.014 [D] P-RadioSpinel-: Wait response: tid=10 key=2048
otbr | 00:00:00.018 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
otbr | 00:00:00.018 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:MAC_15_4_LADDR, laddr:2e1c89c2543dce42
otbr | 00:00:00.018 [D] P-RadioSpinel-: Wait response: tid=11 key=52
otbr | 00:00:00.021 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:MAC_15_4_LADDR, laddr:2e1c89c2543dce42
otbr | 00:00:00.021 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:MAC_15_4_SADDR, saddr:0xfffe
otbr | 00:00:00.021 [D] P-RadioSpinel-: Wait response: tid=12 key=53
otbr | 00:00:00.024 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:MAC_15_4_SADDR, saddr:0xfffe
otbr | 00:00:00.024 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:MAC_SRC_MATCH_SHORT_ADDRESSES, saddr:none
otbr | 00:00:00.024 [D] P-RadioSpinel-: Wait response: tid=13 key=4868
otbr | 00:00:00.027 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
otbr | 00:00:00.027 [D] P-SpinelDrive-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:MAC_SRC_MATCH_EXTENDED_ADDRESSES, extaddr:none
otbr | 00:00:00.027 [D] P-RadioSpinel-: Wait response: tid=14 key=4869
otbr | 00:00:00.029 [D] P-SpinelDrive-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK
otbr | 00:00:00.029 [I] CslTxScheduler: Set frame request ahead: 7605 usec
otbr | 00:00:00.029 [I] ChildSupervsn-: Timeout: 0 -> 190
otbr | 00:00:00.030 [D] P-Trel--------: PrepareSocket()
otbr | 00:00:00.030 [I] TrelInterface-: Enabled interface, local port:58761
otbr | 00:00:00.030 [I] InfraIf-------: Init infra netif 2
otbr | 00:00:00.030 [I] Settings------: Read BrUlaPrefix fd03:6c71:4d4f::/48
otbr | 00:00:00.030 [N] BorderRouting-: BR ULA prefix: fd03:6c71:4d4f::/48 (loaded)
otbr | 00:00:00.030 [I] BorderRouting-: Generated local OMR prefix: fd03:6c71:4d4f:1::/64
otbr | 00:00:00.030 [I] BorderRouting-: Generated local NAT64 prefix: fd03:6c71:4d4f:2:0:0::/96
otbr | 00:00:00.030 [N] BorderRouting-: Local on-link prefix: fdde:ad00:beef:cafe::/64
otbr | 00:00:00.030 [I] InfraIf-------: State changed: NOT RUNNING -> RUNNING
otbr | 00:00:00.030 [I] P-MdnsSocket--: Successfully opened IPv4 socket
otbr | 00:00:00.030 [I] P-MdnsSocket--: Successfully opened IPv6 socket
otbr | 00:00:00.031 [I] P-MdnsSocket--: Enabled
otbr | 00:00:00.031 [I] MulticastDns--: Auto-enabling on infra-if-index 2
otbr | 00:00:00.031 [I] Settings------: Read BorderAgentId {id:b6c061a182bc2da862d5ea66f5f2b335}
otbr | 00:00:00.031 [I] BorderRouting-: Enabling
otbr | 00:00:00.031 [I] Nat64---------: IPv4 CIDR for NAT64: 192.168.255.0/24 (254 addresses)
otbr | [INFO]-HELPER--: Set state callback: OK
otbr | 00:00:00.031 [I] Nat64---------: State: Disabled -> NotRunning
otbr | 00:00:00.031 [I] BbrLocal------: Add Domain Prefix: ::/0, NotFound
otbr | 00:00:00.031 [I] BbrLocal------: Add BBR Service: seqno (39), delay (5s), timeout (3600s), InvalidState
otbr | [WARN]-UTILS---: /usr/src/ot-br-posix/src/rest/network_diag_handler.cpp:1300 lacking some attributes for deviceId 2e1c89c2543dce42
otbr | [WARN]-UTILS---: /usr/src/ot-br-posix/src/rest/network_diag_handler.cpp:1244 cannot get LeaderData while detached
otbr | [WARN]-UTILS---: /usr/src/ot-br-posix/src/rest/rest_devices_coll.cpp:232 - AddItem - 2e1c89c2543dce42
otbr | [INFO]-APP-----: Radio Co-processor version: SL-OPENTHREAD/2.4.4.0_GitHub-7074a43e4; EFR32
otbr | 00:00:00.032 [I] Notifier------: StateChanged (0x42038210) [MLAddr NetData PanId NetName ExtPanId BbrState Nat64]
otbr | 00:00:00.032 [I] BbrLeader-----: PBBR state: None
otbr | [INFO]-REST----: RestWebServer listening on 0.0.0.0:8781
otbr | 00:00:00.032 [I] Bbr-----------: Start listening on port 61631
otbr | 00:00:00.032 [I] Bbr-----------: Backbone TMF subscribes ff32:40:fdde:ad00:beef:0:0:3: OK
otbr | 00:00:00.032 [I] BbrManager----: Start Backbone TMF agent: OK
otbr | 00:00:00.035 [I] Platform------: Execute command `ipset flush otbr-ingress-allow-dst-swap` = 0
otbr | 00:00:00.037 [I] Platform------: Execute command `ipset flush otbr-ingress-deny-src-swap` = 0
otbr | 00:00:00.040 [I] Platform------: Execute command `ipset add otbr-ingress-deny-src-swap fdde:ad00:beef:0::/64 -exist` = 0
otbr | 00:00:00.042 [I] Platform------: Execute command `ipset swap otbr-ingress-deny-src-swap otbr-ingress-deny-src` = 0
otbr | 00:00:00.044 [I] Platform------: Execute command `ipset swap otbr-ingress-allow-dst-swap otbr-ingress-allow-dst` = 0
otbr | 00:00:00.044 [I] P-Netif-------: NAT64 CIDR updated to 192.168.255.0/24.
otbr | 00:00:00.044 [I] P-Netif-------: Sent request#2 to delete route 192.168.255.0/24
otbr | 00:00:00.044 [I] P-Netif-------: Deleting route for NAT64
otbr | 00:00:00.044 [I] P-McastRtMgr--: Disable: OK
otbr | [DEBG]-BBA-----: BackboneAgent: HandleBackboneRouterState: state=1, mBackboneRouterState=0
otbr | 00:00:00.044 [I] RouterTable---: Route table
otbr | 00:00:00.044 [I] TrelDiscoverer: Registering service otTREL2e1c89c2543dce42._trel._udp
otbr | 00:00:00.044 [I] TrelDiscoverer: port:58761, ext-addr:2e1c89c2543dce42, ext-panid:dead00beef00cafe
otbr | 00:00:00.044 [I] MulticastDns--: Adding host address 10.0.0.1
otbr | 00:00:00.044 [I] MulticastDns--: Adding host address fe80:0:0:0:8647:9ff:fe0f:fd94
otbr | 00:00:00.044 [I] MulticastDns--: Adding host address fd9d:5374:2940:fcd9:8647:9ff:fe0f:fd94
otbr | 00:00:00.044 [I] P-Netif-------: Host netif is down
otbr | 00:00:00.044 [I] P-Netif-------: Succeeded to process request#1
otbr | 00:00:00.045 [W] P-Netif-------: Failed to process request#2: No such process
otbr | s6-rc: info: service otbr-agent successfully started
otbr | s6-rc: info: service otbr-web: starting
otbr | s6-rc: info: service otbr-web successfully started
otbr | s6-rc: info: service legacy-services: starting
otbr | Starting otbr-web...
otbr | [INFO]-WEB-----: Running 0.3.0-ceffb2f
otbr | [INFO]-WEB-----: Border router web started on wpan0
otbr | 00:00:00.285 [I] P-Daemon------: Session socket is ready
otbr | 00:00:00.285 [D] Cli-----------: Input: state
otbr | 00:00:00.285 [D] Cli-----------: Output: disabled
otbr | 00:00:00.285 [D] Cli-----------: Output: Done
otbr | s6-rc: info: service legacy-services successfully started
otbr | otbr-web ended with exit code 0 (signal 0).
otbr | 00:00:00.916 [I] TrelDiscoverer: DNS-SD service registered successfully
otbr | 00:00:00.917 [I] TrelPeerTable-: Added peer otTREL2e1c89c2543dce42, dnssd-state:resolving
otbr | 00:00:00.917 [I] TrelDiscoverer: Peer otTREL2e1c89c2543dce42 is this device itself
otbr | 00:00:00.917 [I] TrelPeerTable-: Deleted peer otTREL2e1c89c2543dce42, dnssd-state:resolving
otbr | Starting otbr-web...
otbr | [INFO]-WEB-----: Running 0.3.0-ceffb2f
otbr | [INFO]-WEB-----: Border router web started on wpan0
otbr | 00:00:01.300 [I] P-Daemon------: Session socket is ready
otbr | 00:00:01.300 [D] Cli-----------: Input: state
otbr | 00:00:01.300 [D] Cli-----------: Output: disabled
otbr | 00:00:01.300 [D] Cli-----------: Output: Done
otbr | otbr-web ended with exit code 0 (signal 0).
otbr | Starting otbr-web... |