search

project-chip / connectedhomeip

Matter (formerly Project CHIP) creates more connections between more objects, simplifying development for manufacturers and increasing compatibility for consumers, guided by the Connectivity Standards Alliance.
https://buildwithmatter.com
Apache License 2.0
7.55k stars 2.04k forks source link

[Test Failed] My chip tool cannot add nanoleaf bulbs #32544

Open xjshiMax opened 8 months ago

xjshiMax commented 8 months ago

Test issue(s)

  1. Compiled version used:6a254ac316295bc6f0e1febd1ed4cb19e214ceb0
  2. I built my own OTBR 3../chip-tool pairing code-thread 1009 hex:0e080000000000010000000300001635060004001fffe0020867409d23db23e7a60708fd8e051f1c741f13051095cef67a21137c2b03c90d7e7630e2ec030f4f70656e5468726561642d623563370102b5c70c0402a0f7f80410568e528f9004827904a7e903ed26964a MT:CWBT0A-E15Q.OH3F120 --paa-trust-store-path /home/code/connectedhomeip/credentials/production/paa-root-certs --timeout 7200
  3. failed
  4. How should I fix the problem ...

Platform

No response

Anything else?

[1710230888.276921][9522:9524] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1710230888.276965][9522:9524] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0 [1710230888.277004][9522:9524] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkEnable' [1710230888.277013][9522:9524] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkEnable' -> 'FindOperational' [1710230888.277048][9522:9524] CHIP:CTL: Performing next commissioning step 'FindOperational' [1710230888.277056][9522:9524] CHIP:IN: Expiring all sessions for node <00000000000003F1, 1>!! [1710230888.277064][9522:9524] CHIP:CSM: FindOrEstablishSession: PeerId = [1:00000000000003F1] [1710230888.277112][9522:9524] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1710230888.277126][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: State change 1 --> 2 [1710230888.277271][9522:9524] CHIP:DMG: ICR moving to [AwaitingDe] [1710230888.477014][9522:9524] CHIP:DIS: Checking node lookup status after 200 ms [1710230890.165222][9522:9523] CHIP:DL: Bluez disconnected [1710230890.165244][9522:9523] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1710230890.165437][9522:9524] CHIP:IN: Clearing BLE pending packets. [1710230892.376843][9522:9524] CHIP:DIS: SRV record already actively processed. [1710230892.412757][9522:9524] CHIP:DIS: SRV record already actively processed. [1710230892.430585][9522:9524] CHIP:DIS: Lookup clearing interface for non LL address [1710230892.430779][9522:9524] CHIP:DIS: UDP:[fd11:22::407f:9e32:3eb3:1102%enp0s3]:5540: new best score: 3 [1710230892.430860][9522:9524] CHIP:DIS: Checking node lookup status after 4154 ms [1710230892.430911][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: Updating device address to UDP:[fd11:22::407f:9e32:3eb3:1102]:5540 while in state 2 [1710230892.430966][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: State change 2 --> 3 [1710230892.431046][9522:9524] CHIP:IN: SecureSession[0x7f58e4014540]: Allocated Type:2 LSID:50196 [1710230892.431107][9522:9524] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x00000000000003F1 [1710230892.431451][9522:9524] CHIP:EM: <<< [E:28956i S:0 M:210019834] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fd11:22::407f:9e32:3eb3:1102]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) [1710230892.431551][9522:9524] CHIP:SC: Sent Sigma1 msg [1710230892.431601][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: State change 3 --> 4 [1710230893.479798][9522:9524] CHIP:EM: Retransmitting MessageCounter:210019834 on exchange 28956i Send Cnt 1 [1710230893.554914][9522:9524] CHIP:DIS: SRV record already actively processed. [1710230894.464123][9522:9524] CHIP:EM: Retransmitting MessageCounter:210019834 on exchange 28956i Send Cnt 2 [1710230895.695247][9522:9524] CHIP:DIS: SRV record already actively processed. [1710230896.072295][9522:9524] CHIP:EM: Retransmitting MessageCounter:210019834 on exchange 28956i Send Cnt 3 [1710230898.678110][9522:9524] CHIP:EM: Retransmitting MessageCounter:210019834 on exchange 28956i Send Cnt 4 [1710230902.722541][9522:9524] CHIP:EM: Failed to Send CHIP MessageCounter:210019834 on exchange 28956i sendCount: 4 max retries: 4 [1710230905.705973][9522:9524] CHIP:SC: CASESession timed out while waiting for a response from the peer. Current state was 1 [1710230905.706001][9522:9524] CHIP:IN: SecureSession[0x7f58e4014540]: Released - Type:2 LSID:50196 [1710230905.706019][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: State change 4 --> 2 [1710230905.706028][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: State change 2 --> 1 [1710230905.706037][9522:9524] CHIP:DIS: OperationalSessionSetup:attempts done: 1, attempts left: 2, retry delay 1s, status src/system/SystemLayerImplSelect.cpp:214: Success [1710230905.706041][9522:9524] CHIP:DIS: OperationalSessionSetup[1:00000000000003F1]: State change 1 --> 6 [1710230905.706219][9522:9524] CHIP:CTL: Session establishment failed for <00000000000003F1, 1>, error: src/protocols/secure_channel/CASESession.cpp:560: CHIP Error 0x00000032: Timeout. Next retry expected to get a response to Sigma1 or fail within 11 seconds

bzbarsky-apple commented 8 months ago

Looks like either the light bulb did not get the Sigma1 message or its Sigma2 response did not get back to your commissioner. No way to tell which without logs from the bulb or from the border router.

As to how to fix... I would start by looking at what packets your border router is seeing here. That might help you figure out where the routing failure is.

xjshiMax commented 8 months ago

Thank you very much for your reply. I only have OTBR logs and cannot obtain the logs of the light bulb. I have redone the pairing process, and below are the OTBR logs. otbr-agent[7059]: 00:10:18.145 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:63, rssi:-75 ... otbr-agent[7059]: 00:10:18.146 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1456830563, rxerr:0 otbr-agent[7059]: 00:10:18.146 [I] MeshForwarder-: Received IPv6 UDP msg, len:84, chksum:5b24, ecn:no, from:baf9cf352f3143ba, sec:no, prio:net, rss:-75.0 otbr-agent[7059]: 00:10:18.146 [I] MeshForwarder-: src:[fe80:0:0:0:b8f9:cf35:2f31:43ba]:19788 otbr-agent[7059]: 00:10:18.146 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:2]:19788 otbr-agent[7059]: 00:10:18.147 [I] Mle-----------: Receive Parent Request (fe80:0:0:0:b8f9:cf35:2f31:43ba) otbr-agent[7059]: 00:10:18.147 [I] Mle-----------: Delay Parent Response (fe80:0:0:0:b8f9:cf35:2f31:43ba) otbr-agent[7059]: 00:10:18.448 [I] Mle-----------: Send delayed message (fe80:0:0:0:b8f9:cf35:2f31:43ba) otbr-agent[7059]: 00:10:18.449 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:113, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:18.449 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:18.472 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:18.474 [I] MeshForwarder-: Sent IPv6 UDP msg, len:129, chksum:fc7e, ecn:no, to:baf9cf352f3143ba, sec:no, prio:net otbr-agent[7059]: 00:10:18.475 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:18.476 [I] MeshForwarder-: dst:[fe80:0:0:0:b8f9:cf35:2f31:43ba]:19788 otbr-agent[7059]: 00:10:18.525 [I] Mle-----------: Send Announce on channel 26 otbr-agent[7059]: 00:10:18.527 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:78, channel:26, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:18.528 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:1, isARetx:0, skipAes:1, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:18.545 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:18.546 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:634b, ecn:no, to:0xffff, sec:yes, prio:net otbr-agent[7059]: 00:10:18.547 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:18.548 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:10:18.550 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:PHY_CHAN, channel:24 otbr-agent[7059]: 00:10:18.551 [D] P-RadioSpinel-: Wait response: tid=14 key=33 otbr-agent[7059]: 00:10:18.552 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:PHY_CHAN, channel:24 otbr-agent[7059]: 00:10:18.913 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:98, rssi:-74 ... otbr-agent[7059]: 00:10:18.914 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1457593721, rxerr:0 otbr-agent[7059]: 00:10:18.915 [I] MeshForwarder-: Received IPv6 UDP msg, len:114, chksum:8df0, ecn:no, from:baf9cf352f3143ba, sec:no, prio:net, rss:-74.0 otbr-agent[7059]: 00:10:18.916 [I] MeshForwarder-: src:[fe80:0:0:0:b8f9:cf35:2f31:43ba]:19788 otbr-agent[7059]: 00:10:18.917 [I] MeshForwarder-: dst:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:18.919 [I] Mle-----------: Receive Child ID Request (fe80:0:0:0:b8f9:cf35:2f31:43ba) otbr-agent[7059]: 00:10:18.940 [I] Settings------: Added ChildInfo {rloc:0x6801, extaddr:baf9cf352f3143ba, timeout:240, mode:0x0f, version:4} otbr-agent[7059]: 00:10:18.941 [I] Mle-----------: Send Child ID Response (fe80:0:0:0:b8f9:cf35:2f31:43ba,0x6801) otbr-agent[7059]: 00:10:18.942 [I] Notifier------: StateChanged (0x00000400) [Child+] otbr-agent[7059]: 00:10:18.944 [I] ChildSupervsn-: Starting Child Supervision otbr-agent[7059]: 00:10:18.945 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:18.946 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:18.978 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:18.979 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:118, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:18.981 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:19.012 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:19.013 [I] MeshForwarder-: Sent IPv6 UDP msg, len:208, chksum:0c53, ecn:no, to:baf9cf352f3143ba, sec:yes, prio:net otbr-agent[7059]: 00:10:19.014 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:19.015 [I] MeshForwarder-: dst:[fe80:0:0:0:b8f9:cf35:2f31:43ba]:19788 otbr-agent[7059]: 00:10:19.466 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) otbr-agent[7059]: 00:10:19.468 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:19.469 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:19.485 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:19.486 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:c147, ecn:no, to:0xffff, sec:no, prio:net otbr-agent[7059]: 00:10:19.488 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:19.489 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:10:19.917 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:67, rssi:-75 ... otbr-agent[7059]: 00:10:19.918 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1458601905, rxerr:0 otbr-agent[7059]: 00:10:19.919 [I] MeshForwarder-: Received IPv6 HopOpts msg, len:86, chksum:0000, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-75.0 otbr-agent[7059]: 00:10:19.921 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6801] otbr-agent[7059]: 00:10:19.922 [I] MeshForwarder-: dst:[ff03:0:0:0:0:0:0:2] otbr-agent[7059]: 00:10:19.923 [I] AddrResolver--: Received AddressQuery from 0x6801 for target fda8:bf44:3aa5:be08:5722:1969:4476:6bd6 otbr-agent[7059]: 00:10:19.925 [I] AddrResolver--: Sent AddressNotify for target fda8:bf44:3aa5:be08:5722:1969:4476:6bd6 otbr-agent[7059]: 00:10:19.926 [I] Platform------: MulticastRoutingManager: AddMulticastForwardingCache: add dynamic route: Thread fda8:bf44:3aa5:be08:0:ff:fe00:6801 => ff03:0:0:0:0:0:0:2 None: OK otbr-agent[7059]: 00:10:19.927 [I] Platform------: MulticastRoutingManager: ProcessMulticastRouterMessages: OK otbr-agent[7059]: 00:10:19.929 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:72, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:19.930 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:19.932 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:67, rssi:-75 ... otbr-agent[7059]: 00:10:19.933 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1458616713, rxerr:0 otbr-agent[7059]: 00:10:19.935 [I] Mac-----------: Frame rx failed, error:Duplicated, len:67, seqnum:90, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:19.948 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:19.949 [I] MeshForwarder-: Sent IPv6 UDP msg, len:92, chksum:f523, ecn:no, to:0x6801, sec:yes, prio:net otbr-agent[7059]: 00:10:19.950 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6800]:61631 otbr-agent[7059]: 00:10:19.952 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:0:ff:fe00:6801]:61631 otbr-agent[7059]: 00:10:19.954 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:70, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:19.955 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:19.979 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:19.980 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1458657559, rxerr:0 otbr-agent[7059]: 00:10:19.982 [I] AddrResolver--: Cache entry added: fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd, 0x6801 - snoop otbr-agent[7059]: 00:10:19.984 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:19.985 [I] MeshForwarder-: Sent IPv6 HopOpts msg, len:86, chksum:0000, ecn:no, to:0xffff, sec:yes, prio:normal otbr-agent[7059]: 00:10:19.986 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6801] otbr-agent[7059]: 00:10:19.987 [I] MeshForwarder-: dst:[ff03:0:0:0:0:0:0:2] otbr-agent[7059]: 00:10:19.990 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:70, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:19.992 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:19.997 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:19.998 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1458673743, rxerr:0 otbr-agent[7059]: 00:10:20.013 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-74 ... otbr-agent[7059]: 00:10:20.014 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1458690948, rxerr:0 otbr-agent[7059]: 00:10:20.015 [I] MeshForwarder-: Received IPv6 UDP msg, len:313, chksum:ae2c, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-75.0 otbr-agent[7059]: 00:10:20.017 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:20.018 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:20.019 [I] SrpServer-----: Received DNS update from fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd otbr-agent[7059]: 00:10:20.128 [I] SrpServer-----: Processed SRP update info otbr-agent[7059]: 00:10:20.129 [I] SrpServer-----: Host:BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:20.130 [I] SrpServer-----: Lease:0, key-lease:1209600, ttl:7200 otbr-agent[7059]: 00:10:20.132 [I] SrpServer-----: No host address otbr-agent[7059]: 00:10:20.133 [I] SrpServer-----: SRP update handler is notified (updatedId = 116140141) otbr-agent[7059]: [INFO]-ADPROXY-: Advertise SRP service updates: host=BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: [DEBG]-ADPROXY-: Unpublish SRP host 'BAF9CF352F3143BA.default.service.arpa.' otbr-agent[7059]: [INFO]-MDNS----: Removing host BAF9CF352F3143BA otbr-agent[7059]: [INFO]-ADPROXY-: Handle unpublish SRP host 'BAF9CF352F3143BA.default.service.arpa.': OK otbr-agent[7059]: 00:10:20.138 [I] SrpServer-----: Handler result of SRP update (id = 116140141) is received: OK otbr-agent[7059]: 00:10:20.140 [I] SrpServer-----: Committing update for new host BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:20.141 [I] SrpServer-----: Granted lease:0, key-lease:680400, ttl:30 otbr-agent[7059]: 00:10:20.253 [I] Settings------: Saved SrpServerInfo {port:53539} otbr-agent[7059]: 00:10:20.255 [I] SrpServer-----: Send success response with granted lease: 0 and key lease: 680400 otbr-agent[7059]: 00:10:20.256 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:20.257 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:33, rssi:-75 ... otbr-agent[7059]: 00:10:20.259 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1458709525, rxerr:0 otbr-agent[7059]: 00:10:20.260 [I] MeshForwarder-: Received IPv6 UDP msg, len:54, chksum:5c56, ecn:no, from:0x6801, sec:yes, prio:net, rss:-75.0 otbr-agent[7059]: 00:10:20.261 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6801]:61631 otbr-agent[7059]: 00:10:20.262 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:0:ff:fe00:6800]:61631 otbr-agent[7059]: 00:10:20.264 [I] MeshForwarder-: Sent IPv6 HopOpts msg, len:86, chksum:0000, ecn:no, to:0xffff, sec:yes, prio:normal otbr-agent[7059]: 00:10:20.266 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6801] otbr-agent[7059]: 00:10:20.267 [I] MeshForwarder-: dst:[ff03:0:0:0:0:0:0:2] otbr-agent[7059]: 00:10:20.269 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:84, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:20.270 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:20.287 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:20.289 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:8760, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:20.290 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:20.291 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:21.175 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:21.176 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1459853174, rxerr:0 otbr-agent[7059]: 00:10:22.023 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-77 ... otbr-agent[7059]: 00:10:22.024 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460700875, rxerr:0 otbr-agent[7059]: 00:10:22.039 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-77 ... otbr-agent[7059]: 00:10:22.040 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460717301, rxerr:0 otbr-agent[7059]: 00:10:22.058 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-78 ... otbr-agent[7059]: 00:10:22.060 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460736340, rxerr:0 otbr-agent[7059]: 00:10:22.088 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-80 ... otbr-agent[7059]: 00:10:22.089 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460766232, rxerr:0 otbr-agent[7059]: 00:10:22.109 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-79 ... otbr-agent[7059]: 00:10:22.110 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460787486, rxerr:0 otbr-agent[7059]: 00:10:22.127 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-78 ... otbr-agent[7059]: 00:10:22.128 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:253, timestamp:1460804807, rxerr:0 otbr-agent[7059]: 00:10:22.140 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-78 ... otbr-agent[7059]: 00:10:22.142 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460818419, rxerr:0 otbr-agent[7059]: 00:10:22.143 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:101, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:22.150 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:75, rssi:-76 ... otbr-agent[7059]: 00:10:22.151 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460830709, rxerr:0 otbr-agent[7059]: 00:10:22.153 [I] MeshForwarder-: Received IPv6 UDP msg, len:745, chksum:2aec, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-77.25 otbr-agent[7059]: 00:10:22.154 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:22.155 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:22.157 [I] SrpServer-----: Received DNS update from fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd otbr-agent[7059]: 00:10:22.269 [I] SrpServer-----: Processed SRP update info otbr-agent[7059]: 00:10:22.270 [I] SrpServer-----: Host:BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:22.271 [I] SrpServer-----: Lease:7200, key-lease:1209600, ttl:7200 otbr-agent[7059]: 00:10:22.272 [I] SrpServer-----: 1 host address(es): otbr-agent[7059]: 00:10:22.273 [I] SrpServer-----: fd85:4fd4:1b11:1:bffb:ea60:7610:45fb otbr-agent[7059]: 00:10:22.274 [I] SrpServer-----: Adding service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: 00:10:22.275 [I] SrpServer-----: sub-type: _IECAF001F0A1FD2EC otbr-agent[7059]: 00:10:22.277 [I] SrpServer-----: Adding service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: 00:10:22.278 [I] SrpServer-----: sub-type: _V4442 otbr-agent[7059]: 00:10:22.279 [I] SrpServer-----: sub-type: _S0 otbr-agent[7059]: 00:10:22.280 [I] SrpServer-----: sub-type: _L5 otbr-agent[7059]: 00:10:22.281 [I] SrpServer-----: SRP update handler is notified (updatedId = 116140142) otbr-agent[7059]: [INFO]-ADPROXY-: Advertise SRP service updates: host=BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: [INFO]-MDNS----: Add subtype _IECAF001F0A1FD2EC for service ECAF001F0A1FD2EC-00000000000003F1._matter._tcp otbr-agent[7059]: [INFO]-MDNS----: Commit avahi service ECAF001F0A1FD2EC-00000000000003F1._matter._tcp otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: [INFO]-MDNS----: Add subtype _V4442 for service 1DBCBC653CA9B66E._matterc._udp otbr-agent[7059]: [INFO]-MDNS----: Add subtype _S0 for service 1DBCBC653CA9B66E._matterc._udp otbr-agent[7059]: [INFO]-MDNS----: Add subtype _L5 for service 1DBCBC653CA9B66E._matterc._udp otbr-agent[7059]: [INFO]-MDNS----: Commit avahi service 1DBCBC653CA9B66E._matterc._udp otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP host 'BAF9CF352F3143BA.default.service.arpa.' otbr-agent[7059]: [INFO]-MDNS----: Commit avahi host BAF9CF352F3143BA otbr-agent[7059]: 00:10:22.360 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:22.361 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460843538, rxerr:0 otbr-agent[7059]: 00:10:22.363 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:22.364 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460865719, rxerr:0 otbr-agent[7059]: 00:10:22.365 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:22.366 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460881235, rxerr:0 otbr-agent[7059]: 00:10:22.368 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-77 ... otbr-agent[7059]: 00:10:22.369 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460889162, rxerr:0 otbr-agent[7059]: 00:10:22.370 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:22.371 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460904649, rxerr:0 otbr-agent[7059]: 00:10:22.372 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:22.373 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460922330, rxerr:0 otbr-agent[7059]: 00:10:22.376 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:22.377 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460943502, rxerr:0 otbr-agent[7059]: 00:10:22.378 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:22.379 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460968232, rxerr:0 otbr-agent[7059]: 00:10:22.380 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:22.381 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460983080, rxerr:0 otbr-agent[7059]: 00:10:22.383 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:22.384 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1460993250, rxerr:0 otbr-agent[7059]: 00:10:22.385 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:22.386 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1461010438, rxerr:0 otbr-agent[7059]: 00:10:22.388 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:22.389 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1461022467, rxerr:0 otbr-agent[7059]: 00:10:22.389 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:49, rssi:-75 ... otbr-agent[7059]: 00:10:22.390 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1461032679, rxerr:0 otbr-agent[7059]: 00:10:22.392 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:105, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:22.394 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:110, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:22.396 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:111, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:22.398 [I] MeshForwarder-: Received IPv6 UDP msg, len:911, chksum:487a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-75.25 otbr-agent[7059]: 00:10:22.399 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:22.400 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:22.401 [I] SrpServer-----: Received DNS update from fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd otbr-agent[7059]: 00:10:22.511 [I] SrpServer-----: Processed SRP update info otbr-agent[7059]: 00:10:22.512 [I] SrpServer-----: Host:BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:22.513 [I] SrpServer-----: Lease:7200, key-lease:1209600, ttl:7200 otbr-agent[7059]: 00:10:22.515 [I] SrpServer-----: 1 host address(es): otbr-agent[7059]: 00:10:22.516 [I] SrpServer-----: fd85:4fd4:1b11:1:bffb:ea60:7610:45fb otbr-agent[7059]: 00:10:22.520 [I] SrpServer-----: Adding service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: 00:10:22.521 [I] SrpServer-----: sub-type: _IECAF001F0A1FD2EC otbr-agent[7059]: 00:10:22.523 [I] SrpServer-----: Adding service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: 00:10:22.524 [I] SrpServer-----: sub-type: _V4442 otbr-agent[7059]: 00:10:22.525 [I] SrpServer-----: sub-type: _S0 otbr-agent[7059]: 00:10:22.526 [I] SrpServer-----: sub-type: _L5 otbr-agent[7059]: 00:10:22.527 [I] SrpServer-----: Adding service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.' otbr-agent[7059]: 00:10:22.528 [I] SrpServer-----: SRP update handler is notified (updatedId = 116140143) otbr-agent[7059]: [INFO]-ADPROXY-: Advertise SRP service updates: host=BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.' otbr-agent[7059]: [INFO]-MDNS----: Commit avahi service Nanoleaf A19 005-CEFD._ltpdu._udp otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP host 'BAF9CF352F3143BA.default.service.arpa.' otbr-agent[7059]: [INFO]-MDNS----: Avahi group (@0x77ed59f0) is established otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 2 otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 3 otbr-agent[7059]: [INFO]-MDNS----: Avahi group (@0xbe9560) is established otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 1 otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 2 otbr-agent[7059]: [INFO]-MDNS----: Avahi group (@0x77ed5910) is established otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP host 'BAF9CF352F3143BA.default.service.arpa.': OK otbr-agent[7059]: 00:10:23.273 [I] SrpServer-----: Handler result of SRP update (id = 116140142) is received: OK otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP host 'BAF9CF352F3143BA.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 1 otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: Committing update for existing host BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: Granted lease:7200, key-lease:680400, ttl:7200 otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: Add new service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: sub-type: _IECAF001F0A1FD2EC._sub._matter._tcp.default.service.arpa. otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: Add new service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: sub-type: _V4442._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:23.274 [I] SrpServer-----: sub-type: _S0._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:23.278 [I] SrpServer-----: sub-type: _L5._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:23.279 [I] SrpServer-----: Send success response with granted lease: 7200 and key lease: 680400 otbr-agent[7059]: 00:10:23.284 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:84, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.285 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.338 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.338 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:8b4d, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:23.338 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:23.339 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:23.339 [I] AddrResolver--: Sent AddressQuery for fd85:4fd4:1b11:1:bffb:ea60:7610:45fb otbr-agent[7059]: 00:10:23.339 [I] AddrResolver--: Cache entry added: fd85:4fd4:1b11:1:bffb:ea60:7610:45fb, 0xfffe - query request otbr-agent[7059]: 00:10:23.340 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:68, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.340 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.358 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.358 [I] MeshForwarder-: Sent IPv6 HopOpts msg, len:86, chksum:0000, ecn:no, to:0xffff, sec:yes, prio:net otbr-agent[7059]: 00:10:23.358 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6800] otbr-agent[7059]: 00:10:23.358 [I] MeshForwarder-: dst:[ff03:0:0:0:0:0:0:2] otbr-agent[7059]: 00:10:23.382 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:71, rssi:-76 ... otbr-agent[7059]: 00:10:23.382 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462065921, rxerr:0 otbr-agent[7059]: 00:10:23.382 [I] MeshForwarder-: Received IPv6 UDP msg, len:92, chksum:ac62, ecn:no, from:0x6801, sec:yes, prio:net, rss:-76.0 otbr-agent[7059]: 00:10:23.383 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6801]:61631 otbr-agent[7059]: 00:10:23.383 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:0:ff:fe00:6800]:61631 otbr-agent[7059]: 00:10:23.383 [I] AddrResolver--: Received AddressNotify from 0x6801 for fd85:4fd4:1b11:1:bffb:ea60:7610:45fb to 0x6801 otbr-agent[7059]: 00:10:23.383 [I] AddrResolver--: Cache entry updated: fd85:4fd4:1b11:1:bffb:ea60:7610:45fb, 0x6801 - rx notification otbr-agent[7059]: 00:10:23.383 [I] AddrResolver--: Sent AddressNotify ack otbr-agent[7059]: 00:10:23.384 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:68, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.384 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.402 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.402 [I] MeshForwarder-: Sent IPv6 HopOpts msg, len:86, chksum:0000, ecn:no, to:0xffff, sec:yes, prio:net otbr-agent[7059]: 00:10:23.402 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6800] otbr-agent[7059]: 00:10:23.404 [I] MeshForwarder-: dst:[ff03:0:0:0:0:0:0:2] otbr-agent[7059]: 00:10:23.405 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:34, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.406 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.422 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.422 [I] MeshForwarder-: Sent IPv6 UDP msg, len:54, chksum:8865, ecn:no, to:0x6801, sec:yes, prio:normal otbr-agent[7059]: 00:10:23.422 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:0:ff:fe00:6800]:61631 otbr-agent[7059]: 00:10:23.423 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:0:ff:fe00:6801]:61631 otbr-agent[7059]: 00:10:23.423 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.423 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: [INFO]-MDNS----: Avahi group (@0x77ec0660) is established otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.': OK otbr-agent[7059]: 00:10:23.467 [I] SrpServer-----: Handler result of SRP update (id = 116140143) is received: OK otbr-agent[7059]: 00:10:23.467 [I] SrpServer-----: Committing update for existing host BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:23.467 [I] SrpServer-----: Granted lease:7200, key-lease:680400, ttl:7200 otbr-agent[7059]: 00:10:23.467 [I] SrpServer-----: Update existing service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: 00:10:23.468 [I] SrpServer-----: sub-type: _IECAF001F0A1FD2EC._sub._matter._tcp.default.service.arpa. otbr-agent[7059]: 00:10:23.468 [I] SrpServer-----: Update existing service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: 00:10:23.468 [I] SrpServer-----: sub-type: _V4442._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:23.468 [I] SrpServer-----: sub-type: _S0._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:23.468 [I] SrpServer-----: sub-type: _L5._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:23.468 [I] SrpServer-----: Add new service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.' otbr-agent[7059]: 00:10:23.472 [I] SrpServer-----: Send success response with granted lease: 7200 and key lease: 680400 otbr-agent[7059]: 00:10:23.484 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.486 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.491 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.523 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.524 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.524 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.541 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.541 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:e084, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:23.542 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:23.542 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:23.542 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:84, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.545 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.565 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.565 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:c183, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:23.566 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:23.566 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:23.604 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-75 ... otbr-agent[7059]: 00:10:23.605 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462286474, rxerr:0 otbr-agent[7059]: 00:10:23.607 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:df73, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-75.0 otbr-agent[7059]: 00:10:23.608 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:23.609 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:23.611 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.613 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.614 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-76 ... otbr-agent[7059]: 00:10:23.616 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462292774, rxerr:0 otbr-agent[7059]: 00:10:23.617 [I] Mac-----------: Frame rx failed, error:Duplicated, len:81, seqnum:114, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:23.624 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-78 ... otbr-agent[7059]: 00:10:23.625 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462299069, rxerr:0 otbr-agent[7059]: 00:10:23.626 [I] Mac-----------: Frame rx failed, error:Duplicated, len:81, seqnum:114, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:23.638 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-75 ... otbr-agent[7059]: 00:10:23.639 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462314061, rxerr:0 otbr-agent[7059]: 00:10:23.642 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.643 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:23.645 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:23.646 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:23.656 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:23.657 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462329963, rxerr:0 otbr-agent[7059]: 00:10:23.676 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:23.677 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462353750, rxerr:0 otbr-agent[7059]: 00:10:23.700 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:23.701 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462377431, rxerr:0 otbr-agent[7059]: 00:10:23.725 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:23.726 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462402560, rxerr:0 otbr-agent[7059]: 00:10:23.748 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:23.749 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462426060, rxerr:0 otbr-agent[7059]: 00:10:23.763 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:23.764 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462441246, rxerr:0 otbr-agent[7059]: 00:10:23.779 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:119, rssi:-75 ... otbr-agent[7059]: 00:10:23.780 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1462457377, rxerr:0 otbr-agent[7059]: 00:10:23.782 [I] MeshForwarder-: Received IPv6 UDP msg, len:781, chksum:e79a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-75.0 otbr-agent[7059]: 00:10:23.783 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:23.784 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:23.786 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:120, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.788 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.816 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.817 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.819 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.842 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.844 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.845 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.881 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.883 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.884 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.922 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.924 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.926 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.949 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.951 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.952 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:23.975 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:23.977 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:23.978 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:24.006 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:24.008 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:24.009 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:24.031 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:24.033 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:71, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:24.034 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:24.051 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:24.052 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:829, chksum:dc31, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:24.053 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:24.055 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:24.293 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:24.294 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:24.317 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:24.319 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:24.320 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:24.353 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:24.354 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:24.356 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:24.384 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:24.385 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:e084, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:24.386 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:24.388 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:24.676 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.677 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463354216, rxerr:0 otbr-agent[7059]: 00:10:24.690 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.691 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463360544, rxerr:0 otbr-agent[7059]: 00:10:24.692 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:123, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:24.704 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.705 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463378648, rxerr:0 otbr-agent[7059]: 00:10:24.721 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.722 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463399038, rxerr:0 otbr-agent[7059]: 00:10:24.752 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.753 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463429903, rxerr:0 otbr-agent[7059]: 00:10:24.773 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.774 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463451604, rxerr:0 otbr-agent[7059]: 00:10:24.788 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:24.789 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463466132, rxerr:0 otbr-agent[7059]: 00:10:24.803 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.804 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463480456, rxerr:0 otbr-agent[7059]: 00:10:24.826 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:24.827 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463503528, rxerr:0 otbr-agent[7059]: 00:10:24.852 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:24.853 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463529229, rxerr:0 otbr-agent[7059]: 00:10:24.859 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:49, rssi:-75 ... otbr-agent[7059]: 00:10:24.860 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463540835, rxerr:0 otbr-agent[7059]: 00:10:24.861 [I] MeshForwarder-: Received IPv6 UDP msg, len:911, chksum:b294, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.625 otbr-agent[7059]: 00:10:24.862 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:24.864 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:24.865 [I] SrpServer-----: Received DNS update from fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd otbr-agent[7059]: 00:10:24.979 [I] SrpServer-----: Processed SRP update info otbr-agent[7059]: 00:10:24.980 [I] SrpServer-----: Host:BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:24.981 [I] SrpServer-----: Lease:7200, key-lease:1209600, ttl:7200 otbr-agent[7059]: 00:10:24.982 [I] SrpServer-----: 1 host address(es): otbr-agent[7059]: 00:10:24.983 [I] SrpServer-----: fd85:4fd4:1b11:1:bffb:ea60:7610:45fb otbr-agent[7059]: 00:10:24.984 [I] SrpServer-----: Adding service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: 00:10:24.985 [I] SrpServer-----: sub-type: _IECAF001F0A1FD2EC otbr-agent[7059]: 00:10:24.987 [I] SrpServer-----: Adding service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: 00:10:24.988 [I] SrpServer-----: sub-type: _V4442 otbr-agent[7059]: 00:10:24.989 [I] SrpServer-----: sub-type: _S0 otbr-agent[7059]: 00:10:24.990 [I] SrpServer-----: sub-type: _L5 otbr-agent[7059]: 00:10:24.991 [I] SrpServer-----: Adding service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.' otbr-agent[7059]: 00:10:24.992 [I] SrpServer-----: SRP update handler is notified (updatedId = 116140144) otbr-agent[7059]: [INFO]-ADPROXY-: Advertise SRP service updates: host=BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 3 otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 2 otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.' otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.': OK otbr-agent[7059]: [INFO]-ADPROXY-: Waiting for more publishing callbacks 1 otbr-agent[7059]: [DEBG]-ADPROXY-: Publish SRP host 'BAF9CF352F3143BA.default.service.arpa.' otbr-agent[7059]: [INFO]-ADPROXY-: Handle publish SRP host 'BAF9CF352F3143BA.default.service.arpa.': OK otbr-agent[7059]: 00:10:25.006 [I] SrpServer-----: Handler result of SRP update (id = 116140144) is received: OK otbr-agent[7059]: 00:10:25.007 [I] SrpServer-----: Committing update for existing host BAF9CF352F3143BA.default.service.arpa. otbr-agent[7059]: 00:10:25.008 [I] SrpServer-----: Granted lease:7200, key-lease:680400, ttl:7200 otbr-agent[7059]: 00:10:25.009 [I] SrpServer-----: Update existing service 'ECAF001F0A1FD2EC-00000000000003F1._matter._tcp.default.service.arpa.' otbr-agent[7059]: 00:10:25.010 [I] SrpServer-----: sub-type: _IECAF001F0A1FD2EC._sub._matter._tcp.default.service.arpa. otbr-agent[7059]: 00:10:25.011 [I] SrpServer-----: Update existing service '1DBCBC653CA9B66E._matterc._udp.default.service.arpa.' otbr-agent[7059]: 00:10:25.012 [I] SrpServer-----: sub-type: _V4442._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:25.012 [I] SrpServer-----: sub-type: _S0._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:25.013 [I] SrpServer-----: sub-type: _L5._sub._matterc._udp.default.service.arpa. otbr-agent[7059]: 00:10:25.014 [I] SrpServer-----: Update existing service 'Nanoleaf A19 005-CEFD._ltpdu._udp.default.service.arpa.' otbr-agent[7059]: 00:10:25.015 [I] SrpServer-----: Send success response with granted lease: 7200 and key lease: 680400 otbr-agent[7059]: 00:10:25.017 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-75 ... otbr-agent[7059]: 00:10:25.018 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463555189, rxerr:0 otbr-agent[7059]: 00:10:25.019 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.020 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463570009, rxerr:0 otbr-agent[7059]: 00:10:25.021 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.022 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463583943, rxerr:0 otbr-agent[7059]: 00:10:25.023 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.024 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463592303, rxerr:0 otbr-agent[7059]: 00:10:25.025 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:25.026 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463606457, rxerr:0 otbr-agent[7059]: 00:10:25.027 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.028 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463638586, rxerr:0 otbr-agent[7059]: 00:10:25.029 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.030 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463654817, rxerr:0 otbr-agent[7059]: 00:10:25.031 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.033 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463670060, rxerr:0 otbr-agent[7059]: 00:10:25.033 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.034 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463684671, rxerr:0 otbr-agent[7059]: 00:10:25.036 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:134, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.037 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:134, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.040 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:119, rssi:-75 ... otbr-agent[7059]: 00:10:25.041 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463705427, rxerr:0 otbr-agent[7059]: 00:10:25.043 [I] MeshForwarder-: Received IPv6 UDP msg, len:781, chksum:e79a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.625 otbr-agent[7059]: 00:10:25.044 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:25.045 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:25.047 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:84, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.048 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.050 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-74 ... otbr-agent[7059]: 00:10:25.051 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463726715, rxerr:0 otbr-agent[7059]: 00:10:25.072 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-75 ... otbr-agent[7059]: 00:10:25.073 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463750145, rxerr:0 otbr-agent[7059]: 00:10:25.074 [I] Mac-----------: Frame rx failed, error:Duplicated, len:123, seqnum:141, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.076 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.077 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:5ad9, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:25.079 [I] MeshForwarder-: src:[fda8:bf44:3aa5:be08:5722:1969:4476:6bd6]:53539 otbr-agent[7059]: 00:10:25.080 [I] MeshForwarder-: dst:[fda8:bf44:3aa5:be08:3d2a:5770:f5ae:12fd]:49155 otbr-agent[7059]: 00:10:25.082 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:120, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.083 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.092 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-75 ... otbr-agent[7059]: 00:10:25.093 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463758841, rxerr:0 otbr-agent[7059]: 00:10:25.094 [I] Mac-----------: Frame rx failed, error:Duplicated, len:123, seqnum:141, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.104 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.105 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463773837, rxerr:0 otbr-agent[7059]: 00:10:25.108 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.110 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.111 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.123 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.124 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463787923, rxerr:0 otbr-agent[7059]: 00:10:25.125 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:142, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.136 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.137 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463812711, rxerr:0 otbr-agent[7059]: 00:10:25.149 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.151 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.152 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.172 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.173 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463850037, rxerr:0 otbr-agent[7059]: 00:10:25.176 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.178 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.179 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.192 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.193 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463869870, rxerr:0 otbr-agent[7059]: 00:10:25.223 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.224 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463901402, rxerr:0 otbr-agent[7059]: 00:10:25.247 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.248 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.250 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.290 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-76 ... otbr-agent[7059]: 00:10:25.291 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463968655, rxerr:0 otbr-agent[7059]: 00:10:25.293 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:146, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.295 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.296 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.298 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.309 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-77 ... otbr-agent[7059]: 00:10:25.310 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1463983862, rxerr:0 otbr-agent[7059]: 00:10:25.322 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.323 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:254, timestamp:1463994409, rxerr:0 otbr-agent[7059]: 00:10:25.325 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:147, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.340 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:119, rssi:-75 ... otbr-agent[7059]: 00:10:25.341 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464018386, rxerr:0 otbr-agent[7059]: 00:10:25.343 [I] MeshForwarder-: Received IPv6 UDP msg, len:781, chksum:e79a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.875 otbr-agent[7059]: 00:10:25.344 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:25.345 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:25.347 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.349 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.350 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.364 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-75 ... otbr-agent[7059]: 00:10:25.365 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464042478, rxerr:0 otbr-agent[7059]: 00:10:25.380 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-77 ... otbr-agent[7059]: 00:10:25.381 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464058655, rxerr:0 otbr-agent[7059]: 00:10:25.385 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.387 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.388 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.399 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.400 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464075199, rxerr:0 otbr-agent[7059]: 00:10:25.410 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.411 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:71, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.413 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.430 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:25.431 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:251, timestamp:1464107523, rxerr:0 otbr-agent[7059]: 00:10:25.434 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.435 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:829, chksum:dc31, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:25.436 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:25.437 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:25.439 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:120, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.440 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.447 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.448 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464115132, rxerr:0 otbr-agent[7059]: 00:10:25.450 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:152, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:25.468 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.469 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464144422, rxerr:0 otbr-agent[7059]: 00:10:25.471 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.472 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.474 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.490 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.491 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464167704, rxerr:0 otbr-agent[7059]: 00:10:25.504 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:25.505 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464182629, rxerr:0 otbr-agent[7059]: 00:10:25.509 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.510 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.512 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.522 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:119, rssi:-74 ... otbr-agent[7059]: 00:10:25.523 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464196944, rxerr:0 otbr-agent[7059]: 00:10:25.525 [I] MeshForwarder-: Received IPv6 UDP msg, len:781, chksum:e79a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.875 otbr-agent[7059]: 00:10:25.526 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:25.527 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:25.585 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.587 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.588 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.612 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.613 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.615 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.637 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.638 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.639 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.662 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.663 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.664 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.687 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.688 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.690 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.715 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.716 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:71, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.718 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.733 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.734 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:829, chksum:dc31, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:25.735 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:25.737 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:25.738 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.739 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.769 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.771 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.772 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.793 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.795 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.796 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.815 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.816 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:e084, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:25.817 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:25.818 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:25.820 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:120, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.821 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.841 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-75 ... otbr-agent[7059]: 00:10:25.842 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:145, timestamp:1464523164, rxerr:0 otbr-agent[7059]: 00:10:25.844 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:dd73, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-75.0 otbr-agent[7059]: 00:10:25.845 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:25.846 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:25.848 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.850 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.851 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.874 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.875 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.877 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.899 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.901 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.902 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.926 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.928 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.929 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.952 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.954 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.955 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:25.977 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:25.979 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:25.980 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.023 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.024 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.025 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.049 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.051 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:71, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.052 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.074 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.075 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:829, chksum:dc31, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:26.076 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:26.077 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:26.079 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.080 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.110 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.112 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:26.113 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:26.114 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:26.162 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:123, rssi:-75 ... otbr-agent[7059]: 00:10:26.163 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464840337, rxerr:0 otbr-agent[7059]: 00:10:26.184 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:26.185 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464862250, rxerr:0 otbr-agent[7059]: 00:10:26.198 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:26.199 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464869215, rxerr:0 otbr-agent[7059]: 00:10:26.200 [I] Mac-----------: Frame rx failed, error:Duplicated, len:122, seqnum:159, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:26.212 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:26.213 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464884097, rxerr:0 otbr-agent[7059]: 00:10:26.226 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:26.227 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464900359, rxerr:0 otbr-agent[7059]: 00:10:26.239 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:26.240 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464916162, rxerr:0 otbr-agent[7059]: 00:10:26.259 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-74 ... otbr-agent[7059]: 00:10:26.260 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464936825, rxerr:0 otbr-agent[7059]: 00:10:26.286 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:122, rssi:-75 ... otbr-agent[7059]: 00:10:26.287 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464964441, rxerr:0 otbr-agent[7059]: 00:10:26.309 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:119, rssi:-74 ... otbr-agent[7059]: 00:10:26.310 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1464987457, rxerr:0 otbr-agent[7059]: 00:10:26.312 [I] MeshForwarder-: Received IPv6 UDP msg, len:781, chksum:e79a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.5 otbr-agent[7059]: 00:10:26.313 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:26.315 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:26.317 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:120, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.318 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.348 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.349 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.351 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.379 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.380 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.381 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.405 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.406 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.408 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.430 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.432 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.433 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.456 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.458 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.459 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.482 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.484 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.485 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.507 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.509 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.510 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.533 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.535 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:71, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.536 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.552 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.552 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:829, chksum:dc31, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:26.552 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:26.552 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:26.918 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.919 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.942 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.944 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.945 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.968 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.970 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:26.971 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:26.996 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:26.997 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:e084, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:26.998 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:26.999 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:27.022 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-74 ... otbr-agent[7059]: 00:10:27.023 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1465705172, rxerr:0 otbr-agent[7059]: 00:10:27.025 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:dc73, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.0 otbr-agent[7059]: 00:10:27.026 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:27.027 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:27.029 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:27.030 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:27.054 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:27.055 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:27.057 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:27.058 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:29.282 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:29.282 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:29.371 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:29.373 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:29.374 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:29.401 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:29.402 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:29.404 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:29.419 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:29.420 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:e084, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:29.421 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:29.422 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:29.455 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-77 ... otbr-agent[7059]: 00:10:29.456 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1468137693, rxerr:0 otbr-agent[7059]: 00:10:29.457 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:db73, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-77.0 otbr-agent[7059]: 00:10:29.458 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:29.459 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:29.462 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:29.463 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:29.487 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:29.488 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:29.489 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:29.490 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:30.584 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) otbr-agent[7059]: 00:10:30.585 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:30.586 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:30.602 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:30.603 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:6dea, ecn:no, to:0xffff, sec:no, prio:net otbr-agent[7059]: 00:10:30.604 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:30.605 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:10:37.756 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:37.757 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:37.781 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:37.782 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:37.784 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:37.806 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:37.807 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:37.808 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:37.825 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:37.826 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:fd3d, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:37.827 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:37.828 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:37.851 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-76 ... otbr-agent[7059]: 00:10:37.852 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1476534166, rxerr:0 otbr-agent[7059]: 00:10:37.854 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:063a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-76.0 otbr-agent[7059]: 00:10:37.855 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:37.856 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:37.858 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:37.859 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:37.883 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:37.884 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:37.885 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:37.887 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:38.834 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:38.835 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:38.877 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:38.878 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:38.879 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:38.901 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:38.903 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:38.904 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:38.927 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:38.928 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:fd3d, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:38.929 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:38.931 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:38.953 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-74 ... otbr-agent[7059]: 00:10:38.954 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1477636530, rxerr:0 otbr-agent[7059]: 00:10:38.956 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:053a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-74.0 otbr-agent[7059]: 00:10:38.957 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:38.958 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:38.960 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:38.961 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:38.984 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:38.985 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:38.987 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:38.988 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]

otbr-agent[7059]: 00:10:39.862 [I] Mle-----------: Send Announce on channel 11 otbr-agent[7059]: 00:10:39.863 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:78, channel:11, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:39.863 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:1, isARetx:0, skipAes:1, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:39.888 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:39.889 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:4f28, ecn:no, to:0xffff, sec:yes, prio:net otbr-agent[7059]: 00:10:39.890 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:39.891 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:10:39.893 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:PHY_CHAN, channel:24 otbr-agent[7059]: 00:10:39.894 [D] P-RadioSpinel-: Wait response: tid=12 key=33 otbr-agent[7059]: 00:10:39.895 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:PHY_CHAN, channel:24 otbr-agent[7059]: 00:10:39.916 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:39.917 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:39.968 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:39.969 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:39.970 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:40.000 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:40.002 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:40.003 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:40.019 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:40.020 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:fd3d, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:40.021 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:40.022 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:40.045 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-73 ... otbr-agent[7059]: 00:10:40.047 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1478728755, rxerr:0 otbr-agent[7059]: 00:10:40.048 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:043a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-73.0 otbr-agent[7059]: 00:10:40.049 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:40.051 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:40.053 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:40.054 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:40.078 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:40.079 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:40.081 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:40.082 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:41.638 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:41.639 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:41.721 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:41.723 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:41.724 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:41.748 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:41.749 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:41.751 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:41.766 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:41.767 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:fd3d, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:41.768 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:41.769 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:41.803 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-78 ... otbr-agent[7059]: 00:10:41.804 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:254, timestamp:1480485755, rxerr:0 otbr-agent[7059]: 00:10:41.805 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:033a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-78.0 otbr-agent[7059]: 00:10:41.806 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:41.808 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:41.810 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:41.811 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:41.864 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:41.865 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:41.866 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:41.867 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:43.203 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) otbr-agent[7059]: 00:10:43.205 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:43.206 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:43.223 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:43.224 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:af24, ecn:no, to:0xffff, sec:no, prio:net otbr-agent[7059]: 00:10:43.225 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:43.226 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:10:44.246 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:44.247 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:44.270 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:44.270 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:44.271 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:44.299 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:44.299 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:44.300 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:44.320 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:44.321 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:fd3d, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:44.321 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:44.321 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:44.348 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-79 ... otbr-agent[7059]: 00:10:44.348 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:251, timestamp:1483029871, rxerr:0 otbr-agent[7059]: 00:10:44.348 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:023a, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-79.0 otbr-agent[7059]: 00:10:44.348 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:44.349 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:44.352 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:44.353 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:44.357 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-79 ... otbr-agent[7059]: 00:10:44.359 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1483039697, rxerr:0 otbr-agent[7059]: 00:10:44.360 [I] Mac-----------: Frame rx failed, error:Duplicated, len:81, seqnum:172, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:44.378 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:44.378 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:44.378 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:44.379 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:48.515 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:10, rssi:-58 ... otbr-agent[7059]: 00:10:48.515 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1487206708, rxerr:0 otbr-agent[7059]: 00:10:48.515 [I] Mac-----------: Received Beacon Request otbr-agent[7059]: 00:10:48.517 [I] Mac-----------: Sending Beacon otbr-agent[7059]: 00:10:48.518 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:15, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:48.519 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:48.528 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:51.031 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:51.031 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:51.057 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:51.059 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:51.060 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:51.118 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:51.119 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:51.120 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:51.137 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:51.138 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:d3fd, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:51.140 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:51.141 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:51.163 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-79 ... otbr-agent[7059]: 00:10:51.164 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1489845935, rxerr:0 otbr-agent[7059]: 00:10:51.165 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:a6d9, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-79.0 otbr-agent[7059]: 00:10:51.167 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:51.168 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:51.170 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:51.171 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:51.196 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:51.197 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:51.198 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:51.199 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:52.057 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:52.058 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:52.089 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:52.091 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:52.092 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:52.121 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:52.123 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:52.124 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:52.140 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:52.141 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:d3fd, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:52.142 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:52.144 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:52.185 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-78 ... otbr-agent[7059]: 00:10:52.186 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1490868633, rxerr:0 otbr-agent[7059]: 00:10:52.188 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:a5d9, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-78.0 otbr-agent[7059]: 00:10:52.189 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:52.190 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:52.192 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:52.193 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:52.217 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:52.218 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:52.219 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:52.220 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:53.066 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:53.067 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:53.106 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:53.107 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:53.109 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:53.131 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:53.133 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:53.134 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:53.155 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:7, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:53.156 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:d3fd, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:53.157 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:53.158 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:53.190 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-80 ... otbr-agent[7059]: 00:10:53.191 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1491872579, rxerr:0 otbr-agent[7059]: 00:10:53.193 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:a4d9, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-80.0 otbr-agent[7059]: 00:10:53.194 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:53.195 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:53.197 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:53.199 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:53.200 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-81 ... otbr-agent[7059]: 00:10:53.201 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1491878554, rxerr:0 otbr-agent[7059]: 00:10:53.203 [I] Mac-----------: Frame rx failed, error:Duplicated, len:81, seqnum:175, type:Data, src:0x6801, dst:0x6800, sec:yes, ackreq:yes otbr-agent[7059]: 00:10:53.229 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:8, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:53.230 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:53.231 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:53.233 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:54.809 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:54.809 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:54.846 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:9, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:54.847 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:54.847 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:54.877 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:10, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:54.878 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:54.878 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:54.896 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:11, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:54.896 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:d3fd, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:54.896 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:54.896 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:54.924 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-78 ... otbr-agent[7059]: 00:10:54.924 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1493607314, rxerr:0 otbr-agent[7059]: 00:10:54.925 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:a3d9, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-78.0 otbr-agent[7059]: 00:10:54.925 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:54.925 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:54.926 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:54.927 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:54.952 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:12, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:54.952 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:54.953 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:54.953 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:10:57.091 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:127, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:57.092 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:57.120 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:13, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:57.121 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:122, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:57.123 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:57.142 [I] Mle-----------: Send Advertisement (ff02:0:0:0:0:0:0:1) otbr-agent[7059]: 00:10:57.152 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:14, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:57.153 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:69, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:57.154 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:57.170 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:15, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:57.171 [I] MeshForwarder-: Sent IPv6 UDP msg, len:90, chksum:899e, ecn:no, to:0xffff, sec:no, prio:net otbr-agent[7059]: 00:10:57.173 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:10:57.174 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:10:57.176 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:62, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:57.177 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:57.198 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:1, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:57.199 [I] MeshForwarder-: Sent IPv6 UDP msg, len:244, chksum:d3fd, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:57.200 [I] MeshForwarder-: src:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:57.201 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:57.226 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:81, rssi:-78 ... otbr-agent[7059]: 00:10:57.227 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1495909384, rxerr:0 otbr-agent[7059]: 00:10:57.229 [I] MeshForwarder-: Received IPv6 UDP msg, len:74, chksum:a2d9, ecn:no, from:0x6801, sec:yes, prio:normal, rss:-78.0 otbr-agent[7059]: 00:10:57.230 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb]:5540 otbr-agent[7059]: 00:10:57.231 [I] MeshForwarder-: dst:[fd36:8aa7:fee1:c2d2:a00:27ff:fe9a:2358]:59502 otbr-agent[7059]: 00:10:57.233 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:126, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:10:57.234 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:10:57.259 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:2, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:10:57.260 [I] MeshForwarder-: Sent IPv6 ICMP6 msg, len:122, chksum:def4, ecn:no, to:0x6801, sec:yes, prio:low otbr-agent[7059]: 00:10:57.261 [I] MeshForwarder-: src:[fd85:4fd4:1b11:1:c72c:3c29:4d1:6ee7] otbr-agent[7059]: 00:10:57.262 [I] MeshForwarder-: dst:[fd85:4fd4:1b11:1:bffb:ea60:7610:45fb] otbr-agent[7059]: 00:11:00.579 [D] P-RadioSpinel-: Trying to get RCP time offset otbr-agent[7059]: 00:11:00.579 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_GET, key:TIMESTAMP otbr-agent[7059]: 00:11:00.579 [D] P-RadioSpinel-: Wait response: tid=3 key=2050 otbr-agent[7059]: 00:11:00.582 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:3, cmd:PROP_VALUE_IS, key:TIMESTAMP, timestamp:1499277533 otbr-agent[7059]: 00:11:01.059 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:0, cmd:PROP_VALUE_IS, key:STREAM_RAW, len:10, rssi:-58 ... otbr-agent[7059]: 00:11:01.060 [D] P-RadioSpinel-: ... noise:-128, flags:0x0000, channel:24, lqi:255, timestamp:1499751311, rxerr:0 otbr-agent[7059]: 00:11:01.061 [I] Mac-----------: Received Beacon Request otbr-agent[7059]: 00:11:01.063 [I] Mac-----------: Sending Beacon otbr-agent[7059]: 00:11:01.065 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:15, channel:24, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:11:01.066 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:0, isARetx:0, skipAes:0, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:11:01.075 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:4, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:11:01.840 [I] Mle-----------: Send Announce on channel 12 otbr-agent[7059]: 00:11:01.841 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_SET, key:STREAM_RAW, len:78, channel:12, maxbackoffs:4, maxretries:15 ... otbr-agent[7059]: 00:11:01.843 [D] P-RadioSpinel-: ... csmaCaEnabled:1, isHeaderUpdated:1, isARetx:0, skipAes:1, txDelay:0, txDelayBase:0 otbr-agent[7059]: 00:11:01.860 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:5, cmd:PROP_VALUE_IS, key:LAST_STATUS, status:OK otbr-agent[7059]: 00:11:01.861 [I] MeshForwarder-: Sent IPv6 UDP msg, len:83, chksum:5e0b, ecn:no, to:0xffff, sec:yes, prio:net otbr-agent[7059]: 00:11:01.862 [I] MeshForwarder-: src:[fe80:0:0:0:58ea:5342:8fcd:90ba]:19788 otbr-agent[7059]: 00:11:01.863 [I] MeshForwarder-: dst:[ff02:0:0:0:0:0:0:1]:19788 otbr-agent[7059]: 00:11:01.865 [D] P-RadioSpinel-: Sent spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_SET, key:PHY_CHAN, channel:24 otbr-agent[7059]: 00:11:01.866 [D] P-RadioSpinel-: Wait response: tid=6 key=33 otbr-agent[7059]: 00:11:01.867 [D] P-RadioSpinel-: Received spinel frame, flg:0x2, iid:0, tid:6, cmd:PROP_VALUE_IS, key:PHY_CHAN, channel:24

bzbarsky-apple commented 8 months ago

@xjshiMax I don't know how to read those OTBR logs, so if you're looking for help with that you might need to find someone else to help you...