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

[Wanderzhd]

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"

Fullscreen 5584.log.txt Download

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!

[Mttrinh]

Hi,

What SDK are you running and what version? Are you running a unmodified example?

From the mesh gateway device

What is this gateway? Can you elaborate?

After received the group message, each sensor will publish its status to these group. 

What you mean by these group? 

Are you doing provisioning using our nRF Mesh app?

Also, is it the same devices that keep running into this issue or is it random? Are you testing on a DK?

[Wanderzhd]

Hi Mttrinh,

In our system, we have two type of hardware: the sensors and the gateways. All the hardware using nRF52840 with the V5.0 Mesh SDK.  The sensor device is running modified genericONOFF model.

In the test, we provisioned 25 sensors and 1 gateway in one mesh network, and the 25 sensors are configured into a group  named A. Then the gateway will repeat such a pattern: wait 5 seconds and send a group genericON message to A group，5 seconds later it will send a group genericOFF message to A group. 

Once the sensor devices get the group message, it will publish a ON/OFF message to the gateway.

The issue is random, during our test it needs about 6 hours, about 2 of the 25 will happen.

[Wanderzhd]

HI Mttrinh,

After further debugging, we found that NRF_ERROR_NO_MEM is generated from packet_tx(), once it happens, the sensor device will never be able to publish any message out. It will last for hours after the system is reboot by a software watchdog reset.

The sensor will have two kinds of publish message: 1、light onoff   2、PIR for motion detection.

 

[Wanderzhd]

Fullscreen no_publish_only.txt Download

00> [0:main.c:1411]   ----- Pacom BLE Mesh SRX App -----
00> [381:ble_softdevice_support.c:236]   gap_dev_name : SRD10-d17249a55155,len:18
00> [397:nrf_mesh_dfu.c:724]   nrf_mesh_dfu_init
00> [397:nrf_mesh_dfu.c:793]   Version info: BL(ver, id): 0x01 0x01 SD: 0x00CA
00> [397:nrf_mesh_dfu.c:795]   Version info: APP company: 0x00000059 id: 0x0009 version: 2022052619
00> [398:main.c:1268]   Initializing and adding models
00> [398:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x200074F8,size:224
00> [398:mesh_mem_stdlib.c:74]   total_size: 224
00> [398:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x200075E0,size:10
00> [398:mesh_mem_stdlib.c:74]   total_size: 234
00> [398:main.c:1273]   App Sensor Setup Server Model Handle - no errs: 3
00> [399:main.c:1279]   App Light Lightness Model handle: 10, Element index: 0
00> [399:pacom_db.c:59]   nvm set 0 :, [1]
00> [401:hal_rs485.c:244]   tx init: 256
00> [401:serial_uart.c:98]   Serial (RXD:22,TXD:23)init without HWFC!
00> [401:main.c:1427]   company_id:0x59,app_id:0x9,version:2022052619
00> [402:srx_msg.c:113]   init_wdt
00> [402:pacom_db.c:197]   init nvm state:2, 20003EC8
00> [402:pacom_db.c:202]   MAC read:: 303ABA0AF826
00> [402:pacom_db.c:209]   Comp TEMP=0.0 ,HUMIDITY=0.0 ,PRESURE=0.0 ,
00> [402:pacom_db.c:152]   OTP value:: 0100303ABA0AF8
00> [402:main.c:1382]   Set OTP MAC address [SRD10-F03ABA0AF826]!
00> [402:main.c:1234]   Node Address: 0x0005 
00> [402:mesh_app_utils.c:66]   Device UUID (raw): 91F15F4FDE8846BF9551A54972D18C85
00> [403:mesh_app_utils.c:67]   Device UUID : 91F15F4F-DE88-46BF-9551-A54972D18C85
00> [403:nrf_mesh_dfu.c:590]     RADIO TX! SLOT 0, count 25, interval: periodic, handle: FFFE
00> [403:nrf_mesh_dfu.c:682]     UNSUPPORTED EVENT
00> [404:srx_sensor.c:203]   mesh_sensor_init
00> [406:main.c:1145]   app_light_lightness_binding_setup
00> [454:pacom_db.c:66]   nvm get 2
00> [454: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> [454:main.c:1490]   query type:0,keynum:0x0
00> [454:main.c:1494]   pir:1,lightness:1,wireless_switch:1
00> [454:main.c:1511]   [454:main.c:1520]   query sensor_type:1
00> packet_tx
00> 
00> 
00> 
00> [377496:main.c:870]   srx_clp_timeout_handle
00> [377496:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL1
00> [377496:main.c:885]   app_timer_start->clp.timer
00> [379298:main.c:950]   pir_input_cb,pir:0,type:1,available:1
00> [379298:main.c:413]   app_sensor_get_cb,property_id:0x60
00> [379298:srx_sensor.c:107]   29.61 deg C 
00> [379298:srx_sensor.c:108]   997.94 hPa
00> [379298:srx_sensor.c:109]   27.65% 
00> [379298:srx_sensor.c:110]   23.31 lux
00> [379298:srx_sensor.c:111]   PIR: 0
00> [379298:srx_sensor.c:112]   
00> [379298:main.c:472]   app_sensor_get_cb,PIR:0,LIGHT:23
00> [379298:main.c:330]   srx_light_strategy,light:23,pir:0,op_mode:0,state:5
00> [379299:app_sensor_utils.c:298]   Formatted trigger: current: 1, previous: 1, delta up: 0, delta down: 0, diff: 0
00> [379299:access.c:1200]   access_model_publish
00> [379299:access.c:445]   packet_alloc_and_tx
00> [379299:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20009660,size:11
00> [379299:mesh_mem_stdlib.c:74]   total_size: 245
00> [379299:access.c:339]   packet_tx
00> [379299:access.c:429]   packet_tx,status:4
00> [379299:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20009660
00> [379299:app_sensor_utils.c:488]   ERR: publication failed (0x0004 (4)) = (status)
00> [381546:access.c:253]   RX: [aop: 0x8202]
00> [381547:access.c:276]   RX: Msg: 019A0000
00> [381547:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [381547:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [381547:main.c:713]   get_lightness_cb,*p_lightness:0
00> [381547:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [381547:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [381547:main.c:681]   set_lightness_cb->olp.timer start
00> [381547:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [381547:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [381547:main.c:720]   Transition time: 0, Target lightness: 10
00> [381548:app_light_lightness.c:773]   publish_lightness
00> [381548:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [381548:access.c:1200]   access_model_publish
00> [381548:access.c:445]   packet_alloc_and_tx
00> [381548:access.c:1200]   access_model_publish
00> [381548:access.c:445]   packet_alloc_and_tx
00> [381548:light_lightness_setup_server.c:80]   status_actual_send
00> [381548:access.c:1200]   access_model_publish
00> [381548:access.c:445]   packet_alloc_and_tx
00> [381548:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [381548:mesh_mem_stdlib.c:74]   total_size: 238
00> [381548:access.c:339]   packet_tx
00> [381549:access.c:429]   packet_tx,status:4
00> [381549:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [381549:app_light_lightness.c:789]   publish_lightness, status: 4
00> [381549:access.c:445]   packet_alloc_and_tx
00> [381549:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [381549:mesh_mem_stdlib.c:74]   total_size: 237
00> [381549:access.c:339]   packet_tx
00> [381549:access.c:429]   packet_tx,status:4
00> [381549:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [381549:main.c:1152]   msg-receive
00> [381549:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [381549:main.c:1154]   RX: Msg: 8202019A0000
00> [381550:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [381959:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [381960:srx_msg.c:54]   set_lightness is 254 OK!
00> [385252:access.c:253]   RX: [aop: 0x8202]
00> [385253:access.c:276]   RX: Msg: 009B0000
00> [385253:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [385253:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [385253:main.c:713]   get_lightness_cb,*p_lightness:10
00> [385253:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [385253:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [385253:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [385253:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [385253:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [385253:main.c:720]   Transition time: 0, Target lightness: 0
00> [385254:app_light_lightness.c:773]   publish_lightness
00> [385254:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [385254:access.c:1200]   access_model_publish
00> [385254:access.c:445]   packet_alloc_and_tx
00> [385254:access.c:1200]   access_model_publish
00> [385254:access.c:445]   packet_alloc_and_tx
00> [385254:light_lightness_setup_server.c:80]   status_actual_send
00> [385254:access.c:1200]   access_model_publish
00> [385254:access.c:445]   packet_alloc_and_tx
00> [385254:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [385254:mesh_mem_stdlib.c:74]   total_size: 238
00> [385254:access.c:339]   packet_tx
00> [385255:access.c:429]   packet_tx,status:4
00> [385255:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [385255:app_light_lightness.c:789]   publish_lightness, status: 4
00> [385255:access.c:445]   packet_alloc_and_tx
00> [385255:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [385255:mesh_mem_stdlib.c:74]   total_size: 237
00> [385255:access.c:339]   packet_tx
00> [385255:access.c:429]   packet_tx,status:4
00> [385255:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [385255:main.c:1152]   msg-receive
00> [385255:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [385256:main.c:1154]   RX: Msg: 8202009B0000
00> [385256:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [385390:srx_msg.c:54]   set_lightness is 0 OK!
00> [387496:main.c:870]   srx_clp_timeout_handle
00> [387496:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [387497:main.c:885]   app_timer_start->clp.timer
00> [391360:access.c:253]   RX: [aop: 0x8202]
00> [391361:access.c:276]   RX: Msg: 019C0000
00> [391361:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [391361:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [391361:main.c:713]   get_lightness_cb,*p_lightness:0
00> [391361:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [391361:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [391361:main.c:681]   set_lightness_cb->olp.timer start
00> [391361:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [391361:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [391361:main.c:720]   Transition time: 0, Target lightness: 10
00> [391362:app_light_lightness.c:773]   publish_lightness
00> [391362:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [391362:access.c:1200]   access_model_publish
00> [391362:access.c:445]   packet_alloc_and_tx
00> [391362:access.c:1200]   access_model_publish
00> [391362:access.c:445]   packet_alloc_and_tx
00> [391362:light_lightness_setup_server.c:80]   status_actual_send
00> [391362:access.c:1200]   access_model_publish
00> [391362:access.c:445]   packet_alloc_and_tx
00> [391362:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [391362:mesh_mem_stdlib.c:74]   total_size: 238
00> [391362:access.c:339]   packet_tx
00> [391363:access.c:429]   packet_tx,status:4
00> [391363:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [391363:app_light_lightness.c:789]   publish_lightness, status: 4
00> [391363:access.c:445]   packet_alloc_and_tx
00> [391363:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [391363:mesh_mem_stdlib.c:74]   total_size: 237
00> [391363:access.c:339]   packet_tx
00> [391363:access.c:429]   packet_tx,status:4
00> [391363:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [391363:main.c:1152]   msg-receive
00> [391363:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [391364:main.c:1154]   RX: Msg: 8202019C0000
00> [391364:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [391810:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [391810:srx_msg.c:54]   set_lightness is 254 OK!
00> [395496:access.c:253]   RX: [aop: 0x8202]
00> [395496:access.c:276]   RX: Msg: 009D0000
00> [395496:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [395496:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [395497:main.c:713]   get_lightness_cb,*p_lightness:10
00> [395497:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [395497:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [395497:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [395497:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [395497:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [395497:main.c:720]   Transition time: 0, Target lightness: 0
00> [395497:app_light_lightness.c:773]   publish_lightness
00> [395497:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [395497:access.c:1200]   access_model_publish
00> [395498:access.c:445]   packet_alloc_and_tx
00> [395498:access.c:1200]   access_model_publish
00> [395498:access.c:445]   packet_alloc_and_tx
00> [395498:light_lightness_setup_server.c:80]   status_actual_send
00> [395498:access.c:1200]   access_model_publish
00> [395498:access.c:445]   packet_alloc_and_tx
00> [395498:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [395498:mesh_mem_stdlib.c:74]   total_size: 238
00> [395498:access.c:339]   packet_tx
00> [395498:access.c:429]   packet_tx,status:4
00> [395498:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [395498:app_light_lightness.c:789]   publish_lightness, status: 4
00> [395499:access.c:445]   packet_alloc_and_tx
00> [395499:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [395499:mesh_mem_stdlib.c:74]   total_size: 237
00> [395499:access.c:339]   packet_tx
00> [395499:access.c:429]   packet_tx,status:4
00> [395499:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [395499:main.c:1152]   msg-receive
00> [395499:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [395499:main.c:1154]   RX: Msg: 8202009D0000
00> [395500:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [395633:srx_msg.c:54]   set_lightness is 0 OK!
00> [397497:main.c:870]   srx_clp_timeout_handle
00> [397497:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [397497:main.c:885]   app_timer_start->clp.timer
00> [403072:access.c:253]   RX: [aop: 0x8202]
00> [403072:access.c:276]   RX: Msg: 019E0000
00> [403072:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [403072:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [403072:main.c:713]   get_lightness_cb,*p_lightness:0
00> [403072:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [403072:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [403073:main.c:681]   set_lightness_cb->olp.timer start
00> [403073:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [403073:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [403073:main.c:720]   Transition time: 0, Target lightness: 10
00> [403073:app_light_lightness.c:773]   publish_lightness
00> [403073:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [403073:access.c:1200]   access_model_publish
00> [403073:access.c:445]   packet_alloc_and_tx
00> [403073:access.c:1200]   access_model_publish
00> [403073:access.c:445]   packet_alloc_and_tx
00> [403074:light_lightness_setup_server.c:80]   status_actual_send
00> [403074:access.c:1200]   access_model_publish
00> [403074:access.c:445]   packet_alloc_and_tx
00> [403074:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [403074:mesh_mem_stdlib.c:74]   total_size: 238
00> [403074:access.c:339]   packet_tx
00> [403074:access.c:429]   packet_tx,status:4
00> [403074:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [403074:app_light_lightness.c:789]   publish_lightness, status: 4
00> [403074:access.c:445]   packet_alloc_and_tx
00> [403074:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [403074:mesh_mem_stdlib.c:74]   total_size: 237
00> [403075:access.c:339]   packet_tx
00> [403075:access.c:429]   packet_tx,status:4
00> [403075:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [403075:main.c:1152]   msg-receive
00> [403075:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [403075:main.c:1154]   RX: Msg: 8202019E0000
00> [403075:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [403535:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [403535:srx_msg.c:54]   set_lightness is 254 OK!
00> [407185:access.c:253]   RX: [aop: 0x8202]
00> [407186:access.c:276]   RX: Msg: 009F0000
00> [407186:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [407186:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [407186:main.c:713]   get_lightness_cb,*p_lightness:10
00> [407186:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [407186:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [407186:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [407186:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [407186:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [407186:main.c:720]   Transition time: 0, Target lightness: 0
00> [407187:app_light_lightness.c:773]   publish_lightness
00> [407187:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [407187:access.c:1200]   access_model_publish
00> [407187:access.c:445]   packet_alloc_and_tx
00> [407187:access.c:1200]   access_model_publish
00> [407187:access.c:445]   packet_alloc_and_tx
00> [407187:light_lightness_setup_server.c:80]   status_actual_send
00> [407187:access.c:1200]   access_model_publish
00> [407187:access.c:445]   packet_alloc_and_tx
00> [407187:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [407187:mesh_mem_stdlib.c:74]   total_size: 238
00> [407187:access.c:339]   packet_tx
00> [407188:access.c:429]   packet_tx,status:4
00> [407188:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [407188:app_light_lightness.c:789]   publish_lightness, status: 4
00> [407188:access.c:445]   packet_alloc_and_tx
00> [407188:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [407188:mesh_mem_stdlib.c:74]   total_size: 237
00> [407188:access.c:339]   packet_tx
00> [407188:access.c:429]   packet_tx,status:4
00> [407188:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [407188:main.c:1152]   msg-receive
00> [407188:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [407188:main.c:1154]   RX: Msg: 8202009F0000
00> [407189:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [407324:srx_msg.c:54]   set_lightness is 0 OK!
00> [407497:main.c:870]   srx_clp_timeout_handle
00> [407497:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [407497:main.c:885]   app_timer_start->clp.timer
00> [412496:access.c:253]   RX: [aop: 0x8202]
00> [412496:access.c:276]   RX: Msg: 01A00000
00> [412496:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [412496:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [412496:main.c:713]   get_lightness_cb,*p_lightness:0
00> [412496:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [412497:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [412497:main.c:681]   set_lightness_cb->olp.timer start
00> [412497:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [412497:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [412497:main.c:720]   Transition time: 0, Target lightness: 10
00> [412497:app_light_lightness.c:773]   publish_lightness
00> [412497:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [412497:access.c:1200]   access_model_publish
00> [412497:access.c:445]   packet_alloc_and_tx
00> [412497:access.c:1200]   access_model_publish
00> [412497:access.c:445]   packet_alloc_and_tx
00> [412498:light_lightness_setup_server.c:80]   status_actual_send
00> [412498:access.c:1200]   access_model_publish
00> [412498:access.c:445]   packet_alloc_and_tx
00> [412498:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [412498:mesh_mem_stdlib.c:74]   total_size: 238
00> [412498:access.c:339]   packet_tx
00> [412498:access.c:429]   packet_tx,status:4
00> [412498:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [412498:app_light_lightness.c:789]   publish_lightness, status: 4
00> [412498:access.c:445]   packet_alloc_and_tx
00> [412498:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [412499:mesh_mem_stdlib.c:74]   total_size: 237
00> [412499:access.c:339]   packet_tx
00> [412499:access.c:429]   packet_tx,status:4
00> [412499:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [412499:main.c:1152]   msg-receive
00> [412499:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [412499:main.c:1154]   RX: Msg: 820201A00000
00> [412499:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [412908:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [412908:srx_msg.c:54]   set_lightness is 254 OK!
00> [416394:access.c:253]   RX: [aop: 0x8202]
00> [416394:access.c:276]   RX: Msg: 00A10000
00> [416394:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [416394:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [416394:main.c:713]   get_lightness_cb,*p_lightness:10
00> [416394:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [416394:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [416394:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [416394:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [416395:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [416395:main.c:720]   Transition time: 0, Target lightness: 0
00> [416395:app_light_lightness.c:773]   publish_lightness
00> [416395:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [416395:access.c:1200]   access_model_publish
00> [416395:access.c:445]   packet_alloc_and_tx
00> [416395:access.c:1200]   access_model_publish
00> [416395:access.c:445]   packet_alloc_and_tx
00> [416395:light_lightness_setup_server.c:80]   status_actual_send
00> [416395:access.c:1200]   access_model_publish
00> [416395:access.c:445]   packet_alloc_and_tx
00> [416395:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [416396:mesh_mem_stdlib.c:74]   total_size: 238
00> [416396:access.c:339]   packet_tx
00> [416396:access.c:429]   packet_tx,status:4
00> [416396:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [416396:app_light_lightness.c:789]   publish_lightness, status: 4
00> [416396:access.c:445]   packet_alloc_and_tx
00> [416396:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [416396:mesh_mem_stdlib.c:74]   total_size: 237
00> [416396:access.c:339]   packet_tx
00> [416396:access.c:429]   packet_tx,status:4
00> [416396:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [416397:main.c:1152]   msg-receive
00> [416397:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [416397:main.c:1154]   RX: Msg: 820200A10000
00> [416397:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [416573:srx_msg.c:54]   set_lightness is 0 OK!
00> [417497:main.c:870]   srx_clp_timeout_handle
00> [417497:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [417497:main.c:885]   app_timer_start->clp.timer
00> [421295:access.c:253]   RX: [aop: 0x8202]
00> [421295:access.c:276]   RX: Msg: 01A20000
00> [421295:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [421295:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [421295:main.c:713]   get_lightness_cb,*p_lightness:0
00> [421295:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [421295:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [421296:main.c:681]   set_lightness_cb->olp.timer start
00> [421296:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [421296:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [421296:main.c:720]   Transition time: 0, Target lightness: 10
00> [421296:app_light_lightness.c:773]   publish_lightness
00> [421296:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [421296:access.c:1200]   access_model_publish
00> [421296:access.c:445]   packet_alloc_and_tx
00> [421296:access.c:1200]   access_model_publish
00> [421296:access.c:445]   packet_alloc_and_tx
00> [421296:light_lightness_setup_server.c:80]   status_actual_send
00> [421297:access.c:1200]   access_model_publish
00> [421297:access.c:445]   packet_alloc_and_tx
00> [421297:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [421297:mesh_mem_stdlib.c:74]   total_size: 238
00> [421297:access.c:339]   packet_tx
00> [421297:access.c:429]   packet_tx,status:4
00> [421297:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [421297:app_light_lightness.c:789]   publish_lightness, status: 4
00> [421297:access.c:445]   packet_alloc_and_tx
00> [421297:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [421297:mesh_mem_stdlib.c:74]   total_size: 237
00> [421298:access.c:339]   packet_tx
00> [421298:access.c:429]   packet_tx,status:4
00> [421298:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [421298:main.c:1152]   msg-receive
00> [421298:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [421298:main.c:1154]   RX: Msg: 820201A20000
00> [421298:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [421750:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [421750:srx_msg.c:54]   set_lightness is 254 OK!
00> [425405:access.c:253]   RX: [aop: 0x8202]
00> [425405:access.c:276]   RX: Msg: 00A30000
00> [425405:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [425405:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [425405:main.c:713]   get_lightness_cb,*p_lightness:10
00> [425405:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [425406:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [425406:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [425406:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [425406:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [425406:main.c:720]   Transition time: 0, Target lightness: 0
00> [425406:app_light_lightness.c:773]   publish_lightness
00> [425406:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [425406:access.c:1200]   access_model_publish
00> [425406:access.c:445]   packet_alloc_and_tx
00> [425406:access.c:1200]   access_model_publish
00> [425406:access.c:445]   packet_alloc_and_tx
00> [425407:light_lightness_setup_server.c:80]   status_actual_send
00> [425407:access.c:1200]   access_model_publish
00> [425407:access.c:445]   packet_alloc_and_tx
00> [425407:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [425407:mesh_mem_stdlib.c:74]   total_size: 238
00> [425407:access.c:339]   packet_tx
00> [425407:access.c:429]   packet_tx,status:4
00> [425407:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [425407:app_light_lightness.c:789]   publish_lightness, status: 4
00> [425407:access.c:445]   packet_alloc_and_tx
00> [425407:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [425407:mesh_mem_stdlib.c:74]   total_size: 237
00> [425408:access.c:339]   packet_tx
00> [425408:access.c:429]   packet_tx,status:4
00> [425408:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [425408:main.c:1152]   msg-receive
00> [425408:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [425408:main.c:1154]   RX: Msg: 820200A30000
00> [425408:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [425584:srx_msg.c:54]   set_lightness is 0 OK!
00> [427497:main.c:870]   srx_clp_timeout_handle
00> [427497:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [427498:main.c:885]   app_timer_start->clp.timer
00> [431313:access.c:253]   RX: [aop: 0x8202]
00> [431313:access.c:276]   RX: Msg: 01A40000
00> [431313:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [431313:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [431313:main.c:713]   get_lightness_cb,*p_lightness:0
00> [431313:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [431313:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [431313:main.c:681]   set_lightness_cb->olp.timer start
00> [431314:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [431314:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [431314:main.c:720]   Transition time: 0, Target lightness: 10
00> [431314:app_light_lightness.c:773]   publish_lightness
00> [431314:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [431314:access.c:1200]   access_model_publish
00> [431314:access.c:445]   packet_alloc_and_tx
00> [431314:access.c:1200]   access_model_publish
00> [431314:access.c:445]   packet_alloc_and_tx
00> [431314:light_lightness_setup_server.c:80]   status_actual_send
00> [431314:access.c:1200]   access_model_publish
00> [431315:access.c:445]   packet_alloc_and_tx
00> [431315:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [431315:mesh_mem_stdlib.c:74]   total_size: 238
00> [431315:access.c:339]   packet_tx
00> [431315:access.c:429]   packet_tx,status:4
00> [431315:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [431315:app_light_lightness.c:789]   publish_lightness, status: 4
00> [431315:access.c:445]   packet_alloc_and_tx
00> [431315:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [431315:mesh_mem_stdlib.c:74]   total_size: 237
00> [431315:access.c:339]   packet_tx
00> [431315:access.c:429]   packet_tx,status:4
00> [431316:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [431316:main.c:1152]   msg-receive
00> [431316:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [431316:main.c:1154]   RX: Msg: 820201A40000
00> [431316:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [431765:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [431765:srx_msg.c:54]   set_lightness is 254 OK!
00> [435615:access.c:253]   RX: [aop: 0x8202]
00> [435615:access.c:276]   RX: Msg: 00A50000
00> [435615:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [435615:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [435615:main.c:713]   get_lightness_cb,*p_lightness:10
00> [435615:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [435615:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [435615:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [435616:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [435616:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [435616:main.c:720]   Transition time: 0, Target lightness: 0
00> [435616:app_light_lightness.c:773]   publish_lightness
00> [435616:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [435616:access.c:1200]   access_model_publish
00> [435616:access.c:445]   packet_alloc_and_tx
00> [435616:access.c:1200]   access_model_publish
00> [435616:access.c:445]   packet_alloc_and_tx
00> [435616:light_lightness_setup_server.c:80]   status_actual_send
00> [435616:access.c:1200]   access_model_publish
00> [435616:access.c:445]   packet_alloc_and_tx
00> [435617:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [435617:mesh_mem_stdlib.c:74]   total_size: 238
00> [435617:access.c:339]   packet_tx
00> [435617:access.c:429]   packet_tx,status:4
00> [435617:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [435617:app_light_lightness.c:789]   publish_lightness, status: 4
00> [435617:access.c:445]   packet_alloc_and_tx
00> [435617:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [435617:mesh_mem_stdlib.c:74]   total_size: 237
00> [435617:access.c:339]   packet_tx
00> [435617:access.c:429]   packet_tx,status:4
00> [435617:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [435618:main.c:1152]   msg-receive
00> [435618:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [435618:main.c:1154]   RX: Msg: 820200A50000
00> [435618:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [435760:srx_msg.c:54]   set_lightness is 0 OK!
00> [437498:main.c:870]   srx_clp_timeout_handle
00> [437498:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [437498:main.c:885]   app_timer_start->clp.timer
00> [441525:access.c:253]   RX: [aop: 0x8202]
00> [441525:access.c:276]   RX: Msg: 01A60000
00> [441525:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [441526:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [441526:main.c:713]   get_lightness_cb,*p_lightness:0
00> [441526:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [441526:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [441526:main.c:681]   set_lightness_cb->olp.timer start
00> [441526:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [441526:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [441526:main.c:720]   Transition time: 0, Target lightness: 10
00> [441526:app_light_lightness.c:773]   publish_lightness
00> [441526:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [441527:access.c:1200]   access_model_publish
00> [441527:access.c:445]   packet_alloc_and_tx
00> [441527:access.c:1200]   access_model_publish
00> [441527:access.c:445]   packet_alloc_and_tx
00> [441527:light_lightness_setup_server.c:80]   status_actual_send
00> [441527:access.c:1200]   access_model_publish
00> [441527:access.c:445]   packet_alloc_and_tx
00> [441527:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [441527:mesh_mem_stdlib.c:74]   total_size: 238
00> [441527:access.c:339]   packet_tx
00> [441527:access.c:429]   packet_tx,status:4
00> [441527:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [441528:app_light_lightness.c:789]   publish_lightness, status: 4
00> [441528:access.c:445]   packet_alloc_and_tx
00> [441528:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [441528:mesh_mem_stdlib.c:74]   total_size: 237
00> [441528:access.c:339]   packet_tx
00> [441528:access.c:429]   packet_tx,status:4
00> [441528:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [441528:main.c:1152]   msg-receive
00> [441528:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [441528:main.c:1154]   RX: Msg: 820201A60000
00> [441528:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [441939:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [441939:srx_msg.c:54]   set_lightness is 254 OK!
00> [445624:access.c:253]   RX: [aop: 0x8202]
00> [445624:access.c:276]   RX: Msg: 00A70000
00> [445624:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [445625:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [445625:main.c:713]   get_lightness_cb,*p_lightness:10
00> [445625:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [445625:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [445625:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [445625:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [445625:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [445625:main.c:720]   Transition time: 0, Target lightness: 0
00> [445625:app_light_lightness.c:773]   publish_lightness
00> [445626:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [445626:access.c:1200]   access_model_publish
00> [445626:access.c:445]   packet_alloc_and_tx
00> [445626:access.c:1200]   access_model_publish
00> [445626:access.c:445]   packet_alloc_and_tx
00> [445626:light_lightness_setup_server.c:80]   status_actual_send
00> [445626:access.c:1200]   access_model_publish
00> [445626:access.c:445]   packet_alloc_and_tx
00> [445626:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [445626:mesh_mem_stdlib.c:74]   total_size: 238
00> [445626:access.c:339]   packet_tx
00> [445626:access.c:429]   packet_tx,status:4
00> [445626:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [445627:app_light_lightness.c:789]   publish_lightness, status: 4
00> [445627:access.c:445]   packet_alloc_and_tx
00> [445627:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [445627:mesh_mem_stdlib.c:74]   total_size: 237
00> [445627:access.c:339]   packet_tx
00> [445627:access.c:429]   packet_tx,status:4
00> [445627:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [445627:main.c:1152]   msg-receive
00> [445627:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [445627:main.c:1154]   RX: Msg: 820200A70000
00> [445627:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [445759:srx_msg.c:54]   set_lightness is 0 OK!
00> [447498:main.c:870]   srx_clp_timeout_handle
00> [447498:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [447498:main.c:885]   app_timer_start->clp.timer
00> [451328:access.c:253]   RX: [aop: 0x8202]
00> [451328:access.c:276]   RX: Msg: 01A80000
00> [451328:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [451328:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [451328:main.c:713]   get_lightness_cb,*p_lightness:0
00> [451328:app_light_lightness.c:273]   initialized:1,present_lightness:0,p_in->lightness:10
00> [451329:app_light_lightness.c:285]   SET: target lightness: 10  delay: 0  tt: 0  req-delta: 10 
00> [451329:main.c:681]   set_lightness_cb->olp.timer start
00> [451329:main.c:705]   set_lightness_cb,lightness_level_table[10]:254
00> [451329:app_light_lightness.c:991]   Element 0: transition completed, present-L: 10
00> [451329:main.c:720]   Transition time: 0, Target lightness: 10
00> [451329:app_light_lightness.c:773]   publish_lightness
00> [451329:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [451329:access.c:1200]   access_model_publish
00> [451329:access.c:445]   packet_alloc_and_tx
00> [451329:access.c:1200]   access_model_publish
00> [451329:access.c:445]   packet_alloc_and_tx
00> [451330:light_lightness_setup_server.c:80]   status_actual_send
00> [451330:access.c:1200]   access_model_publish
00> [451330:access.c:445]   packet_alloc_and_tx
00> [451330:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [451330:mesh_mem_stdlib.c:74]   total_size: 238
00> [451330:access.c:339]   packet_tx
00> [451330:access.c:429]   packet_tx,status:4
00> [451330:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [451330:app_light_lightness.c:789]   publish_lightness, status: 4
00> [451330:access.c:445]   packet_alloc_and_tx
00> [451330:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [451331:mesh_mem_stdlib.c:74]   total_size: 237
00> [451331:access.c:339]   packet_tx
00> [451331:access.c:429]   packet_tx,status:4
00> [451331:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [451331:main.c:1152]   msg-receive
00> [451331:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [451331:main.c:1154]   RX: Msg: 820201A80000
00> [451331:drv_dali.c:820]   srx_dali_set_lightness,acture_level:254,old_level:0
00> [451789:drv_dali.c:840]   set lightness successful!,comfirm_level:254
00> [451789:srx_msg.c:54]   set_lightness is 254 OK!
00> [455633:access.c:253]   RX: [aop: 0x8202]
00> [455633:access.c:276]   RX: Msg: 00A90000
00> [455633:generic_onoff_server.c:154]   Server: transition_time_ms = 0
00> [455633:generic_onoff_server.c:155]   Server: delay_ms = 0
00> [455633:main.c:713]   get_lightness_cb,*p_lightness:10
00> [455633:app_light_lightness.c:273]   initialized:1,present_lightness:10,p_in->lightness:0
00> [455633:app_light_lightness.c:285]   SET: target lightness: 0  delay: 0  tt: 0  req-delta: -10 
00> [455633:main.c:662]   set_lightness_cb,clp.times:720,delay_ms:10000
00> [455633:main.c:705]   set_lightness_cb,lightness_level_table[0]:0
00> [455634:app_light_lightness.c:991]   Element 0: transition completed, present-L: 0
00> [455634:main.c:720]   Transition time: 0, Target lightness: 0
00> [455634:app_light_lightness.c:773]   publish_lightness
00> [455634:light_lightness_setup_server.c:986]   light_lightness_server_status_publish
00> [455634:access.c:1200]   access_model_publish
00> [455634:access.c:445]   packet_alloc_and_tx
00> [455634:access.c:1200]   access_model_publish
00> [455634:access.c:445]   packet_alloc_and_tx
00> [455634:light_lightness_setup_server.c:80]   status_actual_send
00> [455634:access.c:1200]   access_model_publish
00> [455634:access.c:445]   packet_alloc_and_tx
00> [455634:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:4
00> [455635:mesh_mem_stdlib.c:74]   total_size: 238
00> [455635:access.c:339]   packet_tx
00> [455635:access.c:429]   packet_tx,status:4
00> [455635:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [455635:app_light_lightness.c:789]   publish_lightness, status: 4
00> [455635:access.c:445]   packet_alloc_and_tx
00> [455635:mesh_mem_stdlib.c:64]   mesh_mem_alloc,p_men:0x20007708,size:3
00> [455635:mesh_mem_stdlib.c:74]   total_size: 237
00> [455635:access.c:339]   packet_tx
00> [455635:access.c:429]   packet_tx,status:4
00> [455635:mesh_mem_stdlib.c:81]   mesh_mem_free,0x20007708
00> [455635:main.c:1152]   msg-receive
00> [455636:main.c:1153]   src:0x46,dst:0xC000,length:6
00> [455636:main.c:1154]   RX: Msg: 820200A90000
00> [455636:drv_dali.c:820]   srx_dali_set_lightness,acture_level:0,old_level:254
00> [455768:srx_msg.c:54]   set_lightness is 0 OK!
00> [457498:main.c:870]   srx_clp_timeout_handle
00> [457498:main.c:881]   particle_size:10,clp.times:720,delay_ms:10000,is_clp_started:1,pirL0
00> [457498:main.c:885]   app_timer_start->clp.timer

00> [381548:access.c:1200] access_model_publish
00> [381548:access.c:445] packet_alloc_and_tx
00> [381548:mesh_mem_stdlib.c:64] mesh_mem_alloc,p_men:0x20007708,size:4
00> [381548:mesh_mem_stdlib.c:74] total_size: 238
00> [381548:access.c:339] packet_tx
00> [381549:access.c:429] packet_tx,status:4

Hi Mttrinh,

The mesh stack running into such a status that any publish message from application layer will be rejected due to no mem. And will never recover itself.

Would you please help take a look at the log and advice further? Thanks.

[Mttrinh]

Hi,

Sorry for the late reply.

Can you debug the code and go through step by step inside packet_tx and see which function is returning this error code? 

I suspect it is nrf_mesh_packet_send(). If so, then NRF_ERROR_NO_MEM means a packet buffer could not be allocated for the packet. The application should try to send the packet again at a later point.

[Wanderzhd]

Hi Mttrinh,

Yes, it fails in nrf_mesh_packet_send due to not able to allocated for the packet. At a later point the application re-sends, sometimes it can recover, but sometimes it will never recover, each time the application sends a message it will report NRF_ERROR_NO_MEM.

Do you have any suggestion? Thanks