• State Not Answered
  • Replies 3 replies
  • Subscribers 93 subscribers
  • Views 427 views
  • Users 0 members are here
Attachments (2) Download All
Nordic Case Info
  • Case ID: 290561
Options

AZURE TWIN RESULT FAIL in LTE log

mexco

I added custom data to default asset_tracker_v2. The custom data is properly encoded and displayed in JSON format in LTE and acknowledged by Azure IOT, but it seems that the json packet is rejected at Azure server due to issue of TWIN fail. I could not understand it and do not know way to resolve it. The LTE log is shown in attached file.

Fullscreen gulzar_lte_log.txt Download 
2022-06-13T16:43:38.349Z DEBUG Application data folder: /home/keyssin/.config/nrfconnect/pc-nrfconnect-linkmonitor
2022-06-13T16:43:38.442Z INFO Using nrf-device-lib-js version: 0.4.11
2022-06-13T16:43:38.442Z INFO Using nrf-device-lib version: 0.11.8
2022-06-13T16:43:38.443Z INFO Using nrfjprog DLL version: 10.15.4
2022-06-13T16:43:38.443Z INFO Using JLink version: JLink_V7.62b
2022-06-13T16:43:38.461Z DEBUG App pc-nrfconnect-linkmonitor v2.0.1 official
2022-06-13T16:43:38.461Z DEBUG App path: /home/keyssin/.nrfconnect-apps/node_modules/pc-nrfconnect-linkmonitor
2022-06-13T16:43:38.461Z DEBUG nRFConnect 3.11.1, required by the app is (^3.8.0)
2022-06-13T16:43:38.461Z DEBUG nRFConnect path: /tmp/.mount_nrfconApX5uc/resources/app.asar
2022-06-13T16:43:38.461Z DEBUG HomeDir: /home/keyssin
2022-06-13T16:43:38.461Z DEBUG TmpDir: /tmp
2022-06-13T16:43:38.468Z INFO Installed JLink version does not match the provided version (V7.58b)
2022-06-13T16:43:55.846Z INFO Modem port is opened
2022-06-13T16:44:04.020Z DEBUG modem << [00:01:00.232,696] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:44:19.218Z DEBUG modem << +CSCON: 1
2022-06-13T16:44:19.227Z DEBUG modem << [00:01:15.438,812] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2022-06-13T16:44:20.840Z DEBUG modem << %CESQ: 48,2,17,2
2022-06-13T16:44:20.858Z DEBUG modem << [00:01:17.060,485] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m
2022-06-13T16:44:22.047Z DEBUG modem << %CESQ: 46,2,11,1
2022-06-13T16:44:22.060Z DEBUG modem << [00:01:18.267,578] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -94[0m
2022-06-13T16:44:23.482Z DEBUG modem << +CSCON: 0
2022-06-13T16:44:23.491Z DEBUG modem << [00:01:19.702,697] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Idle[0m
2022-06-13T16:44:24.206Z DEBUG modem << %CESQ: 47,2,16,2
2022-06-13T16:44:24.218Z DEBUG modem << [00:01:20.426,727] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:44:34.025Z DEBUG modem << [00:01:30.239,929] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:44:44.686Z DEBUG modem << %CESQ: 46,2,10,1
2022-06-13T16:44:44.696Z DEBUG modem << [00:01:40.907,562] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -94[0m
2022-06-13T16:44:47.245Z DEBUG modem << %CESQ: 47,2,15,2
2022-06-13T16:44:47.261Z DEBUG modem << [00:01:43.467,590] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:44:57.485Z DEBUG modem << %CESQ: 46,2,10,1
2022-06-13T16:44:57.501Z DEBUG modem << [00:01:53.707,763] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -94[0m
2022-06-13T16:45:04.031Z DEBUG modem << [00:02:00.247,161] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:45:04.263Z DEBUG modem << [00:02:00.480,529] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-06-13T16:45:04.272Z DEBUG modem << [00:02:00.487,243] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:45:04.281Z DEBUG modem << [00:02:00.494,201] [0m<inf> bms_module: BMS Thread enqueue is_app_module_event() in Event handler[0m
2022-06-13T16:45:04.287Z DEBUG modem << [00:02:00.503,723] [0m<dbg> main: data_get:  Main: Entered data_get()[0m
2022-06-13T16:45:04.295Z DEBUG modem << [00:02:00.510,345] [0m<dbg> main: data_get:  Modem_dyn data request through data_list[][0m
2022-06-13T16:45:04.303Z DEBUG modem << [00:02:00.518,646] [0m<dbg> main: data_get:  APP_BATT data request through data_list[][0m
2022-06-13T16:45:04.312Z DEBUG modem << [00:02:00.526,824] [0m<dbg> main: data_get:  APP_ENV data request through data_list[][0m
2022-06-13T16:45:04.320Z DEBUG modem << [00:02:00.534,973] [0m<dbg> main: data_get:  APP_NBR_CELLS data request through data_list[][0m
2022-06-13T16:45:04.329Z DEBUG modem << [00:02:00.543,579] [0m<dbg> main: data_get:  BMS data request through data_list[][0m
2022-06-13T16:45:04.336Z DEBUG modem << [00:02:00.551,300] [0m<dbg> main: data_get: Requested Data count = 4[0m
2022-06-13T16:45:04.343Z DEBUG modem << [00:02:00.557,891] [0m<dbg> main: data_get: The APP_DATA_COUNT_SIZE = 8[0m
2022-06-13T16:45:04.356Z DEBUG modem << [00:02:00.564,910] [0m<dbg> main: data_get: Data requested through APP_EVT_DATA_GET Event: MOD_DYN, BAT, NEIGHBOR_CELLS, BMS_DATA[0m
2022-06-13T16:45:04.367Z DEBUG modem << [00:02:00.576,873] [0m<dbg> main: data_get: Ready to submit APP_EVT_DATA_GET Event[0m
2022-06-13T16:45:04.374Z DEBUG modem << [00:02:00.584,777] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, NEIGHBOR_CELLS, BMS_DATA)[0m
2022-06-13T16:45:04.380Z DEBUG modem << [00:02:00.596,710] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:45:04.393Z DEBUG modem << [00:02:00.603,668] [0m<inf> bms_module: BMS Thread enqueue is_app_module_event() in Event handler[0m
2022-06-13T16:45:04.401Z DEBUG modem << [00:02:00.613,098] [0m<dbg> main: data_get: Submit APP_EVT_DATA_GET Event[0m
2022-06-13T16:45:04.405Z DEBUG modem << [00:02:00.620,117] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:45:04.413Z DEBUG modem << [00:02:00.627,258] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:45:04.419Z DEBUG modem << [00:02:00.634,307] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:45:04.427Z DEBUG modem << [00:02:00.641,387] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:45:04.434Z DEBUG modem << [00:02:00.648,498] [0m<inf> bms_module: BMS Thread: processing APP_EVT_DATA_GET in running State[0m
2022-06-13T16:45:04.443Z DEBUG modem << [00:02:00.657,562] [0m<inf> bms_module: BMS Thread: bms_run_event submit in Running State[0m
2022-06-13T16:45:04.452Z DEBUG modem << [00:02:00.666,137] [0m<inf> app_event_manager: BMS_DATA_READY - bms_id: 3902,PackV:3.6,SOC:48,Amp:2[0m
2022-06-13T16:45:04.462Z DEBUG modem << [00:02:00.675,506] [0m<inf> main: MAIN Thread: BMS Event detected and enqueued[0m
2022-06-13T16:45:04.470Z DEBUG modem << [00:02:00.682,952] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:45:04.476Z DEBUG modem << [00:02:00.690,124] [0m<dbg> modem_module: on_all_states:  modem_module: APP_EVT_DATA_GET processing[0m
2022-06-13T16:45:04.485Z DEBUG modem << [00:02:00.699,584] [0m<dbg> data_module: on_all_states:  data_module: APP_EVT_DATA_GET processing[0m
2022-06-13T16:45:04.495Z DEBUG modem << [00:02:00.708,801] [0m<dbg> data_module: on_all_states:  data module: processing BMS_DATA_READY Event[0m
2022-06-13T16:45:04.506Z DEBUG modem << [00:02:00.718,292] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_bms_buffer: Entry: 2 of 2 in bms buffer filled[0m
2022-06-13T16:45:04.514Z DEBUG modem << [00:02:00.729,431] [0m<dbg> data_module: on_all_states:  data module: populated bms_buf [0m
2022-06-13T16:45:04.525Z DEBUG modem << [00:02:00.737,884] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:45:04.534Z DEBUG modem << [00:02:00.747,741] [0m<dbg> data_module: on_all_states:  data module: processed BMS_DATA_READY Event[0m
2022-06-13T16:45:04.600Z DEBUG modem << [00:02:00.816,131] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-06-13T16:45:04.614Z DEBUG modem << [00:02:00.824,737] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_modem_dynamic_buffer: Entry: 2 of 2 in dynamic modem buffer filled[0m
2022-06-13T16:45:04.624Z DEBUG modem << [00:02:00.837,615] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:45:04.696Z DEBUG modem << [00:02:00.912,414] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-06-13T16:45:04.713Z DEBUG modem << [00:02:00.920,440] [0m<dbg> cloud_codec_ringbuffer: clou%NCELLMEAS: 0,"014A3E15","310260","3468",144,5035,286,56,24,118619,5035,430,52,18,0,5035,197,45,3,0,5035,143,41,-5,0,78319
2022-06-13T16:45:04.723Z DEBUG modem << [00:02:00.937,408] [0m<dbg> modem_module: lte_evt_handler: Neighbor cell measurements received[0m
2022-06-13T16:45:04.733Z DEBUG modem << [00:02:00.946,441] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-06-13T16:45:04.740Z DEBUG modem << d_codec_populate_bat_buffer: Entry: 2 of 2 in battery buffer filled[0m
2022-06-13T16:45:04.748Z DEBUG modem << [00:02:00.961,242] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:45:04.758Z DEBUG modem << [00:02:00.971,282] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-06-13T16:45:04.761Z DEBUG modem << [00:02:00.977,905] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:45:04.771Z DEBUG modem << [00:02:00.984,863] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-13T16:45:04.781Z DEBUG modem << [00:02:00.994,750] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:45:04.792Z DEBUG modem << [00:02:01.004,669] [0m<dbg> cloud_codec: cloud_codec_encode_neighbor_cells: Entered cloud_codec_encode_neighbour_cells[0m
2022-06-13T16:45:04.807Z DEBUG modem << Encoded message:
2022-06-13T16:45:04.810Z DEBUG modem << {
2022-06-13T16:45:04.812Z DEBUG modem << "mcc":310,
2022-06-13T16:45:04.813Z DEBUG modem << "mnc":260,
2022-06-13T16:45:04.817Z DEBUG modem << "cell":21642773,
2022-06-13T16:45:04.819Z DEBUG modem << "area":13416,
2022-06-13T16:45:04.823Z DEBUG modem << "earfcn":5035,
2022-06-13T16:45:04.824Z DEBUG modem << "adv":144,
2022-06-13T16:45:04.826Z DEBUG modem << "rsrp":-84,
2022-06-13T16:45:04.827Z DEBUG modem << "rsrq":-8,
2022-06-13T16:45:04.832Z DEBUG modem << "ts":1655138704168,
2022-06-13T16:45:04.834Z DEBUG modem << "nmr":[{
2022-06-13T16:45:04.835Z DEBUG modem << "earfcn":5035,
2022-06-13T16:45:04.837Z DEBUG modem << "cell":430,
2022-06-13T16:45:04.839Z DEBUG modem << "rsrp":-88,
2022-06-13T16:45:04.840Z DEBUG modem << "rsrq":-11
2022-06-13T16:45:04.841Z DEBUG modem << }, {
2022-06-13T16:45:04.845Z DEBUG modem << "earfcn":5035,
2022-06-13T16:45:04.846Z DEBUG modem << "cell":197,
2022-06-13T16:45:04.848Z DEBUG modem << "rsrp":-95,
2022-06-13T16:45:04.849Z DEBUG modem << "rsrq":-18
2022-06-13T16:45:04.850Z DEBUG modem << }, {
2022-06-13T16:45:04.851Z DEBUG modem << "earfcn":5035,
2022-06-13T16:45:04.852Z DEBUG modem << "cell":143,
2022-06-13T16:45:04.854Z DEBUG modem << "rsrp":-99,
2022-06-13T16:45:04.856Z DEBUG modem << "rsrq":-22
2022-06-13T16:45:04.861Z DEBUG modem << }]
2022-06-13T16:45:04.862Z DEBUG modem << }
2022-06-13T16:45:04.863Z DEBUG modem << [00:02:01.066,253] [0m<dbg> data_module: data_encode: Neighbor cell data encoded successfully[0m
2022-06-13T16:45:04.869Z DEBUG modem << [00:02:01.075,012] [0m<dbg> data_module: data_list_add_pending: Pending data added: 0x2001d618[0m
2022-06-13T16:45:04.875Z DEBUG modem << [00:02:01.083,923] [0m<inf> app_event_manager: DATA_EVT_NEIGHBOR_CELLS_DATA_SEND[0m
2022-06-13T16:45:04.880Z DEBUG modem << [00:02:01.091,766] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:45:04.885Z DEBUG modem << [00:02:01.098,724] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-13T16:45:04.892Z DEBUG modem << [00:02:01.108,734] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:45:04.899Z DEBUG modem << [00:02:01.115,783] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:45:04.908Z DEBUG modem << [00:02:01.122,894] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:45:04.915Z DEBUG modem << [00:02:01.130,035] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:45:04.924Z DEBUG modem << [00:02:01.137,115] [0m<dbg> cloud_codec: cloud_codec_encode_data: Entered cloud_codec_encode_data[0m
2022-06-13T16:45:04.934Z DEBUG modem << [00:02:01.146,270] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added btn data to encode_data[0m
2022-06-13T16:45:04.941Z DEBUG modem << [00:02:01.155,303] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mdm_sta data to encode_data[0m
2022-06-13T16:45:04.951Z DEBUG modem << [00:02:01.164,916] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mdm_dyn data to encode_data[0m
2022-06-13T16:45:04.960Z DEBUG modem << [00:02:01.174,285] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added gnss data to encode_data[0m
2022-06-13T16:45:04.969Z DEBUG modem << [00:02:01.183,746] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added bms data to encode_data[0m
2022-06-13T16:45:04.978Z DEBUG modem << [00:02:01.192,810] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added env data to encode_data[0m
2022-06-13T16:45:04.987Z DEBUG modem << [00:02:01.201,873] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mov data to encode_data[0m
2022-06-13T16:45:04.997Z DEBUG modem << [00:02:01.211,181] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added batt data to encode_data[0m
2022-06-13T16:45:05.004Z DEBUG modem << Encoded message:
2022-06-13T16:45:05.006Z DEBUG modem << {
2022-06-13T16:45:05.008Z DEBUG modem << "roam":{
2022-06-13T16:45:05.009Z DEBUG modem << "v":{
2022-06-13T16:45:05.016Z DEBUG modem << "rsrp":-94
2022-06-13T16:45:05.017Z DEBUG modem << },
2022-06-13T16:45:05.018Z DEBUG modem << "ts":1655138704038
2022-06-13T16:45:05.020Z DEBUG modem << },
2022-06-13T16:45:05.025Z DEBUG modem << "BMS Device":{
2022-06-13T16:45:05.026Z DEBUG modem << "bms_id":3902,
2022-06-13T16:45:05.026Z DEBUG modem << "Volt":3.6499999999999999,
2022-06-13T16:45:05.027Z DEBUG modem << "SOC":48,
2022-06-13T16:45:05.029Z DEBUG modem << "Current":2
2022-06-13T16:45:05.029Z DEBUG modem << },
2022-06-13T16:45:05.030Z DEBUG modem << "bat":{
2022-06-13T16:45:05.034Z DEBUG modem << "v":5066,
2022-06-13T16:45:05.036Z DEBUG modem << "ts":1655138704134
2022-06-13T16:45:05.037Z DEBUG modem << }
2022-06-13T16:45:05.038Z DEBUG modem << }
2022-06-13T16:45:05.043Z DEBUG modem << [00:02:01.247,802] [0m<dbg> data_module: data_encode: Data encoded successfully[0m
2022-06-13T16:45:05.049Z DEBUG modem << [00:02:01.255,371] [0m<dbg> data_module: data_list_add_pending: Pending data added: 0x2001d368[0m
2022-06-13T16:45:05.054Z DEBUG modem << [00:02:01.264,251] [0m<inf> app_event_manager: DATA_EVT_DATA_SEND[0m
2022-06-13T16:45:05.063Z DEBUG modem << [00:02:01.270,751] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:45:05.071Z DEBUG modem << [00:02:01.277,709] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-13T16:45:05.078Z DEBUG modem << [00:02:01.287,780] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data: Entered cloud_codec_encode_batch_data[0m
2022-06-13T16:45:05.085Z DEBUG modem << [00:02:01.298,065] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added mdm_sta data to encode_batch_data[0m
2022-06-13T16:45:05.095Z DEBUG modem << [00:02:01.308,532] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added mdm_dyn data to encode_batch_data[0m
2022-06-13T16:45:05.106Z DEBUG modem << [00:02:01.319,061] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added gnss data to encode_batch_data[0m
2022-06-13T16:45:05.116Z DEBUG modem << [00:02:01.329,345] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added bms data to encode_batch_data[0m
2022-06-13T16:45:05.126Z DEBUG modem << [00:02:01.339,447] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added sensor data to encode_batch_data[0m
2022-06-13T16:45:05.137Z DEBUG modem << [00:02:01.349,914] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added battery data to encode_batch_data[0m
2022-06-13T16:45:05.148Z DEBUG modem << [00:02:01.360,412] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added acc data to encode_batch_data[0m
2022-06-13T16:45:05.157Z DEBUG modem << [00:02:01.370,483] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data: No data to encode, JSON string empty...[0m
2022-06-13T16:45:05.167Z DEBUG modem << [00:02:01.380,859] [0m<dbg> data_module: data_encode: No batch data to encode, ringbuffers are empty[0m
2022-06-13T16:45:05.174Z DEBUG modem << [00:02:01.390,350] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:45:05.186Z DEBUG modem << [00:02:01.397,399] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:45:05.188Z DEBUG modem << [00:02:01.405,3+CSCON: 1
2022-06-13T16:45:05.197Z DEBUG modem << [00:02:01.407,806] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2022-06-13T16:45:05.205Z DEBUG modem << 95] [0m<dbg> cloud_module: neighbor_cells_data_send: Neighbor cell data sent, data pointer: 0x2001d618[0m
2022-06-13T16:45:05.214Z DEBUG modem << [00:02:01.424,835] [0m<inf> app_event_manager: CLOUD_EVT_DATA_ACK[0m
2022-06-13T16:45:05.220Z DEBUG modem << [00:02:01.432,159] [0m<dbg> data_module: data_ack: Pending data ACKed: 0x2001d618[0m
2022-06-13T16:45:05.229Z DEBUG modem << [00:02:01.440,948] [0m<dbg> cloud_module: data_send: Data sent, data pointer: 0x2001d368[0m
2022-06-13T16:45:05.237Z DEBUG modem << [00:02:01.449,371] [0m<inf> app_event_manager: CLOUD_EVT_DATA_ACK[0m
2022-06-13T16:45:05.243Z DEBUG modem << [00:02:01.456,329] [0m<dbg> data_module: data_ack: Pending data ACKed: 0x2001d368[0m
2022-06-13T16:45:06.293Z DEBUG modem << [00:02:02.505,462] [0m<dbg> azure_iot_hub_integration: azure_iot_hub_event_handler: AZURE_IOT_HUB_EVT_TWIN_RESULT_FAIL[0m
2022-06-13T16:45:10.201Z DEBUG modem << +CSCON: 0
2022-06-13T16:45:10.214Z DEBUG modem << [00:02:06.424,743] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Idle[0m
2022-06-13T16:45:10.286Z DEBUG modem << %CESQ: 47,2,4,0
2022-06-13T16:45:10.299Z DEBUG modem << [00:02:06.508,422] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:45:20.525Z DEBUG modem << %CESQ: 46,2,12,1
2022-06-13T16:45:20.544Z DEBUG modem << [00:02:16.749,176] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -94[0m
2022-06-13T16:45:34.036Z DEBUG modem << [00:02:30.254,394] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:46:04.043Z DEBUG modem << [00:03:00.261,627] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:46:34.047Z DEBUG modem << [00:03:30.268,890] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:46:45.003Z DEBUG modem << %CESQ: 47,2,7,1
2022-06-13T16:46:45.016Z DEBUG modem << [00:03:41.232,818] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:46:47.563Z DEBUG modem << %CESQ: 46,2,12,1
2022-06-13T16:46:47.575Z DEBUG modem << [00:03:43.792,572] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -94[0m
2022-06-13T16:46:55.242Z DEBUG modem << %CESQ: 46,2,7,1
2022-06-13T16:46:55.254Z DEBUG modem << [00:03:51.472,808] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -94[0m
2022-06-13T16:47:00.362Z DEBUG modem << %CESQ: 48,2,14,2
2022-06-13T16:47:00.375Z DEBUG modem << [00:03:56.593,109] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m
2022-06-13T16:47:02.923Z DEBUG modem << %CESQ: 47,2,8,1
2022-06-13T16:47:02.937Z DEBUG modem << [00:03:59.153,503] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:47:04.053Z DEBUG modem << [00:04:00.276,123] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:47:04.256Z DEBUG modem << [00:04:00.480,560] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-06-13T16:47:04.267Z DEBUG modem << [00:04:00.487,274] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:47:04.274Z DEBUG modem << [00:04:00.494,232] [0m<inf> bms_module: BMS Thread enqueue is_app_module_event() in Event handler[0m
2022-06-13T16:47:04.280Z DEBUG modem << [00:04:00.503,723] [0m<dbg> main: data_get:  Main: Entered data_get()[0m
2022-06-13T16:47:04.288Z DEBUG modem << [00:04:00.510,345] [0m<dbg> main: data_get:  Modem_dyn data request through data_list[][0m
2022-06-13T16:47:04.297Z DEBUG modem << [00:04:00.518,615] [0m<dbg> main: data_get:  APP_BATT data request through data_list[][0m
2022-06-13T16:47:04.305Z DEBUG modem << [00:04:00.526,794] [0m<dbg> main: data_get:  APP_ENV data request through data_list[][0m
2022-06-13T16:47:04.313Z DEBUG modem << [00:04:00.534,912] [0m<dbg> main: data_get:  APP_NBR_CELLS data request through data_list[][0m
2022-06-13T16:47:04.323Z DEBUG modem << [00:04:00.543,487] [0m<dbg> main: data_get:  BMS data request through data_list[][0m
2022-06-13T16:47:04.330Z DEBUG modem << [00:04:00.551,208] [0m<dbg> main: data_get: Requested Data count = 4[0m
2022-06-13T16:47:04.338Z DEBUG modem << [00:04:00.557,891] [0m<dbg> main: data_get: The APP_DATA_COUNT_SIZE = 8[0m
2022-06-13T16:47:04.346Z DEBUG modem << [00:04:00.564,849] [0m<dbg> main: data_get: Data requested through APP_EVT_DATA_GET Event: MOD_DYN, BAT, NEIGHBOR_CELLS, BMS_DATA[0m
2022-06-13T16:47:04.354Z DEBUG modem << [00:04:00.576,782] [0m<dbg> main: data_get: Ready to submit APP_EVT_DATA_GET Event[0m
2022-06-13T16:47:04.365Z DEBUG modem << [00:04:00.584,686] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, NEIGHBOR_CELLS, BMS_DATA)[0m
2022-06-13T16:47:04.373Z DEBUG modem << [00:04:00.596,618] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:47:04.383Z DEBUG modem << [00:04:00.603,576] [0m<inf> bms_module: BMS Thread enqueue is_app_module_event() in Event handler[0m
2022-06-13T16:47:04.392Z DEBUG modem << [00:04:00.613,006] [0m<dbg> main: data_get: Submit APP_EVT_DATA_GET Event[0m
2022-06-13T16:47:04.400Z DEBUG modem << [00:04:00.620,025] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:47:04.408Z DEBUG modem << [00:04:00.627,166] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:47:04.417Z DEBUG modem << [00:04:00.634,216] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:47:04.419Z DEBUG modem << [00:04:00.641,296] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:47:04.428Z DEBUG modem << [00:04:00.648,315] [0m<inf> bms_module: BMS Thread: processing APP_EVT_DATA_GET in running State[0m
2022-06-13T16:47:04.435Z DEBUG modem << [00:04:00.657,470] [0m<inf> bms_module: BMS Thread: bms_run_event submit in Running State[0m
2022-06-13T16:47:04.446Z DEBUG modem << [00:04:00.666,046] [0m<inf> app_event_manager: BMS_DATA_READY - bms_id: 3902,PackV:3.6,SOC:48,Amp:2[0m
2022-06-13T16:47:04.455Z DEBUG modem << [00:04:00.675,415] [0m<inf> main: MAIN Thread: BMS Event detected and enqueued[0m
2022-06-13T16:47:04.464Z DEBUG modem << [00:04:00.682,861] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:47:04.471Z DEBUG modem << [00:04:00.690,032] [0m<dbg> modem_module: on_all_states:  modem_module: APP_EVT_DATA_GET processing[0m
2022-06-13T16:47:04.478Z DEBUG modem << [00:04:00.699,493] [0m<dbg> data_module: on_all_states:  data_module: APP_EVT_DATA_GET processing[0m
2022-06-13T16:47:04.489Z DEBUG modem << [00:04:00.708,709] [0m<dbg> data_module: on_all_states:  data module: processing BMS_DATA_READY Event[0m
2022-06-13T16:47:04.498Z DEBUG modem << [00:04:00.718,200] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_bms_buffer: Entry: 0 of 2 in bms buffer filled[0m
2022-06-13T16:47:04.508Z DEBUG modem << [00:04:00.729,248] [0m<dbg> data_module: on_all_states:  data module: populated bms_buf [0m
2022-06-13T16:47:04.518Z DEBUG modem << [00:04:00.737,609] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:47:04.526Z DEBUG modem << [00:04:00.747,528] [0m<dbg> data_module: on_all_states:  data module: processed BMS_DATA_READY Event[0m
2022-06-13T16:47:04.593Z DEBUG modem << [00:04:00.815,826] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-06-13T16:47:04.606Z DEBUG modem << [00:04:00.824,493] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_modem_dynamic_buffer: Entry: 0 of 2 in dynamic modem buffer filled[0m
2022-06-13T16:47:04.616Z DEBUG modem << [00:04:00.837,432] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:47:04.682Z DEBUG modem << [00:04:00.906,127] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-06-13T16:47:04.695Z DEBUG modem << [00:04:00.9%NCELLMEAS: 0,"014A3E15","310260","3468",144,5035,286,54,21,238940,5035,430,51,16,0,5035,197,44,1,0,5035,143,44,-1,0,125039
2022-06-13T16:47:04.705Z DEBUG modem << [00:04:00.927,215] [0m<dbg> modem_module: lte_evt_handler: Neighbor cell measurements received[0m
2022-06-13T16:47:04.713Z DEBUG modem << [00:04:00.936,279] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-06-13T16:47:04.724Z DEBUG modem << 14,123] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_bat_buffer: Entry: 0 of 2 in battery buffer filled[0m
2022-06-13T16:47:04.734Z DEBUG modem << [00:04:00.955,078] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:47:04.742Z DEBUG modem << [00:04:00.965,026] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-06-13T16:47:04.749Z DEBUG modem << [00:04:00.971,649] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:47:04.757Z DEBUG modem << [00:04:00.978,607] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-13T16:47:04.769Z DEBUG modem << [00:04:00.988,494] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-13T16:47:04.778Z DEBUG modem << [00:04:00.998,382] [0m<dbg> cloud_codec: cloud_codec_encode_neighbor_cells: Entered cloud_codec_encode_neighbour_cells[0m
2022-06-13T16:47:04.793Z DEBUG modem << Encoded message:
2022-06-13T16:47:04.796Z DEBUG modem << {
2022-06-13T16:47:04.798Z DEBUG modem << "mcc":310,
2022-06-13T16:47:04.804Z DEBUG modem << "mnc":260,
2022-06-13T16:47:04.805Z DEBUG modem << "cell":21642773,
2022-06-13T16:47:04.806Z DEBUG modem << "area":13416,
2022-06-13T16:47:04.812Z DEBUG modem << "earfcn":5035,
2022-06-13T16:47:04.813Z DEBUG modem << "adv":144,
2022-06-13T16:47:04.814Z DEBUG modem << "rsrp":-86,
2022-06-13T16:47:04.815Z DEBUG modem << "rsrq":-9,
2022-06-13T16:47:04.816Z DEBUG modem << "ts":1655138824158,
2022-06-13T16:47:04.821Z DEBUG modem << "nmr":[{
2022-06-13T16:47:04.823Z DEBUG modem << "earfcn":5035,
2022-06-13T16:47:04.824Z DEBUG modem << "cell":430,
2022-06-13T16:47:04.825Z DEBUG modem << "rsrp":-89,
2022-06-13T16:47:04.826Z DEBUG modem << "rsrq":-12
2022-06-13T16:47:04.829Z DEBUG modem << }, {
2022-06-13T16:47:04.830Z DEBUG modem << "earfcn":5035,
2022-06-13T16:47:04.836Z DEBUG modem << "cell":197,
2022-06-13T16:47:04.837Z DEBUG modem << "rsrp":-96,
2022-06-13T16:47:04.838Z DEBUG modem << "rsrq":-19
2022-06-13T16:47:04.839Z DEBUG modem << }, {
2022-06-13T16:47:04.840Z DEBUG modem << "earfcn":5035,
2022-06-13T16:47:04.842Z DEBUG modem << "cell":143,
2022-06-13T16:47:04.843Z DEBUG modem << "rsrp":-96,
2022-06-13T16:47:04.844Z DEBUG modem << "rsrq":-20
2022-06-13T16:47:04.845Z DEBUG modem << }]
2022-06-13T16:47:04.846Z DEBUG modem << }
2022-06-13T16:47:04.853Z DEBUG modem << [00:04:01.059,814] [0m<dbg> data_module: data_encode: Neighbor cell data encoded successfully[0m
2022-06-13T16:47:04.854Z DEBUG modem << [00:04:01.068,542] [0m<dbg> data_module: data_list_add_pending: Pending data added: 0x2001d870[0m
2022-06-13T16:47:04.862Z DEBUG modem << [00:04:01.077,453] [0m<inf> app_event_manager: DATA_EVT_NEIGHBOR_CELLS_DATA_SEND[0m
2022-06-13T16:47:04.867Z DEBUG modem << [00:04:01.085,296] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:47:04.876Z DEBUG modem << [00:04:01.092,285] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-13T16:47:04.883Z DEBUG modem << [00:04:01.102,264] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:47:04.892Z DEBUG modem << [00:04:01.109,344] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:47:04.898Z DEBUG modem << [00:04:01.116,455] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:47:04.900Z DEBUG modem << [00:04:01.123,596] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:47:04.909Z DEBUG modem << [00:04:01.130,676] [0m<dbg> cloud_codec: cloud_codec_encode_data: Entered cloud_codec_encode_data[0m
2022-06-13T16:47:04.920Z DEBUG modem << [00:04:01.139,831] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added btn data to encode_data[0m
2022-06-13T16:47:04.928Z DEBUG modem << [00:04:01.148,864] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mdm_sta data to encode_data[0m
2022-06-13T16:47:04.937Z DEBUG modem << [00:04:01.158,477] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mdm_dyn data to encode_data[0m
2022-06-13T16:47:04.948Z DEBUG modem << [00:04:01.167,907] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added gnss data to encode_data[0m
2022-06-13T16:47:04.956Z DEBUG modem << [00:04:01.177,307] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added bms data to encode_data[0m
2022-06-13T16:47:04.965Z DEBUG modem << [00:04:01.186,370] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added env data to encode_data[0m
2022-06-13T16:47:04.976Z DEBUG modem << [00:04:01.195,434] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mov data to encode_data[0m
2022-06-13T16:47:04.983Z DEBUG modem << [00:04:01.204,742] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added batt data to encode_data[0m
2022-06-13T16:47:04.990Z DEBUG modem << Encoded message:
2022-06-13T16:47:04.991Z DEBUG modem << {
2022-06-13T16:47:04.992Z DEBUG modem << "roam":{
2022-06-13T16:47:04.999Z DEBUG modem << "v":{
2022-06-13T16:47:05.000Z DEBUG modem << "rsrp":-93
2022-06-13T16:47:05.007Z DEBUG modem << },
2022-06-13T16:47:05.008Z DEBUG modem << "ts":1655138824037
2022-06-13T16:47:05.009Z DEBUG modem << },
2022-06-13T16:47:05.010Z DEBUG modem << "BMS Device":{
2022-06-13T16:47:05.010Z DEBUG modem << "bms_id":3902,
2022-06-13T16:47:05.016Z DEBUG modem << "Volt":3.6499999999999999,
2022-06-13T16:47:05.018Z DEBUG modem << "SOC":48,
2022-06-13T16:47:05.018Z DEBUG modem << "Current":2
2022-06-13T16:47:05.019Z DEBUG modem << },
2022-06-13T16:47:05.020Z DEBUG modem << "bat":{
2022-06-13T16:47:05.022Z DEBUG modem << "v":5070,
2022-06-13T16:47:05.022Z DEBUG modem << "ts":1655138824128
2022-06-13T16:47:05.023Z DEBUG modem << }
2022-06-13T16:47:05.025Z DEBUG modem << }
2022-06-13T16:47:05.032Z DEBUG modem << [00:04:01.241,271] [0m<dbg> data_module: data_encode: Data encoded successfully[0m
2022-06-13T16:47:05.038Z DEBUG modem << [00:04:01.248,840] [0m<dbg> data_module: data_list_add_pending: Pending data added: 0x2001d428[0m
2022-06-13T16:47:05.040Z DEBUG modem << [00:04:01.257,720] [0m<inf> app_event_manager: DATA_EVT_DATA_SEND[0m
2022-06-13T16:47:05.047Z DEBUG modem << [00:04:01.264,282] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-13T16:47:05.055Z DEBUG modem << [00:04:01.271,240] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-13T16:47:05.061Z DEBUG modem << [00:04:01.281,311] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data: Entered cloud_codec_encode_batch_data[0m
2022-06-13T16:47:05.073Z DEBUG modem << [00:04:01.291,595] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added mdm_sta data to encode_batch_data[0m
2022-06-13T16:47:05.085Z DEBUG modem << [00:04:01.302,154] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added mdm_dyn data to encode_batch_data[0m
2022-06-13T16:47:05.094Z DEBUG modem << [00:04:01.312,683] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added gnss data to encode_batch_data[0m
2022-06-13T16:47:05.103Z DEBUG modem << [00:04:01.322,967] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added bms data to encode_batch_data[0m
2022-06-13T16:47:05.113Z DEBUG modem << [00:04:01.333,160] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added sensor data to encode_batch_data[0m
2022-06-13T16:47:05.124Z DEBUG modem << [00:04:01.343,627] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added battery data to encode_batch_data[0m
2022-06-13T16:47:05.140Z DEBUG modem << [00:04:01.354,125] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added acc data to encode_batch_data[0m
2022-06-13T16:47:05.147Z DEBUG modem << [00:04:01.364,288] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data: No data to encode, JSON string empty...[0m
2022-06-13T16:47:05.155Z DEBUG modem << [00:04:01.374,664] [0m<dbg> data_module: data_encode: No batch data to encode, ringbuffers are empty[0m
2022-06-13T16:47:05.165Z DEBUG modem << [00:04:01.384,246] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-13T16:47:05.174Z DEBUG modem << [00:04:01.391,296] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-13T16:47:05.182Z DEBUG modem << [00:04:01.399,444] [0m<dbg> cloud_module: neighbor%CESQ: 48,2,13,1
2022-06-13T16:47:05.191Z DEBUG modem << [00:04:01.405,853] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m
2022-06-13T16:47:05.192Z DEBUG modem << +CSCON: 1
2022-06-13T16:47:05.201Z DEBUG modem << [00:04:01.417,053] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2022-06-13T16:47:05.209Z DEBUG modem << _cells_data_send: Neighbor cell data sent, data pointer: 0x2001d870[0m
2022-06-13T16:47:05.216Z DEBUG modem << [00:04:01.430,908] [0m<inf> app_event_manager: CLOUD_EVT_DATA_ACK[0m
2022-06-13T16:47:05.226Z DEBUG modem << [00:04:01.438,201] [0m<dbg> data_module: data_ack: Pending data ACKed: 0x2001d870[0m
2022-06-13T16:47:05.234Z DEBUG modem << [00:04:01.446,960] [0m<dbg> cloud_module: data_send: Data sent, data pointer: 0x2001d428[0m
2022-06-13T16:47:05.241Z DEBUG modem << [00:04:01.455,383] [0m<inf> app_event_manager: CLOUD_EVT_DATA_ACK[0m
2022-06-13T16:47:05.251Z DEBUG modem << [00:04:01.462,280] [0m<dbg> data_module: data_ack: Pending data ACKed: 0x2001d428[0m
2022-06-13T16:47:06.301Z DEBUG modem << [00:04:02.521,453] [0m<dbg> azure_iot_hub_integration: azure_iot_hub_event_handler: AZURE_IOT_HUB_EVT_TWIN_RESULT_FAIL[0m
2022-06-13T16:47:10.119Z DEBUG modem << +CSCON: 0
2022-06-13T16:47:10.130Z DEBUG modem << [00:04:06.349,945] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Idle[0m
2022-06-13T16:47:23.403Z DEBUG modem << %CESQ: 47,2,6,0
2022-06-13T16:47:23.419Z DEBUG modem << [00:04:19.634,277] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:47:25.962Z DEBUG modem << %CESQ: 47,2,12,1
2022-06-13T16:47:25.975Z DEBUG modem << [00:04:22.194,244] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -93[0m
2022-06-13T16:47:34.058Z DEBUG modem << [00:04:30.283,386] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:47:43.882Z DEBUG modem << %CESQ: 49,2,17,2
2022-06-13T16:47:43.898Z DEBUG modem << [00:04:40.115,020] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -91[0m
2022-06-13T16:47:46.442Z DEBUG modem << %CESQ: 48,2,12,1
2022-06-13T16:47:46.454Z DEBUG modem << [00:04:42.675,384] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m
2022-06-13T16:48:04.063Z DEBUG modem << [00:05:00.290,618] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:48:19.721Z DEBUG modem << %CESQ: 49,2,17,2
2022-06-13T16:48:19.734Z DEBUG modem << [00:05:15.956,573] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -91[0m
2022-06-13T16:48:22.281Z DEBUG modem << %CESQ: 48,2,11,1
2022-06-13T16:48:22.296Z DEBUG modem << [00:05:18.517,150] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m
2022-06-13T16:48:34.069Z DEBUG modem << [00:05:30.297,851] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-13T16:48:45.321Z DEBUG modem << %CESQ: 48,2,17,2
2022-06-13T16:48:45.337Z DEBUG modem << [00:05:41.557,678] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m

Parents
  • 0

    Hi,

    Do you have any logs from Azure which could point to the cause of the problem?

    Which SDK version are you using?

    Can you enable CONFIG_CLOUD_INTEGRATION_LOG_LEVEL_DBG=y and CONFIG_AZURE_IOT_HUB_LOG_LEVEL_DBG=y and see if the extra logs gives us any hints?

    Best regards,

    Didrik

  • 0 in reply to Didrik Rokhaug

    Hi Didrik,

     here is the LTE log with above configuration enabled.

    Fullscreen 8623.gulzar_lte_log.txt Download 
    2022-06-15T07:55:58.710Z DEBUG Application data folder: /home/keyssin/.config/nrfconnect/pc-nrfconnect-linkmonitor
2022-06-15T07:55:58.810Z INFO Using nrf-device-lib-js version: 0.4.11
2022-06-15T07:55:58.810Z INFO Using nrf-device-lib version: 0.11.8
2022-06-15T07:55:58.811Z INFO Using nrfjprog DLL version: 10.15.4
2022-06-15T07:55:58.811Z INFO Using JLink version: JLink_V7.62b
2022-06-15T07:55:58.827Z DEBUG App pc-nrfconnect-linkmonitor v2.0.1 official
2022-06-15T07:55:58.828Z DEBUG App path: /home/keyssin/.nrfconnect-apps/node_modules/pc-nrfconnect-linkmonitor
2022-06-15T07:55:58.828Z DEBUG nRFConnect 3.11.1, required by the app is (^3.8.0)
2022-06-15T07:55:58.828Z DEBUG nRFConnect path: /tmp/.mount_nrfconHq599A/resources/app.asar
2022-06-15T07:55:58.828Z DEBUG HomeDir: /home/keyssin
2022-06-15T07:55:58.828Z DEBUG TmpDir: /tmp
2022-06-15T07:55:58.829Z INFO Installed JLink version does not match the provided version (V7.58b)
2022-06-15T07:56:04.876Z INFO Modem port is opened
2022-06-15T07:56:12.777Z DEBUG modem << [00:04:00.276,031] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2022-06-15T07:56:13.002Z DEBUG modem << [00:04:00.502,258] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-06-15T07:56:13.009Z DEBUG modem << [00:04:00.508,941] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-15T07:56:13.018Z DEBUG modem << [00:04:00.515,899] [0m<inf> bms_module: BMS Thread enqueue is_app_module_event() in Event handler[0m
2022-06-15T07:56:13.026Z DEBUG modem << [00:04:00.525,360] [0m<dbg> main: data_get:  Main: Entered data_get()[0m
2022-06-15T07:56:13.034Z DEBUG modem << [00:04:00.531,951] [0m<dbg> main: data_get:  Modem_dyn data request through data_list[][0m
2022-06-15T07:56:13.041Z DEBUG modem << [00:04:00.540,252] [0m<dbg> main: data_get:  APP_BATT data request through data_list[][0m
2022-06-15T07:56:13.049Z DEBUG modem << [00:04:00.548,431] [0m<dbg> main: data_get:  APP_ENV data request through data_list[][0m
2022-06-15T07:56:13.058Z DEBUG modem << [00:04:00.556,549] [0m<dbg> main: data_get:  APP_NBR_CELLS data request through data_list[][0m
2022-06-15T07:56:13.066Z DEBUG modem << [00:04:00.565,124] [0m<dbg> main: data_get:  BMS data request through data_list[][0m
2022-06-15T07:56:13.072Z DEBUG modem << [00:04:00.572,845] [0m<dbg> main: data_get: Requested Data count = 4[0m
2022-06-15T07:56:13.079Z DEBUG modem << [00:04:00.579,437] [0m<dbg> main: data_get: The APP_DATA_COUNT_SIZE = 8[0m
2022-06-15T07:56:13.091Z DEBUG modem << [00:04:00.586,364] [0m<dbg> main: data_get: Data requested through APP_EVT_DATA_GET Event: MOD_DYN, BAT, NEIGHBOR_CELLS, BMS_DATA[0m
2022-06-15T07:56:13.099Z DEBUG modem << [00:04:00.598,236] [0m<dbg> main: data_get: Ready to submit APP_EVT_DATA_GET Event[0m
2022-06-15T07:56:13.111Z DEBUG modem << [00:04:00.606,140] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, NEIGHBOR_CELLS, BMS_DATA)[0m
2022-06-15T07:56:13.118Z DEBUG modem << [00:04:00.618,041] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-15T07:56:13.127Z DEBUG modem << [00:04:00.624,969] [0m<inf> bms_module: BMS Thread enqueue is_app_module_event() in Event handler[0m
2022-06-15T07:56:13.134Z DEBUG modem << [00:04:00.634,399] [0m<dbg> main: data_get: Submit APP_EVT_DATA_GET Event[0m
2022-06-15T07:56:13.142Z DEBUG modem << [00:04:00.641,387] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-15T07:56:13.149Z DEBUG modem << [00:04:00.648,529] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-15T07:56:13.156Z DEBUG modem << [00:04:00.655,548] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-15T07:56:13.163Z DEBUG modem << [00:04:00.662,628] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-15T07:56:13.172Z DEBUG modem << [00:04:00.669,677] [0m<inf> bms_module: BMS Thread: processing APP_EVT_DATA_GET in running State[0m
2022-06-15T07:56:13.180Z DEBUG modem << [00:04:00.678,741] [0m<inf> bms_module: BMS Thread: bms_run_event submit in Running State[0m
2022-06-15T07:56:13.209Z DEBUG modem << [00:04:00.687,347] [0m<inf> app_event_manager: BMS_DATA_READY - bms_id: 3902,PackV:0.00,SOC:0,Amp:0,max_mv:0,max_Vcell:0,min_mv:0,min_cell:0, max_temp:0,max_Tcell:32,min_temp:0,min_Tcell:0,device_state:0,chg+_MOS:0,dischg_MOS:0,bms_life:0, total_cells:0,temp_sensors:0,charger_status:0,load_status:0,average_volt:0.000000[0m
2022-06-15T07:56:13.217Z DEBUG modem << [00:04:00.716,430] [0m<inf> main: MAIN Thread: BMS Event detected and enqueued[0m
2022-06-15T07:56:13.224Z DEBUG modem << [00:04:00.723,876] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-15T07:56:13.233Z DEBUG modem << [00:04:00.731,018] [0m<dbg> modem_module: on_all_states:  modem_module: APP_EVT_DATA_GET processing[0m
2022-06-15T07:56:13.243Z DEBUG modem << [00:04:00.740,478] [0m<dbg> data_module: on_all_states:  data_module: APP_EVT_DATA_GET processing[0m
2022-06-15T07:56:13.252Z DEBUG modem << [00:04:00.749,664] [0m<dbg> data_module: on_all_states:  data module: processing BMS_DATA_READY Event[0m
2022-06-15T07:56:13.263Z DEBUG modem << [00:04:00.759,155] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_bms_buffer: Entry: 0 of 2 in bms buffer filled[0m
2022-06-15T07:56:13.273Z DEBUG modem << [00:04:00.770,202] [0m<dbg> data_module: on_all_states:  data module: populated bms_buf [0m
2022-06-15T07:56:13.281Z DEBUG modem << [00:04:00.778,564] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-15T07:56:13.291Z DEBUG modem << [00:04:00.788,421] [0m<dbg> data_module: on_all_states:  data module: processed BMS_DATA_READY Event[0m
2022-06-15T07:56:13.358Z DEBUG modem << [00:04:00.856,842] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-06-15T07:56:13.371Z DEBUG modem << [00:04:00.865,447] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_modem_dynamic_buffer: Entry: 2 of 2 in dynamic modem buffer filled[0m
2022-06-15T07:56:13.381Z DEBUG modem << [00:04:00.878,295] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-15T07:56:13.455Z DEBUG modem << [00:04:00.952,941] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-06-15T07:56:13.471Z DEBUG modem << [00:04:00.960,998] [0m<dbg> cloud_codec_ringbuffer: clou%NCELLMEAS: 0,"01508417","310260","3468",336,5035,197,52,21,238734,5035,143,48,12,0,5035,430,47,10,0,5035,286,44,4,0,124514
2022-06-15T07:56:13.480Z DEBUG modem << [00:04:00.977,996] [0m<dbg> modem_module: lte_evt_handler: Neighbor cell measurements received[0m
2022-06-15T07:56:13.488Z DEBUG modem << [00:04:00.987,030] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-06-15T07:56:13.495Z DEBUG modem << d_codec_populate_bat_buffer: Entry: 0 of 2 in battery buffer filled[0m
2022-06-15T07:56:13.505Z DEBUG modem << [00:04:01.001,800] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-15T07:56:13.511Z DEBUG modem << [00:04:01.011,749] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-06-15T07:56:13.519Z DEBUG modem << [00:04:01.018,280] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-15T07:56:13.528Z DEBUG modem << [00:04:01.025,238] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-15T07:56:13.538Z DEBUG modem << [00:04:01.035,095] [0m<dbg> data_module: requested_data_status_set: processed requested_data_status_set()[0m
2022-06-15T07:56:13.549Z DEBUG modem << [00:04:01.045,013] [0m<dbg> cloud_codec: cloud_codec_encode_neighbor_cells: Entered cloud_codec_encode_neighbour_cells[0m
2022-06-15T07:56:13.564Z DEBUG modem << Encoded message:
2022-06-15T07:56:13.571Z DEBUG modem << {
2022-06-15T07:56:13.573Z DEBUG modem << "mcc":310,
2022-06-15T07:56:13.577Z DEBUG modem << "mnc":260,
2022-06-15T07:56:13.579Z DEBUG modem << "cell":22053911,
2022-06-15T07:56:13.580Z DEBUG modem << "area":13416,
2022-06-15T07:56:13.581Z DEBUG modem << "earfcn":5035,
2022-06-15T07:56:13.582Z DEBUG modem << "adv":336,
2022-06-15T07:56:13.582Z DEBUG modem << "rsrp":-88,
2022-06-15T07:56:13.586Z DEBUG modem << "rsrq":-9,
2022-06-15T07:56:13.587Z DEBUG modem << "ts":1655279772883,
2022-06-15T07:56:13.588Z DEBUG modem << "nmr":[{
2022-06-15T07:56:13.589Z DEBUG modem << "earfcn":5035,
2022-06-15T07:56:13.589Z DEBUG modem << "cell":143,
2022-06-15T07:56:13.593Z DEBUG modem << "rsrp":-92,
2022-06-15T07:56:13.594Z DEBUG modem << "rsrq":-14
2022-06-15T07:56:13.595Z DEBUG modem << }, {
2022-06-15T07:56:13.597Z DEBUG modem << "earfcn":5035,
2022-06-15T07:56:13.597Z DEBUG modem << "cell":430,
2022-06-15T07:56:13.598Z DEBUG modem << "rsrp":-93,
2022-06-15T07:56:13.603Z DEBUG modem << "rsrq":-15
2022-06-15T07:56:13.604Z DEBUG modem << }, {
2022-06-15T07:56:13.605Z DEBUG modem << "earfcn":5035,
2022-06-15T07:56:13.607Z DEBUG modem << "cell":286,
2022-06-15T07:56:13.608Z DEBUG modem << "rsrp":-96,
2022-06-15T07:56:13.609Z DEBUG modem << "rsrq":-18
2022-06-15T07:56:13.614Z DEBUG modem << }]
2022-06-15T07:56:13.615Z DEBUG modem << }
2022-06-15T07:56:13.616Z DEBUG modem << [00:04:01.106,353] [0m<dbg> data_module: data_encode: Neighbor cell data encoded successfully[0m
2022-06-15T07:56:13.620Z DEBUG modem << [00:04:01.115,051] [0m<dbg> data_module: data_list_add_pending: Pending data added: 0x2001d938[0m
2022-06-15T07:56:13.628Z DEBUG modem << [00:04:01.123,962] [0m<inf> app_event_manager: DATA_EVT_NEIGHBOR_CELLS_DATA_SEND[0m
2022-06-15T07:56:13.638Z DEBUG modem << [00:04:01.131,744] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-15T07:56:13.641Z DEBUG modem << [00:04:01.138,702] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-15T07:56:13.649Z DEBUG modem << [00:04:01.148,681] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-15T07:56:13.656Z DEBUG modem << [00:04:01.155,731] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-15T07:56:13.663Z DEBUG modem << [00:04:01.162,780] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-15T07:56:13.670Z DEBUG modem << [00:04:01.169,921] [0m<inf> bms_module: BMS Thread Enter in Running State[0m
2022-06-15T07:56:13.679Z DEBUG modem << [00:04:01.176,971] [0m<dbg> cloud_codec: cloud_codec_encode_data: Entered cloud_codec_encode_data[0m
2022-06-15T07:56:13.688Z DEBUG modem << [00:04:01.186,157] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added btn data to encode_data[0m
2022-06-15T07:56:13.698Z DEBUG modem << [00:04:01.195,159] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mdm_sta data to encode_data[0m
2022-06-15T07:56:13.707Z DEBUG modem << [00:04:01.204,772] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mdm_dyn data to encode_data[0m
2022-06-15T07:56:13.716Z DEBUG modem << [00:04:01.214,172] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added gnss data to encode_data[0m
2022-06-15T07:56:13.726Z DEBUG modem << [00:04:01.223,663] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added bms data to encode_data[0m
2022-06-15T07:56:13.735Z DEBUG modem << [00:04:01.232,696] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added env data to encode_data[0m
2022-06-15T07:56:13.744Z DEBUG modem << [00:04:01.241,760] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added mov data to encode_data[0m
2022-06-15T07:56:13.753Z DEBUG modem << [00:04:01.250,976] [0m<dbg> cloud_codec: cloud_codec_encode_data:  Added batt data to encode_data[0m
2022-06-15T07:56:13.760Z DEBUG modem << Encoded message:
2022-06-15T07:56:13.764Z DEBUG modem << {
2022-06-15T07:56:13.766Z DEBUG modem << "roam":{
2022-06-15T07:56:13.767Z DEBUG modem << "v":{
2022-06-15T07:56:13.768Z DEBUG modem << "rsrp":-92
2022-06-15T07:56:13.769Z DEBUG modem << },
2022-06-15T07:56:13.774Z DEBUG modem << "ts":1655279772752
2022-06-15T07:56:13.776Z DEBUG modem << },
2022-06-15T07:56:13.778Z DEBUG modem << "BMS Device":{
2022-06-15T07:56:13.779Z DEBUG modem << "packet":{
2022-06-15T07:56:13.786Z DEBUG modem << "bms_id":3902,
2022-06-15T07:56:13.788Z DEBUG modem << "Volt":0,
2022-06-15T07:56:13.789Z DEBUG modem << "SOC":0,
2022-06-15T07:56:13.790Z DEBUG modem << "Current":0
2022-06-15T07:56:13.791Z DEBUG modem << },
2022-06-15T07:56:13.791Z DEBUG modem << "ts":1655279772574
2022-06-15T07:56:13.792Z DEBUG modem << },
2022-06-15T07:56:13.792Z DEBUG modem << "bat":{
2022-06-15T07:56:13.793Z DEBUG modem << "v":5062,
2022-06-15T07:56:13.799Z DEBUG modem << "ts":1655279772849
2022-06-15T07:56:13.800Z DEBUG modem << }
2022-06-15T07:56:13.801Z DEBUG modem << }
2022-06-15T07:56:13.802Z DEBUG modem << [00:04:01.290,649] [0m<dbg> data_module: data_encode: Data encoded successfully[0m
2022-06-15T07:56:13.807Z DEBUG modem << [00:04:01.298,126] [0m<dbg> data_module: data_list_add_pending: Pending data added: 0x2001d858[0m
2022-06-15T07:56:13.813Z DEBUG modem << [00:04:01.307,006] [0m<inf> app_event_manager: DATA_EVT_DATA_SEND[0m
2022-06-15T07:56:13.821Z DEBUG modem << [00:04:01.313,507] [0m<inf> bms_module: BMS Thread Enter in event_handler[0m
2022-06-15T07:56:13.827Z DEBUG modem << [00:04:01.320,434] [0m<inf> bms_module: BMS Thread enqueue is_data_module_event() in Event handler[0m
2022-06-15T07:56:13.834Z DEBUG modem << [00:04:01.329,986] [0m<dbg> azure_iot_hub_topic: azure_iot_hub_prop_bag_str_get: Requested memory block size: 50[0m
2022-06-15T07:56:13.849Z DEBUG modem << [00:04:01.340,606] [0m<dbg> azure_iot_hub: azure_iot_hub_send: Publishing to topic: devices/351358811475034/messages/events/ncellmeas&%24.ct=application%2Fjson&%24.ce=utf-8[0m
2022-06-15T07:56:13.860Z DEBUG modem << [00:04:01.356,597] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data: Entered cloud_codec_encode_batch_data[0m
2022-06-15T07:56:13.870Z DEBUG modem << [00:04:01.366,912] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added mdm_sta data to encode_batch_data[0m
2022-06-15T07:56:13.881Z DEBUG modem << [00:04:01.377,380] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added mdm_dyn data to encode_batch_data[0m
2022-06-15T07:56:13.891Z DEBUG modem << [00:04:01.387,847] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added gnss data to encode_batch_data[0m
2022-06-15T07:56:13.901Z DEBUG modem << [00:04:01.398,010] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added bms data to encode_batch_data[0m
2022-06-15T07:56:13.911Z DEBUG modem << [00:04:01.408,142] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added sensor data to encode_batch_data[0m
2022-06-15T07:56:13.922Z DEBUG modem << [00:04:01.418,548] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added battery data to encode_batch_data[0m
2022-06-15T07:56:13.932Z DEBUG modem << [00:04:01.429,016] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data:  Added acc data to encode_batch_data[0m
2022-06-15T07:56:13.942Z DEBUG modem << [00:04:01.439,086] [0m<dbg> cloud_codec: cloud_codec_encode_batch_data: No data to encode, JSON string empty...[0m
2022-06-15T07:56:13.952Z DEBUG modem << [00:04:01.449,462] [0m<dbg> data_module: data_encode: No batch data to encode, ringbuffers are empty[0m
2022-06-15T07:56:13.960Z DEBUG modem << [00:04:01.458,953] [0m<inf> bms_module: New Message Received in BMS Thread[0m
2022-06-15T07:56:13.966Z DEBUG modem << [00:04:01.466,094] [0m<inf> bms_module: BMS Thread Enter in Runn+CSCON: 1
2022-06-15T07:56:13.973Z DEBUG modem << [00:04:01.472,991] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2022-06-15T07:56:13.975Z DEBUG modem << ing State[0m
2022-06-15T07:56:13.987Z DEBUG modem << [00:04:01.482,879] [0m<dbg> cloud_module: neighbor_cells_data_send: Neighbor cell data sent, data pointer: 0x2001d938[0m
2022-06-15T07:56:13.993Z DEBUG modem << [00:04:01.493,774] [0m<inf> app_event_manager: CLOUD_EVT_DATA_ACK[0m
2022-06-15T07:56:14.006Z DEBUG modem << [00:04:01.500,701] [0m<dbg> azure_iot_hub: azure_iot_hub_send: Publishing to topic: $iothub/twin/PATCH/properties/reported/?$rid=241500[0m
2022-06-15T07:56:14.017Z DEBUG modem << [00:04:01.513,488] [0m<dbg> data_module: data_ack: Pending data ACKed: 0x2001d938[0m
2022-06-15T07:56:14.024Z DEBUG modem << [00:04:01.522,521] [0m<dbg> cloud_module: data_send: Data sent, data pointer: 0x2001d858[0m
2022-06-15T07:56:14.030Z DEBUG modem << [00:04:01.530,822] [0m<inf> app_event_manager: CLOUD_EVT_DATA_ACK[0m
2022-06-15T07:56:14.038Z DEBUG modem << [00:04:01.537,719] [0m<dbg> data_module: data_ack: Pending data ACKed: 0x2001d858[0m
2022-06-15T07:56:15.026Z DEBUG modem << [00:04:02.522,399] [0m<dbg> azure_iot_hub: on_publish: MQTT_EVT_PUBLISH: id = 0, len = 299 [0m
2022-06-15T07:56:15.036Z DEBUG modem << [00:04:02.531,158] [0m<dbg> azure_iot_hub_topic: azure_iot_hub_topic_parse: Dynamic value: 400[0m
2022-06-15T07:56:15.043Z DEBUG modem << [00:04:02.540,039] [0m<dbg> azure_iot_hub_topic: get_next_prop_bag: Key: $rid[0m
2022-06-15T07:56:15.049Z DEBUG modem << [00:04:02.547,363] [0m<dbg> azure_iot_hub_topic: get_next_prop_bag: Value: 241500[0m
2022-06-15T07:56:15.056Z DEBUG modem << [00:04:02.555,145] [0m<dbg> azure_iot_hub: on_publish: Device twin data received[0m
2022-06-15T07:56:15.066Z DEBUG modem << [00:04:02.562,866] [0m<dbg> azure_iot_hub: device_twin_result_process: Bad twin request, malformed JSON?[0m
2022-06-15T07:56:15.077Z DEBUG modem << [00:04:02.572,601] [0m<dbg> azure_iot_hub_integration: azure_iot_hub_event_handler: AZURE_IOT_HUB_EVT_TWIN_RESULT_FAIL[0m
2022-06-15T07:56:19.437Z DEBUG modem << +CSCON: 0
2022-06-15T07:56:19.446Z DEBUG modem << [00:04:06.943,969] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Idle[0m

  • 0 in reply to mexco

    It looks like Azure is returning an error:

    2022-06-15T07:56:15.026Z DEBUG modem << [00:04:02.522,399] [0m<dbg> azure_iot_hub: on_publish: MQTT_EVT_PUBLISH: id = 0, len = 299 [0m
    2022-06-15T07:56:15.036Z DEBUG modem << [00:04:02.531,158] [0m<dbg> azure_iot_hub_topic: azure_iot_hub_topic_parse: Dynamic value: 400[0m
    2022-06-15T07:56:15.043Z DEBUG modem << [00:04:02.540,039] [0m<dbg> azure_iot_hub_topic: get_next_prop_bag: Key: $rid[0m
    2022-06-15T07:56:15.049Z DEBUG modem << [00:04:02.547,363] [0m<dbg> azure_iot_hub_topic: get_next_prop_bag: Value: 241500[0m
    2022-06-15T07:56:15.056Z DEBUG modem << [00:04:02.555,145] [0m<dbg> azure_iot_hub: on_publish: Device twin data received[0m
    2022-06-15T07:56:15.066Z DEBUG modem << [00:04:02.562,866] [0m<dbg> azure_iot_hub: device_twin_result_process: Bad twin request, malformed JSON?[0m
    2022-06-15T07:56:15.077Z DEBUG modem << [00:04:02.572,601] [0m<dbg> azure_iot_hub_integration: azure_iot_hub_event_handler: AZURE_IOT_HUB_EVT_TWIN_RESULT_FAIL[0m

    Do you have any logs in Azure that can give us any clue?

Reply
  • 0 in reply to mexco

    It looks like Azure is returning an error:

    2022-06-15T07:56:15.026Z DEBUG modem << [00:04:02.522,399] [0m<dbg> azure_iot_hub: on_publish: MQTT_EVT_PUBLISH: id = 0, len = 299 [0m
    2022-06-15T07:56:15.036Z DEBUG modem << [00:04:02.531,158] [0m<dbg> azure_iot_hub_topic: azure_iot_hub_topic_parse: Dynamic value: 400[0m
    2022-06-15T07:56:15.043Z DEBUG modem << [00:04:02.540,039] [0m<dbg> azure_iot_hub_topic: get_next_prop_bag: Key: $rid[0m
    2022-06-15T07:56:15.049Z DEBUG modem << [00:04:02.547,363] [0m<dbg> azure_iot_hub_topic: get_next_prop_bag: Value: 241500[0m
    2022-06-15T07:56:15.056Z DEBUG modem << [00:04:02.555,145] [0m<dbg> azure_iot_hub: on_publish: Device twin data received[0m
    2022-06-15T07:56:15.066Z DEBUG modem << [00:04:02.562,866] [0m<dbg> azure_iot_hub: device_twin_result_process: Bad twin request, malformed JSON?[0m
    2022-06-15T07:56:15.077Z DEBUG modem << [00:04:02.572,601] [0m<dbg> azure_iot_hub_integration: azure_iot_hub_event_handler: AZURE_IOT_HUB_EVT_TWIN_RESULT_FAIL[0m

    Do you have any logs in Azure that can give us any clue?

Children
No Data
Related
Terms of service