During the stability test, the sensors failed to publish status message

Hi,

We setup a mesh network with 26 sensor devices, configured these 26 devices into one group.  From the mesh gateway device, it sends group message to these 26 devices each five seconds. After received the group message, each sensor will publish its status to these group. 

After running this stability test for about 2~3 hours, we found that among these 26 sensors,there are one or two sensors will not able to publish its status with following error found in LOG.

"Unable to publish status message, status: 4"

00> [0:main.c:1411]   ----- Pacom BLE Mesh SRX App -----
00> [384:ble_softdevice_support.c:236]   gap_dev_name : SRD10-f9a91f88a131,len:18
00> [402:nrf_mesh_dfu.c:724]   nrf_mesh_dfu_init
00> [402:nrf_mesh_dfu.c:793]   Version info: BL(ver, id): 0x01 0x01 SD: 0x00CA
00> [402:nrf_mesh_dfu.c:795]   Version info: APP company: 0x00000059 id: 0x0009 version: 2022052608
00> [403:main.c:1268]   Initializing and adding models
00> [403:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x200074F8,size:224
00> [403:mesh_mem_stdlib.c:74]   total_size: 224
00> [403:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x200075E0,size:10
00> [403:mesh_mem_stdlib.c:74]   total_size: 234
00> [403:main.c:1273]   App Sensor Setup Server Model Handle - no errs: 3
00> [404:main.c:1279]   App Light Lightness Model handle: 10, Element index: 0
00> [404:pacom_db.c:59]   nvm set 0 :, [1]
00> [406:hal_rs485.c:244]   tx init: 256
00> [406:serial_uart.c:98]   Serial (RXD:22,TXD:23)init without HWFC!
00> [406:main.c:1427]   company_id:0x59,app_id:0x9,version:2022052608
00> [406:srx_msg.c:113]   init_wdt
00> [406:pacom_db.c:197]   init nvm state:0, 20003EC8
00> [407:pacom_db.c:211]   MAC read:: 000000000000
00> [407:pacom_db.c:212]   ========== OTP NOT initilaized!!! =========
00> [407:pacom_db.c:152]   OTP value:: 00000000000000
00> [407:main.c:1234]   Node Address: 0x001D 
00> [407:mesh_app_utils.c:66]   Device UUID (raw): 23D8E4AAA9AF4C9EB1A1881FA9395E69
00> [407:mesh_app_utils.c:67]   Device UUID : 23D8E4AA-A9AF-4C9E-B1A1-881FA9395E69
00> [408:nrf_mesh_dfu.c:590]     RADIO TX! SLOT 0, count 25, interval: periodic, handle: FFFE
00> [408:nrf_mesh_dfu.c:682]     UNSUPPORTED EVENT
00> [408:srx_sensor.c:203]   mesh_sensor_init
00> [411:main.c:1145]   app_light_lightness_binding_setup
00> [459:pacom_db.c:66]   nvm get 2
00> [459:main.c:1472]   olp:360,clp:7200,threshold:500,1000,dimming,onoff:0,delay:1,background_level:4,prolong_time:60,standby:0,perfer:10,comfort:150,50,curve:20
00> [459:main.c:1490]   query type:0,keynum:0x0
00> [459:main.c:1494]   pir:1,lightness:1,wireless_switch:1
00> [459:main.c:1511]   query trigger_onoff_status:pir:1,lightness:1,wireless_switch:1
00> [459:main.c:1520]   query sensor_type:1
00> 19.61% 
00> 
00> 
(Connection lost)
00> [0:main.c:1411]   ----- Pacom BLE Mesh SRX App -----
00> [398:ble_softdevice_support.c:236]   gap_dev_name : SRD10-f5c90473153c,len:18
00> [417:nrf_mesh_dfu.c:724]   nrf_mesh_dfu_init
00> [417:nrf_mesh_dfu.c:793]   Version info: BL(ver, id): 0x01 0x01 SD: 0x00CA
00> [417:nrf_mesh_dfu.c:795]   Version info: APP company: 0x00000059 id: 0x0009 version: 2022052608
00> [417:main.c:1268]   Initializing and adding models
00> [417:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x200074F8,size:224
00> [418:mesh_mem_stdlib.c:74]   total_size: 224
00> [418:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x200075E0,size:10
00> [418:mesh_mem_stdlib.c:74]   total_size: 234
00> [418:main.c:1273]   App Sensor Setup Server Model Handle - no errs: 3
00> [418:main.c:1279]   App Light Lightness Model handle: 10, Element index: 0
00> [418:pacom_db.c:59]   nvm set 0 :, [1]
00> [421:hal_rs485.c:244]   tx init: 256
00> [421:serial_uart.c:98]   Serial (RXD:22,TXD:23)init without HWFC!
00> [421:main.c:1427]   company_id:0x59,app_id:0x9,version:2022052608
00> [421:srx_msg.c:113]   init_wdt
00> [421:pacom_db.c:197]   init nvm state:2, 20003EC8
00> [421:pacom_db.c:202]   MAC read:: 303ABA0AF8D2
00> [421:pacom_db.c:209]   Comp TEMP=0.0 ,HUMIDITY=0.0 ,PRESURE=0.0 ,
00> [422:pacom_db.c:152]   OTP value:: 0100303ABA0AF8
00> [422:main.c:1382]   Set OTP MAC address [SRD10-F03ABA0AF8D2]!
00> [422:main.c:1234]   Node Address: 0x0018 
00> [422:mesh_app_utils.c:66]   Device UUID (raw): 53D5927BC09C44B2BC157304C975C2F0
00> [422:mesh_app_utils.c:67]   Device UUID : 53D5927B-C09C-44B2-BC15-7304C975C2F0
00> [423:nrf_mesh_dfu.c:590]     RADIO TX! SLOT 0, count 25, interval: periodic, handle: FFFE
00> [423:nrf_mesh_dfu.c:682]     UNSUPPORTED EVENT
00> [423:srx_sensor.c:203]   mesh_sensor_init
00> [425:main.c:1145]   app_light_lightness_binding_setup
00> [444:access.c:253]   RX: [aop: 0x824D]
00> [444:access.c:276]   RX: Msg: 0000100000
00> [444:main.c:713]   get_lightness_cb,*p_lightness:0
00> [444:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:0
00> [445:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: 0 
00> [445:main.c:705]   [445:app_light_lightness.c:985]   
00> 
00> 
00> 
00> [385685:access.c:253]   RX: [aop: 0x824D]
00> [385685:access.c:276]   RX: Msg: 0A00CE0000
00> [385685:main.c:713]   get_lightness_cb,*p_lightness:10
00> [385685:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:10
00> [385686:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 0 
00> [385686:main.c:681]   set_lightness_cb->olp.timer start
00> [385686:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [385686:app_light_lightness.c:985]   Element 0: transition completed, present-L: 10
00> [385686:main.c:720]   Transition time: 0, Target lightness: 10
00> [385686:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [385686:mesh_mem_stdlib.c:74]   total_size: 238
00> [385686:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [385686:main.c:1152]   msg-receive
00> [385687:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [385687:main.c:1154]   RX: Msg: 824D0A00CE0000
00> [385687:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:254
00> [386090:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [386090:srx_msg.c:54]   set_lightness is 254 OK!
00> [390842:access.c:253]   RX: [aop: 0x824D]
00> [390842:access.c:276]   RX: Msg: 0000D00000
00> [390842:main.c:713]   get_lightness_cb,*p_lightness:10
00> [390842:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [390842:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [390843:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [390843:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [390843:app_light_lightness.c:985]   Element 0: transition completed, present-L: 0
00> [390843:main.c:720]   Transition time: 0, Target lightness: 0
00> [390843:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [390843:mesh_mem_stdlib.c:74]   total_size: 238
00> [390843:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [390843:main.c:1152]   msg-receive
00> [390843:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [390844:main.c:1154]   RX: Msg: 824D0000D00000
00> [390844:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [390975:srx_msg.c:54]   set_lightness is 0 OK!
00> [392519:main.c:939]   energy: : 000000000000
00> [392931:drv_dali.c:496]   DTR : 00-00
00> [395850:access.c:253]   RX: [aop: 0x824D]
00> [395850:access.c:276]   RX: Msg: 0A00D20000
00> [395851:main.c:713]   get_lightness_cb,*p_lightness:0
00> [395851:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [395851:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [395851:main.c:681]   set_lightness_cb->olp.timer start
00> [395851:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [395851:app_light_lightness.c:985]   Element 0: transition completed, present-L: 10
00> [395851:main.c:720]   Transition time: 0, Target lightness: 10
00> [395851:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [395851:mesh_mem_stdlib.c:74]   total_size: 238
00> [395852:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [395852:main.c:1152]   msg-receive
00> [395852:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [395852:main.c:1154]   RX: Msg: 824D0A00D20000
00> [395852:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [396256:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [396256:srx_msg.c:54]   set_lightness is 254 OK!
00> [400843:main.c:870]   srx_clp_timeout_handle
00> [400843:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:0,pirL1
00> [400843:main.c:889]   return auto
00> [400843:main.c:713]   get_lightness_cb,*p_lightness:10
00> [400843:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [400843:mesh_mem_stdlib.c:74]   total_size: 238
00> [400843:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [400843:main.c:897]   Unable to publish status message, status: 4
00> [400844:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [401100:access.c:253]   RX: [aop: 0x824D]
00> [401100:access.c:276]   RX: Msg: 0000D40000
00> [401100:main.c:713]   get_lightness_cb,*p_lightness:10
00> [401100:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [401100:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [401101:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [401101:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [401101:app_light_lightness.c:985]   Element 0: transition completed, present-L: 0
00> [401101:main.c:720]   Transition time: 0, Target lightness: 0
00> [401101:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [401101:mesh_mem_stdlib.c:74]   total_size: 238
00> [401101:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [401101:main.c:1152]   msg-receive
00> [401101:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [401101:main.c:1154]   RX: Msg: 824D0000D40000
00> [401249:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [401249:srx_msg.c:54]   set_lightness is 254 OK!
00> [401249:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [401389:srx_msg.c:54]   set_lightness is 0 OK!
00> [406981:access.c:253]   RX: [aop: 0x824D]
00> [406981:access.c:276]   RX: Msg: 0A00D60000
00> [406981:main.c:713]   get_lightness_cb,*p_lightness:0
00> [406981:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [406982:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [406982:main.c:681]   set_lightness_cb->olp.timer start
00> [406982:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [406982:app_light_lightness.c:985]   Element 0: transition completed, present-L: 10
00> [406982:main.c:720]   Transition time: 0, Target lightness: 10
00> [406982:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [406982:mesh_mem_stdlib.c:74]   total_size: 238
00> [406982:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [406982:main.c:1152]   msg-receive
00> [406983:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [406983:main.c:1154]   RX: Msg: 824D0A00D60000
00> [406983:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [407389:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [407389:srx_msg.c:54]   set_lightness is 254 OK!
(Connection lost)
00> [411101:main.c:870]   srx_clp_timeout_handle
00> [411101:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:0,pirL1
00> [411101:main.c:889]   return auto
00> [411101:main.c:713]   get_lightness_cb,*p_lightness:10
00> [411101:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [411101:mesh_mem_stdlib.c:74]   total_size: 238
00> [411101:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [411101:main.c:897]   Unable to publish status message, status: 4
00> [411101:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [411547:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [411547:srx_msg.c:54]   set_lightness is 254 OK!
00> [414606:access.c:253]   RX: [aop: 0x824D]
00> [414606:access.c:276]   RX: Msg: 0000D80000
00> [414606:main.c:713]   get_lightness_cb,*p_lightness:10
00> [414606:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [414606:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [414606:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [414606:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [414606:app_light_lightness.c:985]   Element 0: transition completed, present-L: 0
00> [414607:main.c:720]   Transition time: 0, Target lightness: 0
00> [414607:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [414607:mesh_mem_stdlib.c:74]   total_size: 238
00> [414607:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [414607:main.c:1152]   msg-receive
00> [414607:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [414607:main.c:1154]   RX: Msg: 824D0000D80000
00> [414607:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [414737:srx_msg.c:54]   set_lightness is 0 OK!
00> [418748:access.c:253]   RX: [aop: 0x824D]
00> [418748:access.c:276]   RX: Msg: 0A00DA0000
00> [418748:main.c:713]   get_lightness_cb,*p_lightness:0
00> [418748:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [418748:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [418748:main.c:681]   [418749:main.c:705]   [454364:access.c:253]   RX: [aop: 0x824D]
00> [454364:access.c:276]   RX: Msg: 0000E80000
00> [454364:main.c:713]   get_lightness_cb,*p_lightness:10
00> [454364:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [454365:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [454365:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [454365:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [454365:app_light_lightness.c:985]   Element 0: transition completed, present-L: 0
00> [454365:main.c:720]   Transition time: 0, Target lightness: 0
00> [454365:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [454365:mesh_mem_stdlib.c:74]   total_size: 238
00> [454365:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [454365:main.c:1152]   msg-receive
00> [454366:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [454366:main.c:1154]   RX: Msg: 824D0000E80000
00> [454366:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [454538:srx_msg.c:54]   set_lightness is 0 OK!
00> [455172:main.c:870]   srx_clp_timeout_handle
00> [455173:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL1
00> [455173:main.c:885]   app_timer_start->clp.timer
00> [459435:access.c:253]   RX: [aop: 0x824D]
00> [459435:access.c:276]   RX: Msg: 0A00EA0000
00> [459435:main.c:713]   get_lightness_cb,*p_lightness:0
00> [459435:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [459436:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [459436:main.c:681]   set_lightness_cb->olp.timer start
00> [459436:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [459436:app_light_lightness.c:985]   Element 0: transition completed, present-L: 10
00> [459436:main.c:720]   Transition time: 0, Target lightness: 10
00> [459436:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [459436:mesh_mem_stdlib.c:74]   total_size: 238
00> [459436:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [459436:main.c:1152]   msg-receive
00> [459436:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [459437:main.c:1154]   RX: Msg: 824D0A00EA0000
00> [459437:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [459882:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [459882:srx_msg.c:54]   set_lightness is 254 OK!
00> [464553:access.c:253]   RX: [aop: 0x824D]
00> [464553:access.c:276]   RX: Msg: 0000EC0000
00> [464553:main.c:713]   get_lightness_cb,*p_lightness:10
00> [464553:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [464553:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [464553:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [464554:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [464554:app_light_lightness.c:985]   Element 0: transition completed, present-L: 0
00> [464554:main.c:720]   Transition time: 0, Target lightness: 0
00> [464554:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [464554:mesh_mem_stdlib.c:74]   total_size: 238
00> [464554:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [464554:main.c:1152]   msg-receive
00> [464554:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [464554:main.c:1154]   RX: Msg: 824D0000EC0000
00> [464554:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [464684:srx_msg.c:54]   set_lightness is 0 OK!
00> [465173:main.c:870]   srx_clp_timeout_handle
00> [465173:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL1
00> [465173:main.c:885]   app_timer_start->clp.timer
00> [469971:access.c:253]   RX: [aop: 0x824D]
00> [469971:access.c:276]   RX: Msg: 0A00EE0000
00> [469971:main.c:713]   get_lightness_cb,*p_lightness:0
00> [469971:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [469971:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [469971:main.c:681]   set_lightness_cb->olp.timer start
00> [469971:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [469972:app_light_lightness.c:985]   Element 0: transition completed, present-L: 10
00> [469972:main.c:720]   Transition time: 0, Target lightness: 10
00> [469972:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007860,size:4
00> [469972:mesh_mem_stdlib.c:74]   total_size: 238
00> [469972:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007860
00> [469972:main.c:1152]   msg-receive
00> [469972:main.c:1153]   src:0x2B,dst:0xC000,length:7
00> [469972:main.c:1154]   RX: Msg: 824D0A00EE0000
00> [469972:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [470376:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [470376:srx_msg.c:54]   set_lightness is 254 OK!

Parents Reply Children
No Data
Related