This post is older than 2 years and might not be relevant anymore
More Info: Consider searching for newer posts

Mesh latency during 2nd node configuration

Hello,

I have a little issue during the mesh configuration of my network.

First my provisioner (called master) provision and configure a first node (called slave), this step works well and my slave will send a custom message every minutes "surveillance message".

My problem comes with the second node : Provisioning is fine but configuration stops after the "Adding appkey" step on provisioner (at time 00:04:04 in provisioner logs). The slave is receiving the message and responds to it after having added the appkey (dsm_appkey_add line 48 of slave 2 logs) with opcode 0x8003. The provisioner does not receive this message and tries to send multiple times the "adding appkey" message . After more than 3 minutes of retries, the provisioner finally receives the response (0x8003 at 00:07:48, line 326 on provisioner logs) and performs the end of the configuration. Then both slaves nodes are able to send their surveillance message every minutes.

I would like the configuration step of the second node to be as fast as for the first one instead of during 3 minutes.

I tried the same setup powering off the first node after its provisioning and configuration, and the problem disappeared : provisioning and configurations only lasted a few seconds for the second node ! However, in the first case, the first node is not sending any messages while the other is being configured or it seems not to because i do not receive any "RX: [aop: " logs.

Thanks for helping,

Provisioner (master) logs :

<00:00:00>,...I N D O O R   O U T D O O R  A N C H O R  F I R M W A R E (ADM-F-P40-5.0)...
<00:00:00>,...This version was compiled at 12:07:10 Dec 12 2019
<00:00:00>,[SYSCONFIG] Generic reset cause  : 0xE
<00:00:00>,[SYSCONFIG] Specific reset cause : 0
<00:00:00>,[SYSCONFIG] Reset caused by watchdog
<00:00:00>,[SYSCONFIG] Reset caused by soft reset
<00:00:00>,[SYSCONFIG] Reset caused by CPU lock-up
<00:00:00>,[SYSCONFIG] Master Anchor
<00:00:02>,[DWMNGR] Init OK 
<00:00:02>,[DWMNGR] Anchor Address: 0XC886 (51334)
<00:00:02>,[Mesh DRV] Init Anchor as Master & Provisioner
<00:00:02>,Initializing and adding models
<00:00:02>,[FLASH DRV]Flash manager added for app data
<00:00:02>,[FLASH DRV]Unable to add flash manager for anchor config
<00:00:02>,[FLASH DRV]Unable to add flash manager for server info
<00:00:02>,[SYSCONFIG] Anchor not configured by BLE
<00:00:02>,[MESH DRV]Server Info : 
	 Base @ : .localeez.adveez.com 
	 UDP @ : staging.api.fama 
	 Port @ : 9998 
<00:00:02>,[SYSCONFIG] Building ID: 0 => Adveez   0 | Master Anchor 0XC886(51334) config Grp 0, Mode 2
<00:00:02>,[MESH DRV]Setup defaults: Adding keys, addresses, and bindings 
<00:00:02>,[PROV-HELPER]netkey_handle: 0
<00:00:02>,[MESH DRV]Setting master message model 0x0012 to publish to group @ 0x0000
<00:00:02>,[PROVISIONER DRV]Waiting for Slave node to be provisioned ...
<00:00:02>,Slave URI index: 537002800
<00:00:02>,[PROV-HELPER]Scanning For Unprovisioned Devices
<00:00:02>,Device UUID (raw): E661E90B53F6B54DB7085E85454E3F11
<00:00:02>,Device UUID : 0BE961E6-F653-4DB5-B708-5E85454E3F11
<00:00:02>,[FLASH DRV]Flash manager added for anchor config
<00:00:02>,[FLASH DRV]Unable to add flash manager for server info
<00:00:02>,[FLASH DRV]Flash manager added for server info
<00:00:02>,[FLASH DRV]Flash write complete
<00:00:02>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:00:02>,[PROVISIONER DRV]Starting application ...
<00:00:02>,                 Provisoned Nodes: 0, Configured Nodes: 0 Next Address: 0x0100
<00:00:02>,                 Dev key : 75789AF50296629E931A327E97E3BA3E
<00:00:02>,                 Net key : A98F61A4A63ADE68B325CA2DDBC93347
<00:00:02>,                 App key : A2441A35A021544C589889A585055DF2
<00:00:02>,                 Device URI : ¹Adveez   0
<00:00:02>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:00:02>,[BLE]-> Fast advertising
<00:00:34>,[PROV-HELPER]UUID seen: 060CCF5DF136794BB9C717C85D397E91
<00:00:34>,^RSSI: -31
<00:00:34>,^URI Hash: EA2D509D
<00:00:34>,URI hash matched. Provisioning ...
<00:00:34>,[PROV-HELPER]Start provisioning address: 0x0100 
<00:00:34>,PB-ADV: context at 0x200203BC added to bearer
<00:00:34>,PB-ADV: Link opened.
<00:00:34>,[PROV-HELPER]Provisioning link established
<00:00:34>,Received ACK for [ppdu0]
<00:00:35>,Received ACK for [ppdu2]
<00:00:35>,Received ACK for [ppdu3]
<00:00:35>,Provisioner L945685737: provisionee's public key received!
<00:00:35>,[PROV-HELPER]Static authentication data provided
<00:00:35>,Provisioner L945685737: requested static OOB data from application
<00:00:35>,Received ACK for [ppdu5]
<00:00:35>,Provisioner L945685737: provisioning confirmation received!
<00:00:35>,Received ACK for [ppdu6]
<00:00:35>,Provisioner L945685737: provisionee's random number received!
<00:00:35>,Provisioner L945685737: sending provisioning data
<00:00:37>,Got start packet for a transaction we've already processed, send the ack again.<00:00:37>,TX RETRY: [ppdu7]
<00:00:39>,TX RETRY: [ppdu7]
<00:00:39>,Received ACK for [ppdu7]
<00:00:39>,Provisioner L945685737: received provisioning complete message!
<00:00:39>,[PROV-HELPER]Provisioning completed received
<00:00:39>,[PROV-HELPER]Adding device address, and device keys
<00:00:39>,[PROV-HELPER]Addr: 0x0100 addr_handle: 1 netkey_handle: 0 devkey_handle: 2
<00:00:39>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:00:39>,[PROV-HELPER]Local provisioning link closed: prov_state: 3  remaining retries: 10
<00:00:39>,[PROVISIONER DRV] Provisioning successful
<00:00:39>,[PROV-HELPER]Provisioning complete. Node addr: 0x0100 elements: 1
<00:00:39>,[NODE SETUP ]Configuring Node: 0x0100...
<00:00:39>,Config client setup: devkey_handle:2 addr_handle:1
<00:00:39>,Getting composition data
<00:00:39>,TX: [aop: 0x8008] 
<00:00:39>,TX: Msg: 00
<00:00:39>,[FLASH DRV]Flash write complete
<00:00:39>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:00:40>,TX: [aop: 0x8008] 
<00:00:40>,TX: Msg: 00
<00:00:40>,RX: [aop: 0x0002]
<00:00:40>,RX: Msg: 005900000000001F000100000002010000020059000D00
<00:00:40>,[PROVISIONER DRV]Config client event
<00:00:40>,Updating network transmit: count: 2 steps: 1
<00:00:40>,TX: [aop: 0x8024] 
<00:00:40>,TX: Msg: 0A
<00:00:40>,RX: [aop: 0x8025]
<00:00:40>,RX: Msg: 0A
<00:00:40>,[PROVISIONER DRV]Config client event
<00:00:40>,Adding appkey
<00:00:40>,TX: [aop: 0x0000] 
<00:00:40>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:40>,[er8] <= access_model_publish()
<00:00:40>,TX: [aop: 0x0000] 
<00:00:40>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:41>,RX: [aop: 0x8003]
<00:00:41>,RX: Msg: 00000000
<00:00:41>,[PROVISIONER DRV]Config client event
<00:00:41>,opcode status field: 0 
<00:00:41>,App key bind: Custom Message slave on element 0x0100
<00:00:41>,TX: [aop: 0x803D] 
<00:00:41>,TX: Msg: 0001000059000D00
<00:00:41>,RX: [aop: 0x803E]
<00:00:41>,RX: Msg: 000001000059000D00
<00:00:41>,[PROVISIONER DRV]Config client event
<00:00:41>,opcode status field: 0 
<00:00:41>,Setting publication address for the message slave 0x0100 to 0x0002
<00:00:41>,[er8] <= access_model_publish()
<00:00:41>,RX: [aop: 0x8003]
<00:00:41>,RX: Msg: 00000000
<00:00:41>,[PROVISIONER DRV]Config client event
<00:00:41>,Unexpected opcode: exp 0x8019  rx 0x8003
<00:00:41>,TX: [aop: 0x0003] 
<00:00:41>,TX: Msg: 0001020000000F000159000D00
<00:00:41>,RX: [aop: 0x8019]
<00:00:41>,RX: Msg: 000001020000000F000159000D00
<00:00:41>,[PROVISIONER DRV]Config client event
<00:00:41>,opcode status field: 0 
<00:00:41>,Adding subscription of element 0x0100 to group 0xC002
<00:00:41>,TX: [aop: 0x801B] 
<00:00:41>,TX: Msg: 000102C059000D00
<00:00:42>,RX: [aop: 0x801F]
<00:00:42>,RX: Msg: 00000102C059000D00
<00:00:42>,[PROVISIONER DRV]Config client event
<00:00:42>,opcode status field: 0 
<00:00:42>,Configuration of device 0 successful
<00:00:42>,Setting master message model 0x0003 to publish @ 0x0001
<00:00:42>,[PROV-HELPER]Scanning For Unprovisioned Devices
<00:00:42>,[FLASH DRV]Flash write complete
<00:00:42>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:01:02>,DIAG->FW=5.0 Building=0
 Master 51334(0xC886),Rx = 0, Err : GSM =0, UWB =0, Mesh =0| IIL =0
<00:01:43>,RX: [aop: 0x00C1]
<00:01:43>,RX: Msg: B00000DA1700000000020105000E000000000000000000
<00:01:43>,[BLEMNGR] RX->: B00000DA1700000000020105000E000000000000000000
<00:01:43>,[SYSCONFIG] Anchor 1 config added 
<00:01:43>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(14,0) 
<00:01:44>,TX: [aop: 0x00C2] 
<00:01:44>,TX: Msg: 
<00:01:44>,Sending ack to @ 0x0003, status : 0
<00:01:44>,[APP]NETWORK ALERT needed to be sent
<00:01:44>,DIAG->FW=5.0 Building=0
 Master 51334(0xC886),Rx = 0, Err : GSM =0, UWB =0, Mesh =0| IIL =0
<00:01:44>,[APP]->55831(0xDA17)[2],CONN,5.0 Grp : 1, Rx =    0,Err: UWB =     0, Mesh =    0
<00:02:46>,RX: [aop: 0x00C1]
<00:02:46>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:02:46>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:02:46>,[SYSCONFIG] Anchor 1 config updated 
<00:02:46>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:02:46>,TX: [aop: 0x00C2] 
<00:02:46>,TX: Msg: 
<00:02:46>,Sending ack to @ 0x0003, status : 0
<00:03:49>,RX: [aop: 0x00C1]
<00:03:49>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:03:49>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:03:49>,[SYSCONFIG] Anchor 1 config updated 
<00:03:49>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:03:49>,TX: [aop: 0x00C2] 
<00:03:49>,TX: Msg: 
<00:03:49>,Sending ack to @ 0x0003, status : 0
<00:04:03>,[PROV-HELPER]UUID seen: 54D66EF7D216B34DBBFAA188F8D60ADE
<00:04:03>,^RSSI: -29
<00:04:03>,^URI Hash: EA2D509D
<00:04:03>,URI hash matched. Provisioning ...
<00:04:03>,[PROV-HELPER]Start provisioning address: 0x0101 
<00:04:03>,PB-ADV: context at 0x200203BC added to bearer
<00:04:03>,PB-ADV: Link opened.
<00:04:03>,[PROV-HELPER]Provisioning link established
<00:04:03>,Received ACK for [ppdu0]
<00:04:04>,Received ACK for [ppdu2]
<00:04:04>,Received ACK for [ppdu3]
<00:04:04>,Provisioner L-1625619469: provisionee's public key received!
<00:04:04>,[PROV-HELPER]Static authentication data provided
<00:04:04>,Provisioner L-1625619469: requested static OOB data from application
<00:04:04>,Received ACK for [ppdu5]
<00:04:04>,Provisioner L-1625619469: provisioning confirmation received!
<00:04:04>,Received ACK for [ppdu6]
<00:04:04>,Provisioner L-1625619469: provisionee's random number received!
<00:04:04>,Provisioner L-1625619469: sending provisioning data
<00:04:04>,Received ACK for [ppdu7]
<00:04:04>,Provisioner L-1625619469: received provisioning complete message!
<00:04:04>,[PROV-HELPER]Provisioning completed received
<00:04:04>,[PROV-HELPER]Adding device address, and device keys
<00:04:04>,[PROV-HELPER]Addr: 0x0101 addr_handle: 2 netkey_handle: 0 devkey_handle: 3
<00:04:04>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:04:04>,[PROV-HELPER]Local provisioning link closed: prov_state: 3  remaining retries: 10
<00:04:04>,[PROVISIONER DRV] Provisioning successful
<00:04:04>,[PROV-HELPER]Provisioning complete. Node addr: 0x0101 elements: 1
<00:04:04>,[NODE SETUP ]Configuring Node: 0x0101...
<00:04:04>,Config client setup: devkey_handle:3 addr_handle:2
<00:04:04>,Getting composition data
<00:04:04>,TX: [aop: 0x8008] 
<00:04:04>,TX: Msg: 00
<00:04:04>,[FLASH DRV]Flash write complete
<00:04:04>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:04:04>,RX: [aop: 0x0002]
<00:04:04>,RX: Msg: 005900000000001F000100000002010000020059000D00
<00:04:04>,[PROVISIONER DRV]Config client event
<00:04:04>,Updating network transmit: count: 2 steps: 1
<00:04:04>,TX: [aop: 0x8024] 
<00:04:04>,TX: Msg: 0A
<00:04:04>,RX: [aop: 0x8025]
<00:04:04>,RX: Msg: 0A
<00:04:04>,[PROVISIONER DRV]Config client event
<00:04:04>,Adding appkey
<00:04:04>,TX: [aop: 0x0000] 
<00:04:04>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:05>,TX: [aop: 0x0000] 
<00:04:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:05>,TX: [aop: 0x0000] 
<00:04:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:05>,[APP]Send data via GSM failed, TO!!!
<00:04:06>,[APP]NETWORK ALERT needed to be sent
<00:04:06>,DIAG->FW=5.0 Building=0
 Master 51334(0xC886),Rx = 0, Err : GSM =1, UWB =0, Mesh =0| IIL =0
<00:04:06>,[APP]->55831(0xDA17)[2],CONN,5.0 Grp : 1, Rx =    0,Err: UWB =     0, Mesh =    0
<00:04:06>,TX: [aop: 0x0000] 
<00:04:06>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:07>,TX: [aop: 0x0000] 
<00:04:07>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:10>,TX: [aop: 0x0000] 
<00:04:10>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:15>,TX: [aop: 0x0000] 
<00:04:15>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:26>,TX: [aop: 0x0000] 
<00:04:26>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:48>,TX: [aop: 0x0000] 
<00:04:48>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:04:51>,RX: [aop: 0x00C1]
<00:04:51>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:04:52>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:04:52>,[SYSCONFIG] Anchor 1 config updated 
<00:04:52>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:04:52>,TX: [aop: 0x00C2] 
<00:04:52>,TX: Msg: 
<00:04:52>,Sending ack to @ 0x0003, status : 0
<00:05:04>,[PROVISIONER DRV]Config client event
<00:05:04>,[NODE]Acknowledged message status not received 
<00:05:04>,[NODE]Retry ...
<00:05:04>,Adding appkey
<00:05:04>,TX: [aop: 0x0000] 
<00:05:04>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:05>,TX: [aop: 0x0000] 
<00:05:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:05>,TX: [aop: 0x0000] 
<00:05:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:06>,TX: [aop: 0x0000] 
<00:05:06>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:07>,TX: [aop: 0x0000] 
<00:05:07>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:10>,TX: [aop: 0x0000] 
<00:05:10>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:15>,TX: [aop: 0x0000] 
<00:05:15>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:26>,TX: [aop: 0x0000] 
<00:05:26>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:48>,TX: [aop: 0x0000] 
<00:05:48>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:05:54>,RX: [aop: 0x00C1]
<00:05:54>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:05:54>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:05:54>,[SYSCONFIG] Anchor 1 config updated 
<00:05:54>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:05:54>,TX: [aop: 0x00C2] 
<00:05:54>,TX: Msg: 
<00:05:54>,Sending ack to @ 0x0003, status : 0
<00:06:04>,[PROVISIONER DRV]Config client event
<00:06:04>,[NODE]Acknowledged message status not received 
<00:06:04>,[NODE]Retry ...
<00:06:04>,Adding appkey
<00:06:04>,TX: [aop: 0x0000] 
<00:06:04>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:05>,TX: [aop: 0x0000] 
<00:06:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:05>,TX: [aop: 0x0000] 
<00:06:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:06>,TX: [aop: 0x0000] 
<00:06:06>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:07>,TX: [aop: 0x0000] 
<00:06:07>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:10>,TX: [aop: 0x0000] 
<00:06:10>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:15>,TX: [aop: 0x0000] 
<00:06:15>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:26>,TX: [aop: 0x0000] 
<00:06:26>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:27>,[APP]Send data via GSM failed, TO!!!
<00:06:28>,[APP]NETWORK ALERT needed to be sent
<00:06:28>,DIAG->FW=5.0 Building=0
 Master 51334(0xC886),Rx = 0, Err : GSM =2, UWB =0, Mesh =0| IIL =0
<00:06:28>,[APP]->55831(0xDA17)[2],CONN,5.0 Grp : 1, Rx =    0,Err: UWB =     0, Mesh =    0
<00:06:48>,TX: [aop: 0x0000] 
<00:06:48>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:06:57>,RX: [aop: 0x00C1]
<00:06:57>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:06:57>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:06:57>,[SYSCONFIG] Anchor 1 config updated 
<00:06:57>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:06:57>,TX: [aop: 0x00C2] 
<00:06:57>,TX: Msg: 
<00:06:57>,Sending ack to @ 0x0003, status : 0
<00:07:04>,[PROVISIONER DRV]Config client event
<00:07:04>,[NODE]Acknowledged message status not received 
<00:07:04>,[NODE]Retry ...
<00:07:04>,Adding appkey
<00:07:04>,TX: [aop: 0x0000] 
<00:07:04>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:05>,TX: [aop: 0x0000] 
<00:07:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:05>,TX: [aop: 0x0000] 
<00:07:05>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:06>,TX: [aop: 0x0000] 
<00:07:06>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:07>,TX: [aop: 0x0000] 
<00:07:07>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:10>,TX: [aop: 0x0000] 
<00:07:10>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:15>,TX: [aop: 0x0000] 
<00:07:15>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:26>,TX: [aop: 0x0000] 
<00:07:26>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:40>,SYS_STATUS_RXPHE: Receiver PHY Header Error
<00:07:48>,TX: [aop: 0x0000] 
<00:07:48>,TX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:07:48>,RX: [aop: 0x8003]
<00:07:48>,RX: Msg: 00000000
<00:07:48>,[PROVISIONER DRV]Config client event
<00:07:48>,opcode status field: 0 
<00:07:48>,App key bind: Custom Message slave on element 0x0101
<00:07:48>,TX: [aop: 0x803D] 
<00:07:48>,TX: Msg: 0101000059000D00
<00:07:48>,RX: [aop: 0x803E]
<00:07:48>,RX: Msg: 000101000059000D00
<00:07:48>,[PROVISIONER DRV]Config client event
<00:07:48>,opcode status field: 0 
<00:07:48>,Setting publication address for the message slave 0x0101 to 0x0003
<00:07:48>,TX: [aop: 0x0003] 
<00:07:48>,TX: Msg: 0101030000000F000159000D00
<00:07:48>,RX: [aop: 0x8019]
<00:07:48>,RX: Msg: 000101030000000F000159000D00
<00:07:48>,[PROVISIONER DRV]Config client event
<00:07:48>,opcode status field: 0 
<00:07:48>,Adding subscription of element 0x0101 to group 0xC002
<00:07:48>,TX: [aop: 0x801B] 
<00:07:48>,TX: Msg: 010102C059000D00
<00:07:49>,RX: [aop: 0x801F]
<00:07:49>,RX: Msg: 00010102C059000D00
<00:07:49>,[PROVISIONER DRV]Config client event
<00:07:49>,opcode status field: 0 
<00:07:49>,Configuration of device 1 successful
<00:07:49>,Setting master message model 0x0004 to publish @ 0x0002
<00:07:49>,[PROV-HELPER]Scanning For Unprovisioned Devices
<00:07:49>,[FLASH DRV]Flash write complete
<00:07:49>,[PROVISIONER DRV]Mesh evt: FLASH_STABLE 
<00:08:00>,RX: [aop: 0x00C1]
<00:08:00>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:08:00>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:08:00>,[SYSCONFIG] Anchor 1 config updated 
<00:08:00>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:08:00>,TX: [aop: 0x00C2] 
<00:08:00>,TX: Msg: 
<00:08:00>,Sending ack to @ 0x0003, status : 0
<00:08:49>,[APP]Send data via GSM failed, TO!!!
<00:08:49>,[APP]NETWORK ALERT needed to be sent
<00:08:49>,DIAG->FW=5.0 Building=0
 Master 51334(0xC886),Rx = 0, Err : GSM =3, UWB =0, Mesh =0| IIL =0
<00:08:49>,[APP]->55831(0xDA17)[2],CONN,5.0 Grp : 1, Rx =    0,Err: UWB =     0, Mesh =    0
<00:08:51>,RX: [aop: 0x00C1]
<00:08:51>,RX: Msg: B00000D523000000000202050004000000000000000001
<00:08:51>,[BLEMNGR] RX->: B00000D523000000000202050004000000000000000001
<00:08:51>,[SYSCONFIG] Anchor 2 config added 
<00:08:51>,[APP]->SURVEILLANCE ANCHOR 54563(2), Group 2 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 1,Reset(4,0) 
<00:08:51>,TX: [aop: 0x00C2] 
<00:08:51>,TX: Msg: 
<00:08:51>,Sending ack to @ 0x0004, status : 0
<00:09:03>,RX: [aop: 0x00C1]
<00:09:03>,RX: Msg: B00000DA17000000000201050000000000000000000000
<00:09:03>,[BLEMNGR] RX->: B00000DA17000000000201050000000000000000000000
<00:09:03>,[SYSCONFIG] Anchor 1 config updated 
<00:09:03>,[APP]->SURVEILLANCE ANCHOR 55831(2), Group 1 at 0x0(+/-0)-> FW 5.0,fail MESH=0,UWB= 0,Reset(0,0) 
<00:09:03>,TX: [aop: 0x00C2] 
<00:09:03>,TX: Msg: 
<00:09:03>,Sending ack to @ 0x0003, status : 0

First node (slave) logs :

<00:00:00>,...I N D O O R   O U T D O O R  A N C H O R  F I R M W A R E (ADM-F-P40-5.0)...
<00:00:00>,...This version was compiled at 12:07:10 Dec 12 2019
<00:00:00>,[SYSCONFIG] Generic reset cause  : 0xE
<00:00:00>,[SYSCONFIG] Specific reset cause : 0
<00:00:00>,[SYSCONFIG] Reset caused by watchdog
<00:00:00>,[SYSCONFIG] Reset caused by soft reset
<00:00:00>,[SYSCONFIG] Reset caused by CPU lock-up
<00:00:00>,[SYSCONFIG] slave Anchor
<00:00:02>,[DWMNGR] Init OK 
<00:00:02>,[DWMNGR] Anchor Address: 0XDA17 (55831)
<00:00:02>,[Mesh DRV] Init Anchor as Slave
<00:00:02>,Initializing and adding models
<00:00:02>,[FLASH DRV]Flash manager added for app data
<00:00:02>,[FLASH DRV]Unable to add flash manager for anchor config
<00:00:02>,[SYSCONFIG] Anchor not configured by BLE
<00:00:02>,[SYSCONFIG] Building ID: 0 => Adveez   0 | Slave Anchor 0XDA17(55831) config Grp 0, Mode 2
<00:00:02>,PB-ADV: context at 0x200206E8 added to bearer
<00:00:02>,Device UUID (raw): 060CCF5DF136794BB9C717C85D397E91
<00:00:02>,Device UUID : 5DCF0C06-36F1-4B79-B9C7-17C85D397E91
<00:00:02>,[FLASH DRV]Flash manager added for anchor config
<00:00:02>,[FLASH DRV]Flash write complete
<00:00:02>,[BLE]-> Fast advertising
<00:00:02>,PB-ADV: Link opened.
<00:00:02>,Received ACK for [ppdu1]
<00:00:03>,Received ACK for [ppdu3]
<00:00:03>,Received ACK for [ppdu5]
<00:00:03>,Got the next segment before an ACK for our own (5, 5)
<00:00:05>,TX RETRY: [ppdu6]
<00:00:05>,Received ACK for [ppdu6]
<00:00:07>,Received start segment while already in RX mode.
<00:00:07>,Received ACK for [ppdu8]
<00:00:07>,[MESH DRV] Anchor was Successfully provisioned : addresse 0X0100
<00:00:07>,[MESH DRV] Group set to 1 
<00:00:07>,PB-ADV: Link closed.
<00:00:07>,[FLASH DRV]Flash write complete
<00:00:08>,RX: [aop: 0x8008]
<00:00:08>,RX: Msg: 00
<00:00:08>,TX: [aop: 0x0002] 
<00:00:08>,TX: Msg: 005900000000001F000100000002010000020059000D00
<00:00:08>,RX: [aop: 0x8024]
<00:00:08>,RX: Msg: 0A
<00:00:08>,TX: [aop: 0x8025] 
<00:00:08>,TX: Msg: 0A
<00:00:08>,[MESH DRV] Config_server Event -> Core network transmission parameters was set
<00:00:08>,RX: [aop: 0x0000]
<00:00:08>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:08>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:08>,TX: [aop: 0x8003] 
<00:00:08>,TX: Msg: 00000000
<00:00:08>,[MESH DRV] Config_server Event -> A new application key was added
<00:00:08>,RX: [aop: 0x803D]
<00:00:08>,RX: Msg: 0001000059000D00
<00:00:08>,Access  Info:
		element_index=0		model_id = D-59		model_handle=2
<00:00:08>,TX: [aop: 0x803E] 
<00:00:08>,TX: Msg: 000001000059000D00
<00:00:08>,[MESH DRV] Config_server Event -> The given model was bound to a new application key
<00:00:09>,RX: [aop: 0x0000]
<00:00:09>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:09>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:09>,TX: [aop: 0x8003] 
<00:00:09>,TX: Msg: 00000000
<00:00:09>,RX: [aop: 0x0003]
<00:00:09>,RX: Msg: 0001020000000F000159000D00
<00:00:09>,TX: [aop: 0x8019] 
<00:00:09>,TX: Msg: 000001020000000F000159000D00
<00:00:09>,[MESH DRV] Config_server Event -> The publication parameters for a given model was set
<00:00:09>,RX: [aop: 0x801B]
<00:00:09>,RX: Msg: 000102C059000D00
<00:00:09>,TX: [aop: 0x801F] 
<00:00:09>,TX: Msg: 00000102C059000D00
<00:00:09>,[MESH DRV] Config_server Event -> A subscription was added to the given model
<00:01:11>,[APP]->SEND: Sending surveillance msg 
<00:01:11>,Message to send : 23 bytes :  ° 
<00:01:11>,[BLEMNGR] TX->: B00000DA1700000000020105000E000000000000000000
<00:01:11>,TX: [aop: 0x00C1] 
<00:01:11>,TX: Msg: B00000DA1700000000020105000E000000000000000000
<00:01:11>,Status send : 0 
<00:01:11>,RX: [aop: 0x00C2]
<00:01:11>,RX: Msg: 
<00:01:12>,[APP]SEND-> ACK received 

Second node (slave) logs :

<00:00:00>,...I N D O O R   O U T D O O R  A N C H O R  F I R M W A R E (ADM-F-P40-5.0)...
<00:00:00>,...This version was compiled at 12:07:10 Dec 12 2019
<00:00:00>,[SYSCONFIG] Generic reset cause  : 0x4
<00:00:00>,[SYSCONFIG] Specific reset cause : 0
<00:00:00>,[SYSCONFIG] Reset caused by soft reset
<00:00:00>,[SYSCONFIG] slave Anchor
<00:00:02>,[DWMNGR] Init OK 
<00:00:02>,[DWMNGR] Anchor Address: 0XD523 (54563)
<00:00:02>,[Mesh DRV] Init Anchor as Slave
<00:00:02>,Initializing and adding models
<00:00:02>,[FLASH DRV]Flash manager added for app data
<00:00:02>,[FLASH DRV]Unable to add flash manager for anchor config
<00:00:02>,[SYSCONFIG] Anchor not configured by BLE
<00:00:02>,[SYSCONFIG] Building ID: 0 => Adveez   0 | Slave Anchor 0XD523(54563) config Grp 0, Mode 2
<00:00:02>,PB-ADV: context at 0x200206E8 added to bearer
<00:00:02>,Device UUID (raw): 54D66EF7D216B34DBBFAA188F8D60ADE
<00:00:02>,Device UUID : F76ED654-16D2-4DB3-BBFA-A188F8D60ADE
<00:00:02>,[FLASH DRV]Flash manager added for anchor config
<00:00:02>,[FLASH DRV]Flash write complete
<00:00:02>,[BLE]-> Fast advertising
<00:00:03>,PB-ADV: Link opened.
<00:00:03>,Received ACK for [ppdu1]
<00:00:03>,Received ACK for [ppdu3]
<00:00:04>,Received ACK for [ppdu5]
<00:00:04>,Received ACK for [ppdu6]
<00:00:04>,Received ACK for [ppdu8]
<00:00:04>,[MESH DRV] Anchor was Successfully provisioned : addresse 0X0101
<00:00:04>,[MESH DRV] Group set to 2 
<00:00:04>,PB-ADV: Link closed.
<00:00:04>,[FLASH DRV]Flash write complete
<00:00:04>,RX: [aop: 0x8008]
<00:00:04>,RX: Msg: 00
<00:00:04>,TX: [aop: 0x0002] 
<00:00:04>,TX: Msg: 005900000000001F000100000002010000020059000D00
<00:00:04>,RX: [aop: 0x8024]
<00:00:04>,RX: Msg: 0A
<00:00:04>,TX: [aop: 0x8025] 
<00:00:04>,TX: Msg: 0A
<00:00:04>,[MESH DRV] Config_server Event -> Core network transmission parameters was set
<00:00:04>,RX: [aop: 0x0000]
<00:00:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:04>,TX: [aop: 0x8003] 
<00:00:04>,TX: Msg: 00000000
<00:00:04>,[MESH DRV] Config_server Event -> A new application key was added
<00:00:04>,RX: [aop: 0x0000]
<00:00:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:04>,TX: [aop: 0x8003] 
<00:00:04>,TX: Msg: 00000000
<00:00:05>,RX: [aop: 0x0000]
<00:00:05>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:05>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:05>,TX: [aop: 0x8003] 
<00:00:05>,TX: Msg: 00000000
<00:00:05>,RX: [aop: 0x0000]
<00:00:05>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:05>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:05>,TX: [aop: 0x8003] 
<00:00:05>,TX: Msg: 00000000
<00:00:07>,RX: [aop: 0x0000]
<00:00:07>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:07>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:07>,TX: [aop: 0x8003] 
<00:00:07>,TX: Msg: 00000000
<00:00:15>,RX: [aop: 0x0000]
<00:00:15>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:15>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:15>,TX: [aop: 0x8003] 
<00:00:15>,TX: Msg: 00000000
<00:00:26>,RX: [aop: 0x0000]
<00:00:26>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:26>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:26>,TX: [aop: 0x8003] 
<00:00:26>,TX: Msg: 00000000
<00:00:48>,RX: [aop: 0x0000]
<00:00:48>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:00:48>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:00:48>,TX: [aop: 0x8003] 
<00:00:48>,TX: Msg: 00000000
<00:01:04>,RX: [aop: 0x0000]
<00:01:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:04>,TX: [aop: 0x8003] 
<00:01:04>,TX: Msg: 00000000
<00:01:04>,RX: [aop: 0x0000]
<00:01:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:04>,TX: [aop: 0x8003] 
<00:01:04>,TX: Msg: 00000000
<00:01:05>,RX: [aop: 0x0000]
<00:01:05>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:05>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:05>,TX: [aop: 0x8003] 
<00:01:05>,TX: Msg: 00000000
<00:01:07>,RX: [aop: 0x0000]
<00:01:07>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:07>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:07>,TX: [aop: 0x8003] 
<00:01:07>,TX: Msg: 00000000
<00:01:09>,RX: [aop: 0x0000]
<00:01:09>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:09>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:09>,TX: [aop: 0x8003] 
<00:01:09>,TX: Msg: 00000000
<00:01:15>,RX: [aop: 0x0000]
<00:01:15>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:15>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:15>,TX: [aop: 0x8003] 
<00:01:15>,TX: Msg: 00000000
<00:01:26>,RX: [aop: 0x0000]
<00:01:26>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:26>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:26>,TX: [aop: 0x8003] 
<00:01:26>,TX: Msg: 00000000
<00:01:48>,RX: [aop: 0x0000]
<00:01:48>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:01:48>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:01:48>,TX: [aop: 0x8003] 
<00:01:48>,TX: Msg: 00000000
<00:02:04>,RX: [aop: 0x0000]
<00:02:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:04>,TX: [aop: 0x8003] 
<00:02:04>,TX: Msg: 00000000
<00:02:04>,RX: [aop: 0x0000]
<00:02:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:04>,TX: [aop: 0x8003] 
<00:02:04>,TX: Msg: 00000000
<00:02:05>,RX: [aop: 0x0000]
<00:02:05>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:05>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:05>,TX: [aop: 0x8003] 
<00:02:05>,TX: Msg: 00000000
<00:02:07>,RX: [aop: 0x0000]
<00:02:07>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:07>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:07>,TX: [aop: 0x8003] 
<00:02:07>,TX: Msg: 00000000
<00:02:10>,RX: [aop: 0x0000]
<00:02:10>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:10>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:10>,TX: [aop: 0x8003] 
<00:02:10>,TX: Msg: 00000000
<00:02:15>,RX: [aop: 0x0000]
<00:02:15>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:15>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:15>,TX: [aop: 0x8003] 
<00:02:15>,TX: Msg: 00000000
<00:02:48>,RX: [aop: 0x0000]
<00:02:48>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:02:48>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:02:48>,TX: [aop: 0x8003] 
<00:02:48>,TX: Msg: 00000000
<00:03:04>,RX: [aop: 0x0000]
<00:03:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:04>,TX: [aop: 0x8003] 
<00:03:04>,TX: Msg: 00000000
<00:03:04>,RX: [aop: 0x0000]
<00:03:04>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:04>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:04>,TX: [aop: 0x8003] 
<00:03:04>,TX: Msg: 00000000
<00:03:05>,RX: [aop: 0x0000]
<00:03:05>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:05>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:05>,TX: [aop: 0x8003] 
<00:03:05>,TX: Msg: 00000000
<00:03:05>,RX: [aop: 0x0000]
<00:03:05>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:05>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:05>,TX: [aop: 0x8003] 
<00:03:05>,TX: Msg: 00000000
<00:03:07>,RX: [aop: 0x0000]
<00:03:07>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:07>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:07>,TX: [aop: 0x8003] 
<00:03:07>,TX: Msg: 00000000
<00:03:10>,RX: [aop: 0x0000]
<00:03:10>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:10>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:10>,TX: [aop: 0x8003] 
<00:03:10>,TX: Msg: 00000000
<00:03:15>,RX: [aop: 0x0000]
<00:03:15>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:15>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:15>,TX: [aop: 0x8003] 
<00:03:15>,TX: Msg: 00000000
<00:03:26>,RX: [aop: 0x0000]
<00:03:26>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:26>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:26>,TX: [aop: 0x8003] 
<00:03:26>,TX: Msg: 00000000
<00:03:48>,RX: [aop: 0x0000]
<00:03:48>,RX: Msg: 000000A2441A35A021544C589889A585055DF2
<00:03:48>,dsm_appkey_add(appkey_handle:0 appkey_index:0)
<00:03:48>,TX: [aop: 0x8003] 
<00:03:48>,TX: Msg: 00000000
<00:03:48>,RX: [aop: 0x803D]
<00:03:48>,RX: Msg: 0101000059000D00
<00:03:48>,Access  Info:
		element_index=0		model_id = D-59		model_handle=2
<00:03:48>,TX: [aop: 0x803E] 
<00:03:48>,TX: Msg: 000101000059000D00
<00:03:48>,[MESH DRV] Config_server Event -> The given model was bound to a new application key
<00:03:48>,RX: [aop: 0x0003]
<00:03:48>,RX: Msg: 0101030000000F000159000D00
<00:03:48>,TX: [aop: 0x8019] 
<00:03:48>,TX: Msg: 000101030000000F000159000D00
<00:03:48>,[MESH DRV] Config_server Event -> The publication parameters for a given model was set
<00:03:48>,RX: [aop: 0x801B]
<00:03:48>,RX: Msg: 010102C059000D00
<00:03:48>,TX: [aop: 0x801F] 
<00:03:48>,TX: Msg: 00010102C059000D00
<00:03:48>,[MESH DRV] Config_server Event -> A subscription was added to the given model
<00:04:03>,SYS_STATUS_RXPHE: Receiver PHY Header Error
<00:04:50>,[APP]->SEND: Sending surveillance msg 
<00:04:50>,Message to send : 23 bytes :  ° 
<00:04:50>,[BLEMNGR] TX->: B00000D523000000000202050004000000000000000001
<00:04:50>,TX: [aop: 0x00C1] 
<00:04:50>,TX: Msg: B00000D523000000000202050004000000000000000001
<00:04:50>,Status send : 0 
<00:01:04>,RX: [aop: 0x00C2]
<00:01:04>,RX: Msg: 
<00:01:04>,[APP]SEND-> ACK received 

Related