Code stops suddenly without a specific error pattern nrf9160 (Thingy91)

[Adeel]

Hi Dev Team,

I am facing a strange issue in which my code stops after a random time without any error messages. I currently have 5 timers running for different tasks and after an unknown while (its really random), the code stops without any error and only a single process keeps running. 

I have 1st timer for the timestamp, 2nd to get the battery value, 3rd to get the bme680 sensor values, 4th to get my connected sensor value, and the 5th timer to store the data in JSON and publish them. 

I am storing the data 5 times and publishing all the stored values every 6th time.

For reproducibility at your end, I will attach the code along with the log files.

5367.mqtt_buffer_test_working.rar

The only extra thing to be added in the autoconf file to reproduce this is : 

#define CONFIG_MQTT_PUB_TOPIC_1 "sensorstest1/nrf352656101119801/humidity"
#define CONFIG_MQTT_PUB_TOPIC_2 "sensorstest1/nrf352656101119801/rsrp"
#define CONFIG_MQTT_PUB_TOPIC_3 "sensorstest1/nrf352656101119801/battery"
#define CONFIG_MQTT_PUB_TOPIC_4 "sensorstest1/nrf352656101119801/vmc"
#define CONFIG_MQTT_PUB_TOPIC_5 "sensorstest1/nrf352656101119801/temp"

The log file of one instance is : 

2768.Code stops due to unknow reason.txt

the log file of second instance is : 

Fullscreen Code stoppage happens again.txt Download

2021-02-22T14:58:21.699Z INFO Application data folder: C:\Users\adeel\AppData\Roaming\nrfconnect\pc-nrfconnect-linkmonitor
2021-02-22T14:58:21.789Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-02-22T14:58:21.789Z DEBUG App path: C:\Users\adeel\.nrfconnect-apps\node_modules\pc-nrfconnect-linkmonitor
2021-02-22T14:58:21.789Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-02-22T14:58:21.789Z DEBUG nRFConnect path: C:\Users\adeel\AppData\Local\Programs\nrfconnect\resources\app.asar
2021-02-22T14:58:21.789Z DEBUG HomeDir: C:\Users\adeel
2021-02-22T14:58:21.790Z DEBUG TmpDir: C:\Users\adeel\AppData\Local\Temp
2021-02-22T14:58:21.797Z VERBOSE Could not fetch serial number for serial port at COM3
2021-02-22T14:58:21.798Z VERBOSE Could not fetch serial number for serial port at COM1
2021-02-22T14:58:39.309Z INFO Modem port is opened
2021-02-22T14:58:39.321Z DEBUG modem >> AT+CFUN?
2021-02-22T14:58:42.305Z DEBUG modem << +CEREG: 2,"5226","029E6004",7,0,0,"11100000","11100000"
2021-02-22T14:58:42.357Z DEBUG modem << +CSCON: 1
2021-02-22T14:58:43.419Z DEBUG modem << +CEREG: 5,"5226","029E6004",7,,,"00000010","00011111"
2021-02-22T14:58:43.427Z DEBUG modem << LTE Link Connected!
2021-02-22T14:58:43.428Z DEBUG modem << RSRP is : 0
2021-02-22T14:58:43.429Z DEBUG modem >> AT+COPS=3,2
2021-02-22T14:58:43.573Z DEBUG modem << +CEREG: 5,"5226","029E6004",7,,,"00000001","00111000"
2021-02-22T14:58:48.423Z DEBUG modem << AT send: AT%XDATAPRFL=0
2021-02-22T14:58:48.465Z DEBUG modem << AT ret: OK
2021-02-22T14:58:48.467Z DEBUG modem << AT send: AT+CEREG=5
2021-02-22T14:58:48.470Z DEBUG modem << AT ret: OK
2021-02-22T14:58:48.475Z DEBUG modem << AT send: AT%XSYSTEMMODE=1,0,0,0
2021-02-22T14:58:48.477Z DEBUG modem << AT ret: 1
2021-02-22T14:58:48.478Z DEBUG modem << AT send: AT%XVBAT
2021-02-22T14:58:48.481Z DEBUG modem << AT recv:%XVBAT: 4383
2021-02-22T14:58:48.482Z DEBUG modem << AT ret: OK
2021-02-22T14:58:48.486Z DEBUG modem << AT send: AT+CFUN=1
2021-02-22T14:58:48.490Z DEBUG modem << AT ret: OK
2021-02-22T14:58:48.492Z DEBUG modem << connecting...
2021-02-22T14:58:48.493Z DEBUG modem << connected
2021-02-22T14:58:48.495Z DEBUG modem << AT send: AT+CEREG=5
2021-02-22T14:58:48.497Z DEBUG modem << AT ret: OK
2021-02-22T14:58:48.500Z DEBUG modem << AT send: AT+CEREG?
2021-02-22T14:58:48.504Z DEBUG modem << AT recv:+CEREG: 5,5,"5226","029E6004",7,,,"00000001","00111000"
2021-02-22T14:58:48.510Z DEBUG modem << AT ret: OK
2021-02-22T14:58:48.511Z DEBUG modem << Busy-wait 10 s
2021-02-22T14:58:58.518Z DEBUG modem << Curr min: 58
2021-02-22T14:58:58.521Z DEBUG modem << Curr sec: 57
2021-02-22T14:58:58.525Z DEBUG modem << Delay is :63
2021-02-22T14:58:58.529Z DEBUG modem << Real Delay is :663
2021-02-22T14:59:00.397Z DEBUG modem << +CSCON: 0
2021-02-22T14:59:02.422Z DEBUG modem << %CESQ: 255,0,255,0
2021-02-22T14:59:40.519Z DEBUG modem << UART Listening for DDI data...
2021-02-22T14:59:40.790Z DEBUG modem << 1818.3 23.3 0
2021-02-22T14:59:40.823Z DEBUG modem << gFj
2021-02-22T14:59:43.534Z DEBUG modem << T: 24.10000; P: 96.15000; H: 28.345000; G: 51877.000
2021-02-22T14:59:43.542Z DEBUG modem << float value 1 : 96.150002
2021-02-22T14:59:43.545Z DEBUG modem << float value 2 : 28.344999
2021-02-22T14:59:43.547Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T14:59:43.550Z DEBUG modem << Value testval2 is : 28.344999
2021-02-22T14:59:48.534Z DEBUG modem << Attempting to acquire time and date from date_time library...
2021-02-22T14:59:48.540Z DEBUG modem << Time update:
2021-02-22T14:59:48.543Z DEBUG modem <<   Unix time ms: 1614005987765
2021-02-22T14:59:48.546Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 14:59:47
2021-02-22T14:59:48.548Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T14:59:53.527Z DEBUG modem << Battery is : 4391
2021-02-22T14:59:58.522Z DEBUG modem << Counterdo value is : 0
2021-02-22T15:00:40.538Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:00:40.540Z DEBUG modem << M1 value is : 1818.3
2021-02-22T15:00:40.544Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:00:40.546Z DEBUG modem << M3 value is : 0
2021-02-22T15:00:40.549Z DEBUG modem << float value DDI is : 1818.30
2021-02-22T15:00:40.550Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:00:40.551Z DEBUG modem << Value of testval3 is : 1818.30
2021-02-22T15:00:40.552Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:00:40.553Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:00:40.812Z DEBUG modem << 1817.5 23.3 -1
2021-02-22T15:00:40.845Z DEBUG modem << g5V
2021-02-22T15:00:43.531Z DEBUG modem << T: 24.190000; P: 96.17000; H: 28.18000; G: 48206.000
2021-02-22T15:00:43.539Z DEBUG modem << float value 1 : 96.169998
2021-02-22T15:00:43.540Z DEBUG modem << float value 2 : 28.180000
2021-02-22T15:00:43.541Z DEBUG modem << Value testval1 is : 96.169998
2021-02-22T15:00:43.542Z DEBUG modem << Value testval2 is : 28.180000
2021-02-22T15:00:48.526Z DEBUG modem << Time update:
2021-02-22T15:00:48.527Z DEBUG modem <<   Unix time ms: 1614006047765
2021-02-22T15:00:48.531Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:00:47
2021-02-22T15:00:48.532Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:00:53.525Z DEBUG modem << Battery is : 4391
2021-02-22T15:00:58.520Z DEBUG modem << Counterdo value is : 1
2021-02-22T15:01:40.535Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:01:40.536Z DEBUG modem << M1 value is : 1817.5
2021-02-22T15:01:40.541Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:01:40.542Z DEBUG modem << M3 value is : -1
2021-02-22T15:01:40.544Z DEBUG modem << float value DDI is : 1817.50
2021-02-22T15:01:40.545Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:01:40.546Z DEBUG modem << Value of testval3 is : 1817.50
2021-02-22T15:01:40.548Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:01:40.549Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:01:40.811Z DEBUG modem << 1818.0 23.3 -1
2021-02-22T15:01:40.843Z DEBUG modem << g1L
2021-02-22T15:01:43.529Z DEBUG modem << T: 24.50000; P: 96.19000; H: 28.187000; G: 49735.000
2021-02-22T15:01:43.538Z DEBUG modem << float value 1 : 96.190002
2021-02-22T15:01:43.539Z DEBUG modem << float value 2 : 28.187000
2021-02-22T15:01:43.540Z DEBUG modem << Value testval1 is : 96.190002
2021-02-22T15:01:43.542Z DEBUG modem << Value testval2 is : 28.187000
2021-02-22T15:01:48.524Z DEBUG modem << Time update:
2021-02-22T15:01:48.525Z DEBUG modem <<   Unix time ms: 1614006107765
2021-02-22T15:01:48.530Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:01:47
2021-02-22T15:01:48.531Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:01:53.523Z DEBUG modem << Battery is : 4391
2021-02-22T15:01:58.518Z DEBUG modem << Counterdo value is : 2
2021-02-22T15:02:40.533Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:02:40.534Z DEBUG modem << M1 value is : 1818.0
2021-02-22T15:02:40.539Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:02:40.542Z DEBUG modem << M3 value is : -1
2021-02-22T15:02:40.544Z DEBUG modem << float value DDI is : 1818.00
2021-02-22T15:02:40.547Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:02:40.548Z DEBUG modem << Value of testval3 is : 1818.00
2021-02-22T15:02:40.549Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:02:40.551Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:02:40.807Z DEBUG modem << 1818.1 23.3 -1
2021-02-22T15:02:40.840Z DEBUG modem << g2L
2021-02-22T15:02:43.527Z DEBUG modem << T: 23.960000; P: 96.15000; H: 28.367000; G: 51048.000
2021-02-22T15:02:43.532Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:02:43.534Z DEBUG modem << float value 2 : 28.367001
2021-02-22T15:02:43.536Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:02:43.538Z DEBUG modem << Value testval2 is : 28.367001
2021-02-22T15:02:48.521Z DEBUG modem << Time update:
2021-02-22T15:02:48.522Z DEBUG modem <<   Unix time ms: 1614006167765
2021-02-22T15:02:48.534Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:02:47
2021-02-22T15:02:48.535Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:02:53.520Z DEBUG modem << Battery is : 4391
2021-02-22T15:02:58.516Z DEBUG modem << Counterdo value is : 3
2021-02-22T15:03:40.531Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:03:40.532Z DEBUG modem << M1 value is : 1818.1
2021-02-22T15:03:40.537Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:03:40.538Z DEBUG modem << M3 value is : -1
2021-02-22T15:03:40.539Z DEBUG modem << float value DDI is : 1818.10
2021-02-22T15:03:40.539Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:03:40.540Z DEBUG modem << Value of testval3 is : 1818.10
2021-02-22T15:03:40.540Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:03:40.541Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:03:40.806Z DEBUG modem << 1818.1 23.3 -1
2021-02-22T15:03:40.840Z DEBUG modem << g2L
2021-02-22T15:03:43.526Z DEBUG modem << T: 23.900000; P: 96.15000; H: 28.462000; G: 52466.000
2021-02-22T15:03:43.531Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:03:43.532Z DEBUG modem << float value 2 : 28.462000
2021-02-22T15:03:43.532Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:03:43.533Z DEBUG modem << Value testval2 is : 28.462000
2021-02-22T15:03:48.519Z DEBUG modem << Time update:
2021-02-22T15:03:48.520Z DEBUG modem <<   Unix time ms: 1614006227765
2021-02-22T15:03:48.529Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:03:47
2021-02-22T15:03:48.530Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:03:53.518Z DEBUG modem << Battery is : 4391
2021-02-22T15:03:58.515Z DEBUG modem << Counterdo value is : 4
2021-02-22T15:04:40.529Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:04:40.530Z DEBUG modem << M1 value is : 1818.1
2021-02-22T15:04:40.538Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:04:40.539Z DEBUG modem << M3 value is : -1
2021-02-22T15:04:40.540Z DEBUG modem << float value DDI is : 1818.10
2021-02-22T15:04:40.540Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:04:40.541Z DEBUG modem << Value of testval3 is : 1818.10
2021-02-22T15:04:40.544Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:04:40.545Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:04:40.802Z DEBUG modem << 1818.2 23.3 -1
2021-02-22T15:04:40.835Z DEBUG modem << g3Y
2021-02-22T15:04:43.523Z DEBUG modem << T: 23.860000; P: 96.15000; H: 28.572000; G: 53651.000
2021-02-22T15:04:43.529Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:04:43.530Z DEBUG modem << float value 2 : 28.572001
2021-02-22T15:04:43.531Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:04:43.532Z DEBUG modem << Value testval2 is : 28.572001
2021-02-22T15:04:48.508Z DEBUG modem << Time update:
2021-02-22T15:04:48.509Z DEBUG modem <<   Unix time ms: 1614006287765
2021-02-22T15:04:48.517Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:04:47
2021-02-22T15:04:48.520Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:04:53.516Z DEBUG modem << Battery is : 4391
2021-02-22T15:04:58.512Z DEBUG modem << Counterdo value is : 5
2021-02-22T15:04:59.449Z DEBUG modem << IPv4 Address found 13.80.240.194
2021-02-22T15:04:59.543Z DEBUG modem << Timer triggered, publishing MQTT message!!!!
2021-02-22T15:04:59.736Z DEBUG modem << [mqtt_evt_handler:730] MQTT client connected!
2021-02-22T15:04:59.743Z DEBUG modem << Subscribing to: my/send/message len 15
2021-02-22T15:04:59.744Z DEBUG modem << Publishing: {
2021-02-22T15:04:59.745Z DEBUG modem << "description":"BME680",
2021-02-22T15:04:59.746Z DEBUG modem << "Time":"2021-02-22 14:59:47",
2021-02-22T15:04:59.747Z DEBUG modem << "value":96.15,
2021-02-22T15:04:59.748Z DEBUG modem << "unit":"N/m2",
2021-02-22T15:04:59.749Z DEBUG modem << "type":"pressure"
2021-02-22T15:04:59.749Z DEBUG modem << }
2021-02-22T15:04:59.750Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/pressure len: 40
2021-02-22T15:04:59.750Z DEBUG modem << Publishing: {
2021-02-22T15:04:59.750Z DEBUG modem << "description":"BME680",
2021-02-22T15:04:59.751Z DEBUG modem << "Time":"2021-02-22 14:59:47",
2021-02-22T15:04:59.752Z DEBUG modem << "value":28.345,
2021-02-22T15:04:59.761Z DEBUG modem << "unit":"rH",
2021-02-22T15:04:59.763Z DEBUG modem << "type":"humidity"
2021-02-22T15:04:59.764Z DEBUG modem << }
2021-02-22T15:04:59.765Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/humidity len: 40
2021-02-22T15:04:59.766Z DEBUG modem << Publishing: {
2021-02-22T15:04:59.766Z DEBUG modem << "description":"RSRP",
2021-02-22T15:04:59.766Z DEBUG modem << "Time":"2021-02-22 14:59:47",
2021-02-22T15:04:59.767Z DEBUG modem << "value":0,
2021-02-22T15:04:59.767Z DEBUG modem << "unit":"dBm",
2021-02-22T15:04:59.768Z DEBUG modem << "type":"RSRP"
2021-02-22T15:04:59.769Z DEBUG modem << }
2021-02-22T15:04:59.769Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/rsrp len: 36
2021-02-22T15:04:59.770Z DEBUG modem << Publishing: {
2021-02-22T15:04:59.770Z DEBUG modem << "description":"Battery",
2021-02-22T15:04:59.771Z DEBUG modem << "Time":"2021-02-22 14:59:47",
2021-02-22T15:04:59.771Z DEBUG modem << "value":4391,
2021-02-22T15:04:59.772Z DEBUG modem << "unit":"mV",
2021-02-22T15:04:59.772Z DEBUG modem << "type":"Voltage"
2021-02-22T15:04:59.772Z DEBUG modem << }
2021-02-22T15:04:59.773Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/battery len: 39
2021-02-22T15:04:59.773Z DEBUG modem << Publishing: {
2021-02-22T15:04:59.774Z DEBUG modem << "description":"Teros12",
2021-02-22T15:04:59.775Z DEBUG modem << "Time":"2021-02-22 14:59:47",
2021-02-22T15:04:59.775Z DEBUG modem << "value":0,
2021-02-22T15:04:59.775Z DEBUG modem << "unit":"cm3",
2021-02-22T15:04:59.776Z DEBUG modem << "type":"vmc"
2021-02-22T15:04:59.776Z DEBUG modem << }
2021-02-22T15:04:59.777Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/vmc len: 35
2021-02-22T15:04:59.777Z DEBUG modem << Publishing: {
2021-02-22T15:04:59.777Z DEBUG modem << "description":"Teros12",
2021-02-22T15:04:59.778Z DEBUG modem << "Time":"2021-02-22 14:59:47",
2021-02-22T15:04:59.778Z DEBUG modem << "value":0,
2021-02-22T15:04:59.779Z DEBUG modem << "unit":"C",
2021-02-22T15:04:59.780Z DEBUG modem << "type":"temp"
2021-02-22T15:04:59.781Z DEBUG modem << }
2021-02-22T15:04:59.783Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/temp len: 36
2021-02-22T15:05:00.754Z DEBUG modem << Publishing: {
2021-02-22T15:05:00.755Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:00.761Z DEBUG modem << "Time":"2021-02-22 15:00:47",
2021-02-22T15:05:00.762Z DEBUG modem << "value":96.17,
2021-02-22T15:05:00.763Z DEBUG modem << "unit":"N/m2",
2021-02-22T15:05:00.764Z DEBUG modem << "type":"pressure"
2021-02-22T15:05:00.764Z DEBUG modem << }
2021-02-22T15:05:00.769Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/pressure len: 40
2021-02-22T15:05:00.801Z DEBUG modem << Publishing: {
2021-02-22T15:05:00.802Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:00.809Z DEBUG modem << "Time":"2021-02-22 15:00:47",
2021-02-22T15:05:00.810Z DEBUG modem << "value":28.18,
2021-02-22T15:05:00.811Z DEBUG modem << "unit":"rH",
2021-02-22T15:05:00.811Z DEBUG modem << "type":"humidity"
2021-02-22T15:05:00.812Z DEBUG modem << }
2021-02-22T15:05:00.813Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/humidity len: 40
2021-02-22T15:05:00.813Z DEBUG modem << Publishing: {
2021-02-22T15:05:00.814Z DEBUG modem << "description":"RSRP",
2021-02-22T15:05:00.815Z DEBUG modem << "Time":"2021-02-22 15:00:47",
2021-02-22T15:05:00.817Z DEBUG modem << "value":0,
2021-02-22T15:05:00.818Z DEBUG modem << "unit":"dBm",
2021-02-22T15:05:00.819Z DEBUG modem << "type":"RSRP"
2021-02-22T15:05:00.820Z DEBUG modem << }
2021-02-22T15:05:00.821Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/rsrp len: 36
2021-02-22T15:05:00.822Z DEBUG modem << Publishing: {
2021-02-22T15:05:00.823Z DEBUG modem << "description":"Battery",
2021-02-22T15:05:00.848Z DEBUG modem << "Time":"2021-02-22 15:00:47",
2021-02-22T15:05:00.849Z DEBUG modem << "value":4391,
2021-02-22T15:05:00.850Z DEBUG modem << "unit":"mV",
2021-02-22T15:05:00.868Z DEBUG modem << "type":"Voltage"
2021-02-22T15:05:00.870Z DEBUG modem << }
2021-02-22T15:05:00.872Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/battery len: 39
2021-02-22T15:05:00.873Z DEBUG modem << Publishing: {
2021-02-22T15:05:00.874Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:00.875Z DEBUG modem << "Time":"2021-02-22 15:00:47",
2021-02-22T15:05:00.876Z DEBUG modem << "value":1818.3,
2021-02-22T15:05:00.877Z DEBUG modem << "unit":"cm3",
2021-02-22T15:05:00.877Z DEBUG modem << "type":"vmc"
2021-02-22T15:05:00.877Z DEBUG modem << }
2021-02-22T15:05:00.878Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/vmc len: 35
2021-02-22T15:05:00.878Z DEBUG modem << Publishing: {
2021-02-22T15:05:00.878Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:00.879Z DEBUG modem << "Time":"2021-02-22 15:00:47",
2021-02-22T15:05:00.879Z DEBUG modem << "value":23.3,
2021-02-22T15:05:00.879Z DEBUG modem << "unit":"C",
2021-02-22T15:05:00.880Z DEBUG modem << "type":"temp"
2021-02-22T15:05:00.882Z DEBUG modem << }
2021-02-22T15:05:00.882Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/temp len: 36
2021-02-22T15:05:01.951Z DEBUG modem << Publishing: {
2021-02-22T15:05:01.953Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:01.961Z DEBUG modem << "Time":"2021-02-22 15:01:47",
2021-02-22T15:05:01.962Z DEBUG modem << "value":96.19,
2021-02-22T15:05:01.962Z DEBUG modem << "unit":"N/m2",
2021-02-22T15:05:01.963Z DEBUG modem << "type":"pressure"
2021-02-22T15:05:01.964Z DEBUG modem << }
2021-02-22T15:05:01.964Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/pressure len: 40
2021-02-22T15:05:01.965Z DEBUG modem << Publishing: {
2021-02-22T15:05:01.967Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:01.968Z DEBUG modem << "Time":"2021-02-22 15:01:47",
2021-02-22T15:05:01.970Z DEBUG modem << "value":28.187,
2021-02-22T15:05:01.971Z DEBUG modem << "unit":"rH",
2021-02-22T15:05:01.972Z DEBUG modem << "type":"humidity"
2021-02-22T15:05:01.973Z DEBUG modem << }
2021-02-22T15:05:01.974Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/humidity len: 40
2021-02-22T15:05:01.974Z DEBUG modem << Publishing: {
2021-02-22T15:05:01.975Z DEBUG modem << "description":"RSRP",
2021-02-22T15:05:01.975Z DEBUG modem << "Time":"2021-02-22 15:01:47",
2021-02-22T15:05:01.976Z DEBUG modem << "value":0,
2021-02-22T15:05:01.977Z DEBUG modem << "unit":"dBm",
2021-02-22T15:05:01.977Z DEBUG modem << "type":"RSRP"
2021-02-22T15:05:01.979Z DEBUG modem << }
2021-02-22T15:05:01.979Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/rsrp len: 36
2021-02-22T15:05:01.980Z DEBUG modem << Publishing: {
2021-02-22T15:05:01.980Z DEBUG modem << "description":"Battery",
2021-02-22T15:05:01.981Z DEBUG modem << "Time":"2021-02-22 15:01:47",
2021-02-22T15:05:01.981Z DEBUG modem << "value":4391,
2021-02-22T15:05:01.981Z DEBUG modem << "unit":"mV",
2021-02-22T15:05:01.982Z DEBUG modem << "type":"Voltage"
2021-02-22T15:05:01.982Z DEBUG modem << }
2021-02-22T15:05:01.984Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/battery len: 39
2021-02-22T15:05:01.985Z DEBUG modem << Publishing: {
2021-02-22T15:05:01.987Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:01.988Z DEBUG modem << "Time":"2021-02-22 15:01:47",
2021-02-22T15:05:01.989Z DEBUG modem << "value":1817.5,
2021-02-22T15:05:01.991Z DEBUG modem << "unit":"cm3",
2021-02-22T15:05:01.992Z DEBUG modem << "type":"vmc"
2021-02-22T15:05:01.994Z DEBUG modem << }
2021-02-22T15:05:01.994Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/vmc len: 35
2021-02-22T15:05:01.994Z DEBUG modem << Publishing: {
2021-02-22T15:05:01.995Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:01.996Z DEBUG modem << "Time":"2021-02-22 15:01:47",
2021-02-22T15:05:01.997Z DEBUG modem << "value":23.3,
2021-02-22T15:05:01.997Z DEBUG modem << "unit":"C",
2021-02-22T15:05:01.998Z DEBUG modem << "type":"temp"
2021-02-22T15:05:01.998Z DEBUG modem << }
2021-02-22T15:05:01.999Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/temp len: 36
2021-02-22T15:05:02.968Z DEBUG modem << Publishing: {
2021-02-22T15:05:02.970Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:02.978Z DEBUG modem << "Time":"2021-02-22 15:02:47",
2021-02-22T15:05:02.979Z DEBUG modem << "value":96.15,
2021-02-22T15:05:02.980Z DEBUG modem << "unit":"N/m2",
2021-02-22T15:05:02.981Z DEBUG modem << "type":"pressure"
2021-02-22T15:05:02.981Z DEBUG modem << }
2021-02-22T15:05:02.982Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/pressure len: 40
2021-02-22T15:05:02.996Z DEBUG modem << Publishing: {
2021-02-22T15:05:02.997Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:02.998Z DEBUG modem << "Time":"2021-02-22 15:02:47",
2021-02-22T15:05:03.000Z DEBUG modem << "value":28.367,
2021-02-22T15:05:03.000Z DEBUG modem << "unit":"rH",
2021-02-22T15:05:03.001Z DEBUG modem << "type":"humidity"
2021-02-22T15:05:03.001Z DEBUG modem << }
2021-02-22T15:05:03.002Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/humidity len: 40
2021-02-22T15:05:03.002Z DEBUG modem << Publishing: {
2021-02-22T15:05:03.063Z DEBUG modem << "description":"RSRP",
2021-02-22T15:05:03.065Z DEBUG modem << "Time":"2021-02-22 15:02:47",
2021-02-22T15:05:03.065Z DEBUG modem << "value":0,
2021-02-22T15:05:03.066Z DEBUG modem << "unit":"dBm",
2021-02-22T15:05:03.067Z DEBUG modem << "type":"RSRP"
2021-02-22T15:05:03.068Z DEBUG modem << }
2021-02-22T15:05:03.068Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/rsrp len: 36
2021-02-22T15:05:03.069Z DEBUG modem << Publishing: {
2021-02-22T15:05:03.069Z DEBUG modem << "description":"Battery",
2021-02-22T15:05:03.070Z DEBUG modem << "Time":"2021-02-22 15:02:47",
2021-02-22T15:05:03.070Z DEBUG modem << "value":4391,
2021-02-22T15:05:03.070Z DEBUG modem << "unit":"mV",
2021-02-22T15:05:03.071Z DEBUG modem << "type":"Voltage"
2021-02-22T15:05:03.072Z DEBUG modem << }
2021-02-22T15:05:03.072Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/battery len: 39
2021-02-22T15:05:03.073Z DEBUG modem << Publishing: {
2021-02-22T15:05:03.073Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:03.073Z DEBUG modem << "Time":"2021-02-22 15:02:47",
2021-02-22T15:05:03.074Z DEBUG modem << "value":1818,
2021-02-22T15:05:03.074Z DEBUG modem << "unit":"cm3",
2021-02-22T15:05:03.074Z DEBUG modem << "type":"vmc"
2021-02-22T15:05:03.075Z DEBUG modem << }
2021-02-22T15:05:03.075Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/vmc len: 35
2021-02-22T15:05:03.075Z DEBUG modem << Publishing: {
2021-02-22T15:05:03.076Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:03.093Z DEBUG modem << "Time":"2021-02-22 15:02:47",
2021-02-22T15:05:03.094Z DEBUG modem << "value":23.3,
2021-02-22T15:05:03.094Z DEBUG modem << "unit":"C",
2021-02-22T15:05:03.095Z DEBUG modem << "type":"temp"
2021-02-22T15:05:03.095Z DEBUG modem << }
2021-02-22T15:05:03.096Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/temp len: 36
2021-02-22T15:05:04.080Z DEBUG modem << Publishing: {
2021-02-22T15:05:04.081Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:04.100Z DEBUG modem << "Time":"2021-02-22 15:03:47",
2021-02-22T15:05:04.101Z DEBUG modem << "value":96.15,
2021-02-22T15:05:04.102Z DEBUG modem << "unit":"N/m2",
2021-02-22T15:05:04.104Z DEBUG modem << "type":"pressure"
2021-02-22T15:05:04.105Z DEBUG modem << }
2021-02-22T15:05:04.106Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/pressure len: 40
2021-02-22T15:05:04.171Z DEBUG modem << Publishing: {
2021-02-22T15:05:04.172Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:04.174Z DEBUG modem << "Time":"2021-02-22 15:03:47",
2021-02-22T15:05:04.174Z DEBUG modem << "value":28.462,
2021-02-22T15:05:04.175Z DEBUG modem << "unit":"rH",
2021-02-22T15:05:04.176Z DEBUG modem << "type":"humidity"
2021-02-22T15:05:04.177Z DEBUG modem << }
2021-02-22T15:05:04.179Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/humidity len: 40
2021-02-22T15:05:04.180Z DEBUG modem << Publishing: {
2021-02-22T15:05:04.181Z DEBUG modem << "description":"RSRP",
2021-02-22T15:05:04.182Z DEBUG modem << "Time":"2021-02-22 15:03:47",
2021-02-22T15:05:04.182Z DEBUG modem << "value":0,
2021-02-22T15:05:04.182Z DEBUG modem << "unit":"dBm",
2021-02-22T15:05:04.183Z DEBUG modem << "type":"RSRP"
2021-02-22T15:05:04.183Z DEBUG modem << }
2021-02-22T15:05:04.183Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/rsrp len: 36
2021-02-22T15:05:04.184Z DEBUG modem << Publishing: {
2021-02-22T15:05:04.184Z DEBUG modem << "description":"Battery",
2021-02-22T15:05:04.184Z DEBUG modem << "Time":"2021-02-22 15:03:47",
2021-02-22T15:05:04.186Z DEBUG modem << "value":4391,
2021-02-22T15:05:04.187Z DEBUG modem << "unit":"mV",
2021-02-22T15:05:04.188Z DEBUG modem << "type":"Voltage"
2021-02-22T15:05:04.189Z DEBUG modem << }
2021-02-22T15:05:04.190Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/battery len: 39
2021-02-22T15:05:04.212Z DEBUG modem << Publishing: {
2021-02-22T15:05:04.213Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:04.214Z DEBUG modem << "Time":"2021-02-22 15:03:47",
2021-02-22T15:05:04.215Z DEBUG modem << "value":1818.1,
2021-02-22T15:05:04.215Z DEBUG modem << "unit":"cm3",
2021-02-22T15:05:04.215Z DEBUG modem << "type":"vmc"
2021-02-22T15:05:04.216Z DEBUG modem << }
2021-02-22T15:05:04.216Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/vmc len: 35
2021-02-22T15:05:04.216Z DEBUG modem << Publishing: {
2021-02-22T15:05:04.217Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:04.217Z DEBUG modem << "Time":"2021-02-22 15:03:47",
2021-02-22T15:05:04.218Z DEBUG modem << "value":23.3,
2021-02-22T15:05:04.219Z DEBUG modem << "unit":"C",
2021-02-22T15:05:04.220Z DEBUG modem << "type":"temp"
2021-02-22T15:05:04.222Z DEBUG modem << }
2021-02-22T15:05:04.223Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/temp len: 36
2021-02-22T15:05:05.189Z DEBUG modem << Publishing: {
2021-02-22T15:05:05.190Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:05.200Z DEBUG modem << "Time":"2021-02-22 15:04:47",
2021-02-22T15:05:05.201Z DEBUG modem << "value":96.15,
2021-02-22T15:05:05.204Z DEBUG modem << "unit":"N/m2",
2021-02-22T15:05:05.204Z DEBUG modem << "type":"pressure"
2021-02-22T15:05:05.205Z DEBUG modem << }
2021-02-22T15:05:05.205Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/pressure len: 40
2021-02-22T15:05:05.205Z DEBUG modem << Publishing: {
2021-02-22T15:05:05.231Z DEBUG modem << "description":"BME680",
2021-02-22T15:05:05.232Z DEBUG modem << "Time":"2021-02-22 15:04:47",
2021-02-22T15:05:05.233Z DEBUG modem << "value":28.572,
2021-02-22T15:05:05.234Z DEBUG modem << "unit":"rH",
2021-02-22T15:05:05.235Z DEBUG modem << "type":"humidity"
2021-02-22T15:05:05.236Z DEBUG modem << }
2021-02-22T15:05:05.237Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/humidity len: 40
2021-02-22T15:05:05.237Z DEBUG modem << Publishing: {
2021-02-22T15:05:05.238Z DEBUG modem << "description":"RSRP",
2021-02-22T15:05:05.238Z DEBUG modem << "Time":"2021-02-22 15:04:47",
2021-02-22T15:05:05.238Z DEBUG modem << "value":0,
2021-02-22T15:05:05.238Z DEBUG modem << "unit":"dBm",
2021-02-22T15:05:05.239Z DEBUG modem << "type":"RSRP"
2021-02-22T15:05:05.239Z DEBUG modem << }
2021-02-22T15:05:05.240Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/rsrp len: 36
2021-02-22T15:05:05.255Z DEBUG modem << Publishing: {
2021-02-22T15:05:05.256Z DEBUG modem << "description":"Battery",
2021-02-22T15:05:05.257Z DEBUG modem << "Time":"2021-02-22 15:04:47",
2021-02-22T15:05:05.258Z DEBUG modem << "value":4391,
2021-02-22T15:05:05.258Z DEBUG modem << "unit":"mV",
2021-02-22T15:05:05.259Z DEBUG modem << "type":"Voltage"
2021-02-22T15:05:05.259Z DEBUG modem << }
2021-02-22T15:05:05.259Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/battery len: 39
2021-02-22T15:05:05.295Z DEBUG modem << Publishing: {
2021-02-22T15:05:05.296Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:05.297Z DEBUG modem << "Time":"2021-02-22 15:04:47",
2021-02-22T15:05:05.298Z DEBUG modem << "value":1818.1,
2021-02-22T15:05:05.298Z DEBUG modem << "unit":"cm3",
2021-02-22T15:05:05.299Z DEBUG modem << "type":"vmc"
2021-02-22T15:05:05.299Z DEBUG modem << }
2021-02-22T15:05:05.299Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/vmc len: 35
2021-02-22T15:05:05.299Z DEBUG modem << Publishing: {
2021-02-22T15:05:05.300Z DEBUG modem << "description":"Teros12",
2021-02-22T15:05:05.300Z DEBUG modem << "Time":"2021-02-22 15:04:47",
2021-02-22T15:05:05.300Z DEBUG modem << "value":23.3,
2021-02-22T15:05:05.301Z DEBUG modem << "unit":"C",
2021-02-22T15:05:05.301Z DEBUG modem << "type":"temp"
2021-02-22T15:05:05.301Z DEBUG modem << }
2021-02-22T15:05:05.302Z DEBUG modem << to topic: sensorstest1/nrf352656101119801/temp len: 36
2021-02-22T15:05:09.297Z DEBUG modem << Disconnecting MQTT client...
2021-02-22T15:05:09.317Z DEBUG modem << [mqtt_evt_handler:736] MQTT client disconnected 0
2021-02-22T15:05:09.332Z DEBUG modem << ERROR: mqtt_live -128
2021-02-22T15:05:09.333Z DEBUG modem << ERROR: mqtt_input -13
2021-02-22T15:05:09.335Z DEBUG modem >> AT+COPS=3,2
2021-02-22T15:05:15.323Z DEBUG modem << %CESQ: 44,2,20,2
2021-02-22T15:05:15.329Z DEBUG modem << RSRP is : 97
2021-02-22T15:05:15.331Z DEBUG modem << +CSCON: 1
2021-02-22T15:05:15.332Z DEBUG modem << %CESQ: 44,2,25,3
2021-02-22T15:05:15.351Z DEBUG modem << RSRP is : 97
2021-02-22T15:05:20.811Z DEBUG modem << +CSCON: 0
2021-02-22T15:05:21.017Z DEBUG modem << %CESQ: 44,2,20,2
2021-02-22T15:05:21.021Z DEBUG modem << RSRP is : 97
2021-02-22T15:05:22.837Z DEBUG modem << %CESQ: 255,0,255,0
2021-02-22T15:05:40.526Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:05:40.527Z DEBUG modem << M1 value is : 1818.2
2021-02-22T15:05:40.538Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:05:40.539Z DEBUG modem << M3 value is : -1
2021-02-22T15:05:40.540Z DEBUG modem << float value DDI is : 1818.20
2021-02-22T15:05:40.541Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:05:40.542Z DEBUG modem << Value of testval3 is : 1818.20
2021-02-22T15:05:40.544Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:05:40.545Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:05:40.802Z DEBUG modem << 1818.5 23.3 -1
2021-02-22T15:05:40.835Z DEBUG modem << g6A
2021-02-22T15:05:43.521Z DEBUG modem << T: 23.840000; P: 96.15000; H: 28.591000; G: 54424.000
2021-02-22T15:05:43.532Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:05:43.533Z DEBUG modem << float value 2 : 28.591000
2021-02-22T15:05:43.534Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:05:43.536Z DEBUG modem << Value testval2 is : 28.591000
2021-02-22T15:05:48.516Z DEBUG modem << Time update:
2021-02-22T15:05:48.517Z DEBUG modem <<   Unix time ms: 1614006347765
2021-02-22T15:05:48.541Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:05:47
2021-02-22T15:05:48.542Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:05:53.514Z DEBUG modem << Battery is : 4391
2021-02-22T15:05:58.510Z DEBUG modem << Counterdo value is : 0
2021-02-22T15:06:40.524Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:06:40.525Z DEBUG modem << M1 value is : 1818.5
2021-02-22T15:06:40.541Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:06:40.542Z DEBUG modem << M3 value is : -1
2021-02-22T15:06:40.543Z DEBUG modem << float value DDI is : 1818.50
2021-02-22T15:06:40.544Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:06:40.545Z DEBUG modem << Value of testval3 is : 1818.50
2021-02-22T15:06:40.546Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:06:40.547Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:06:40.791Z DEBUG modem << 1818.1 23.3 0
2021-02-22T15:06:40.825Z DEBUG modem << gDQ
2021-02-22T15:06:43.519Z DEBUG modem << T: 23.980000; P: 96.15000; H: 28.445000; G: 55073.000
2021-02-22T15:06:43.531Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:06:43.532Z DEBUG modem << float value 2 : 28.445000
2021-02-22T15:06:43.533Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:06:43.534Z DEBUG modem << Value testval2 is : 28.445000
2021-02-22T15:06:48.512Z DEBUG modem << Time update:
2021-02-22T15:06:48.514Z DEBUG modem <<   Unix time ms: 1614006407765
2021-02-22T15:06:48.531Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:06:47
2021-02-22T15:06:48.532Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:06:53.512Z DEBUG modem << Battery is : 4391
2021-02-22T15:06:58.508Z DEBUG modem << Counterdo value is : 1
2021-02-22T15:07:40.522Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:07:40.523Z DEBUG modem << M1 value is : 1818.1
2021-02-22T15:07:40.547Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:07:40.551Z DEBUG modem << M3 value is : 0
2021-02-22T15:07:40.553Z DEBUG modem << float value DDI is : 1818.10
2021-02-22T15:07:40.555Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:07:40.556Z DEBUG modem << Value of testval3 is : 1818.10
2021-02-22T15:07:40.556Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:07:40.557Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:07:40.797Z DEBUG modem << 1817.9 23.3 -1
2021-02-22T15:07:40.831Z DEBUG modem << g9a
2021-02-22T15:07:43.516Z DEBUG modem << T: 23.920000; P: 96.15000; H: 28.589000; G: 55146.000
2021-02-22T15:07:43.545Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:07:43.547Z DEBUG modem << float value 2 : 28.589001
2021-02-22T15:07:43.549Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:07:43.549Z DEBUG modem << Value testval2 is : 28.589001
2021-02-22T15:07:48.511Z DEBUG modem << Time update:
2021-02-22T15:07:48.513Z DEBUG modem <<   Unix time ms: 1614006467765
2021-02-22T15:07:48.528Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:07:47
2021-02-22T15:07:48.530Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:07:53.510Z DEBUG modem << Battery is : 4391
2021-02-22T15:07:58.506Z DEBUG modem << Counterdo value is : 2
2021-02-22T15:08:40.520Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:08:40.522Z DEBUG modem << M1 value is : 1817.9
2021-02-22T15:08:40.537Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:08:40.538Z DEBUG modem << M3 value is : -1
2021-02-22T15:08:40.539Z DEBUG modem << float value DDI is : 1817.90
2021-02-22T15:08:40.540Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:08:40.541Z DEBUG modem << Value of testval3 is : 1817.90
2021-02-22T15:08:40.541Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:08:40.542Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:08:40.795Z DEBUG modem << 1819.0 23.3 -1
2021-02-22T15:08:40.828Z DEBUG modem << g2d
2021-02-22T15:08:43.514Z DEBUG modem << T: 23.880000; P: 96.15000; H: 28.693000; G: 55663.000
2021-02-22T15:08:43.528Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:08:43.529Z DEBUG modem << float value 2 : 28.693001
2021-02-22T15:08:43.530Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:08:43.531Z DEBUG modem << Value testval2 is : 28.693001
2021-02-22T15:08:48.509Z DEBUG modem << Time update:
2021-02-22T15:08:48.511Z DEBUG modem <<   Unix time ms: 1614006527765
2021-02-22T15:08:48.523Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:08:47
2021-02-22T15:08:48.524Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:08:53.505Z DEBUG modem << Battery is : 4391
2021-02-22T15:08:58.503Z DEBUG modem << Counterdo value is : 3
2021-02-22T15:09:40.513Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:09:40.514Z DEBUG modem << M1 value is : 1819.0
2021-02-22T15:09:40.527Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:09:40.528Z DEBUG modem << M3 value is : -1
2021-02-22T15:09:40.529Z DEBUG modem << float value DDI is : 1819.00
2021-02-22T15:09:40.530Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:09:40.531Z DEBUG modem << Value of testval3 is : 1819.00
2021-02-22T15:09:48.507Z DEBUG modem << Time update:
2021-02-22T15:09:48.508Z DEBUG modem <<   Unix time ms: 1614006587765
2021-02-22T15:09:48.522Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:09:47
2021-02-22T15:09:48.523Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:10:48.504Z DEBUG modem << Time update:
2021-02-22T15:10:48.506Z DEBUG modem <<   Unix time ms: 1614006647765
2021-02-22T15:10:48.521Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:10:47
2021-02-22T15:10:48.522Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:11:48.502Z DEBUG modem << Time update:
2021-02-22T15:11:48.504Z DEBUG modem <<   Unix time ms: 1614006707765
2021-02-22T15:11:48.538Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:11:47
2021-02-22T15:11:48.539Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:12:48.500Z DEBUG modem << Time update:
2021-02-22T15:12:48.501Z DEBUG modem <<   Unix time ms: 1614006767765
2021-02-22T15:12:48.518Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:12:47
2021-02-22T15:12:48.520Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:13:48.498Z DEBUG modem << Time update:
2021-02-22T15:13:48.499Z DEBUG modem <<   Unix time ms: 1614006827765
2021-02-22T15:13:48.512Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:13:47
2021-02-22T15:13:48.513Z DEBUG modem <<   Time Value is :537048647 

The weirdest error is that the code just stops after a while and I only keep getting the timestamp. Its visible at the end of the log file.

It would be great if you could help me understand what is happening that causes the code stoppage.

Regards,

Adeel.

[Øyvind]

Hi Adeel, 

From the results you have posted I see you get -1 several times before data is sent.

M3 value is : -1

Is this as expected?

Then you receive:

2021-02-22T13:52:14.337Z DEBUG modem << Disconnecting MQTT client...
2021-02-22T13:52:14.378Z DEBUG modem << [mqtt_evt_handler:736] MQTT client disconnected 0
2021-02-22T13:52:14.380Z DEBUG modem << ERROR: mqtt_live -128
2021-02-22T13:52:14.381Z DEBUG modem << ERROR: mqtt_input -13

Difficult for me to see where the issue starts / code stops sending? Have you tried to debug e.g. removing one timer at a time or sensor reading? What single process keeps running?

Thanks. 

Kind regards,
Øyvind

[Adeel]

Hi Oyvind,

Øyvind said:

Is this as expected?

Yes, this is as expected and is a value from my sensor. 

2021-02-22T13:52:14.337Z DEBUG modem << Disconnecting MQTT client...
2021-02-22T13:52:14.378Z DEBUG modem << [mqtt_evt_handler:736] MQTT client disconnected 0
2021-02-22T13:52:14.380Z DEBUG modem << ERROR: mqtt_live -128
2021-02-22T13:52:14.381Z DEBUG modem << ERROR: mqtt_input -13

I connect to MQTT broker only when I need to publish data and then I disconnect the broker. So I get the disconnected message which is normal as well. 

Øyvind said:

What single process keeps running?

The process of getting the timestamp keeps running while all the other processes just stop suddenly. I don't even get any error messages for that. 

2021-02-22T15:07:58.506Z DEBUG modem << Counterdo value is : 2
2021-02-22T15:08:40.520Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:08:40.522Z DEBUG modem << M1 value is : 1817.9
2021-02-22T15:08:40.537Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:08:40.538Z DEBUG modem << M3 value is : -1
2021-02-22T15:08:40.539Z DEBUG modem << float value DDI is : 1817.90
2021-02-22T15:08:40.540Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:08:40.541Z DEBUG modem << Value of testval3 is : 1817.90
2021-02-22T15:08:40.541Z DEBUG modem << Value of VWC is : 0.01
2021-02-22T15:08:40.542Z DEBUG modem << Value of testval4 is : 23.30
2021-02-22T15:08:40.795Z DEBUG modem << 1819.0 23.3 -1
2021-02-22T15:08:40.828Z DEBUG modem << g2d
2021-02-22T15:08:43.514Z DEBUG modem << T: 23.880000; P: 96.15000; H: 28.693000; G: 55663.000
2021-02-22T15:08:43.528Z DEBUG modem << float value 1 : 96.150002
2021-02-22T15:08:43.529Z DEBUG modem << float value 2 : 28.693001
2021-02-22T15:08:43.530Z DEBUG modem << Value testval1 is : 96.150002
2021-02-22T15:08:43.531Z DEBUG modem << Value testval2 is : 28.693001
2021-02-22T15:08:48.509Z DEBUG modem << Time update:
2021-02-22T15:08:48.511Z DEBUG modem <<   Unix time ms: 1614006527765
2021-02-22T15:08:48.523Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:08:47
2021-02-22T15:08:48.524Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:08:53.505Z DEBUG modem << Battery is : 4391
2021-02-22T15:08:58.503Z DEBUG modem << Counterdo value is : 3
2021-02-22T15:09:40.513Z DEBUG modem 

This is the basic process that is correct as attached above : I get my sensor data first, then I get my BME680 sensor data, then I get the timestamp, then I get the battery and then my counter value increases which stores all the data. 

So, basically I start my code, get the sensor readings, store it and increment a counter from 0 to 5. After that, I publish the data out.

2021-02-22T15:08:58.503Z DEBUG modem << Counterdo value is : 3
2021-02-22T15:09:40.513Z DEBUG modem << UART Listening for DDI data...
2021-02-22T15:09:40.514Z DEBUG modem << M1 value is : 1819.0
2021-02-22T15:09:40.527Z DEBUG modem << M2 value is : 23.3
2021-02-22T15:09:40.528Z DEBUG modem << M3 value is : -1
2021-02-22T15:09:40.529Z DEBUG modem << float value DDI is : 1819.00
2021-02-22T15:09:40.530Z DEBUG modem << float value DDI is : 23.30
2021-02-22T15:09:40.531Z DEBUG modem << Value of testval3 is : 1819.00
2021-02-22T15:09:48.507Z DEBUG modem << Time update:
2021-02-22T15:09:48.508Z DEBUG modem <<   Unix time ms: 1614006587765
2021-02-22T15:09:48.522Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:09:47
2021-02-22T15:09:48.523Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:10:48.504Z DEBUG modem << Time update:
2021-02-22T15:10:48.506Z DEBUG modem <<   Unix time ms: 1614006647765
2021-02-22T15:10:48.521Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:10:47
2021-02-22T15:10:48.522Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:11:48.502Z DEBUG modem << Time update:
2021-02-22T15:11:48.504Z DEBUG modem <<   Unix time ms: 1614006707765
2021-02-22T15:11:48.538Z DEBUG modem <<   Scheduler starts at local time: 2021-02-22 15:11:47
2021-02-22T15:11:48.539Z DEBUG modem <<   Time Value is :537048647 
2021-02-22T15:12:48.500Z DEBUG modem

Øyvind said:

Difficult for me to see where the issue starts / code stops sending?

This works well but sometimes as attached here, the process just stops and I just keep getting the timestamp value. The other processes just don't continue as they should be. I even did not see any error or fault message when I tried to debug it. 

Øyvind said:

Have you tried to debug e.g. removing one timer at a time or sensor reading?

I tried removing other processes and tried with just 1 sensor and sending the data through, but still the code process stops in the same way.

I am unable to understand how and what is causing the other processes to stop suddenly. 

Regards,

Adeel.

[Øyvind]

Hi Adeel,

I have looked through your code, and I find it difficult to follow. I can't see that you are checking the return code from various functions. E.g. at_cmd_write() has several return values that are important to check i.e. ENOEXEC:

 * @brief Function to send an AT command and receive response immediately
 *
 * This function should be used if the response from the modem should be
 * returned in a user supplied buffer. This function will return an empty buffer
 * if nothing is returned by the modem.
 *
 * @param cmd Pointer to null terminated AT command string
 * @param buf Buffer to put the response in. NULL pointer is allowed, see
 *            behaviour explanation for @p buf_len equals 0.
 * @param buf_len Length of response buffer. 0 length is allowed and will send
 *                the command, process the return code from the modem, but
 *                any returned data will be dropped.
 * @param state   Pointer to enum @em at_cmd_state variable that can hold
 *                the error state returned by the modem. If the return state
 *                is a CMS or CME errors will the error code be returned in the
 *                the function return code as a positive value. NULL pointer is
 *                allowed.
 *
 * @note It is allowed to use the same buffer for both, @p cmd and @p buf
 *       parameters in order to save RAM. The function will not modify @p buf
 *       contents until the entire @p cmd is sent.
 *
 * @retval 0 If command execution was successful (same as OK returned from
 *           modem). Error codes returned from the driver or by the socket are
 *           returned as negative values, CMS and CME errors are returned as
 *           positive values, the state parameter will indicate if it's a CME
 *           or CMS error. ERROR will return ENOEXEC (positve).
 *
 * @retval -ENOBUFS is returned if AT_CMD_RESPONSE_MAX_LEN is not large enough
 *         to hold the data returned from the modem.
 * @retval -ENOEXEC is returned if the modem returned ERROR.
 * @retval -EMSGSIZE is returned if the supplied buffer is to small or NULL.
 * @retval -EIO is returned if the function failed to send the command.
 * @retval -EHOSTDOWN is returned if the Modem library is shutdown.
 *
 */
int at_cmd_write(const char *const cmd,
		 char *buf,
		 size_t buf_len,
		 enum at_cmd_state *state);

Please add functionality to check return value from misc. functions. You can have a look at our applications, i.e. asset tracker, to see how this is done

#if defined(CONFIG_AT_CMD)
		err = at_cmd_write(modem_at_cmd_buff, modem_at_cmd_buff,
				sizeof(modem_at_cmd_buff), &state);

Kind regards,

Øyvind

[Adeel]

Hi Oyvind,

Thanks for your look at the code. I will implement the changes in my AT command handling , thanks for pointing it out. 

However, I have observed the issues with respect to my timers. They work well at the start, for sometime (cant really define because the code stops randomly) but just stops after that. I am trying to have this application to keep working infinitely.

In this respect, I wanted to ask if there is any limit to the number of timers I can use ? Also, Am I defining my timers through work handlers in a right way ? 

Could I use threads instead of work handlers just to try and see if that solves the issue ? What would be the exact difference between them ?

Regards,

Adeel

[Øyvind]

Hi Adeel, 

Adeel said:

ask if there is any limit to the number of timers I can use ?

Any number of timers can be defined (limited only by available RAM). Each timer is referenced by its memory address

Adeel said:

Am I defining my timers through work handlers in a right way ?

 Have a look at the Timers chapter in the Zephyr documentation. Have a look at how we define the timers in e.g. Asset Tracker v2 to verify.

Kind regards,
Øyvind

[Adeel]

Hi Oyvind,

Thanks for the link. I read it and I think I have defined it in the right way. 

Would it be a better idea to define threads rather than work handlers in this case ? I really did not understand the difference between the two. 

I read this documentation for this : 

https://docs.zephyrproject.org/latest/reference/kernel/threads/index.html

Regards,

Adeel.

[Øyvind]

Adeel said:

Would it be a better idea to define threads rather than work handlers in this case ?

 That is difficult for me to say, as this depends on the application. Have a look at the Motion module from nRF Desktop application, this uses threads to handle sensor data. Threads are part of the scheduler in the Zephyr RTOS, and may provide a better and more advanced algorithm for handling sensor data. 

Have a look at my colleague Simon's answers regarding Threads here and here.

Kind regards,
Øyvind