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
