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 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 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 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 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 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 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 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 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 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 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 watchdog: primary_feed_worker: Feeding watchdog[0m 2022-06-13T16:45:04.263Z DEBUG modem << [00:02:00.480,529] [0m app_event_manager: APP_EVT_DATA_GET_ALL[0m 2022-06-13T16:45:04.272Z DEBUG modem << [00:02:00.487,243] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:45:04.281Z DEBUG modem << [00:02:00.494,201] [0m 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 main: data_get: Main: Entered data_get()[0m 2022-06-13T16:45:04.295Z DEBUG modem << [00:02:00.510,345] [0m 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 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 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 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 main: data_get: BMS data request through data_list[][0m 2022-06-13T16:45:04.336Z DEBUG modem << [00:02:00.551,300] [0m main: data_get: Requested Data count = 4[0m 2022-06-13T16:45:04.343Z DEBUG modem << [00:02:00.557,891] [0m main: data_get: The APP_DATA_COUNT_SIZE = 8[0m 2022-06-13T16:45:04.356Z DEBUG modem << [00:02:00.564,910] [0m 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 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 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 bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:45:04.393Z DEBUG modem << [00:02:00.603,668] [0m 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 main: data_get: Submit APP_EVT_DATA_GET Event[0m 2022-06-13T16:45:04.405Z DEBUG modem << [00:02:00.620,117] [0m bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:45:04.413Z DEBUG modem << [00:02:00.627,258] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:45:04.419Z DEBUG modem << [00:02:00.634,307] [0m bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:45:04.427Z DEBUG modem << [00:02:00.641,387] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:45:04.434Z DEBUG modem << [00:02:00.648,498] [0m 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 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 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 main: MAIN Thread: BMS Event detected and enqueued[0m 2022-06-13T16:45:04.470Z DEBUG modem << [00:02:00.682,952] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:45:04.476Z DEBUG modem << [00:02:00.690,124] [0m 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 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 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 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 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 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 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 app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m 2022-06-13T16:45:04.614Z DEBUG modem << [00:02:00.824,737] [0m 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 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 app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m 2022-06-13T16:45:04.713Z DEBUG modem << [00:02:00.920,440] [0m 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 modem_module: lte_evt_handler: Neighbor cell measurements received[0m 2022-06-13T16:45:04.733Z DEBUG modem << [00:02:00.946,441] [0m 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 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 app_event_manager: DATA_EVT_DATA_READY[0m 2022-06-13T16:45:04.761Z DEBUG modem << [00:02:00.977,905] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:45:04.771Z DEBUG modem << [00:02:00.984,863] [0m 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 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 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 data_module: data_encode: Neighbor cell data encoded successfully[0m 2022-06-13T16:45:04.869Z DEBUG modem << [00:02:01.075,012] [0m 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 app_event_manager: DATA_EVT_NEIGHBOR_CELLS_DATA_SEND[0m 2022-06-13T16:45:04.880Z DEBUG modem << [00:02:01.091,766] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:45:04.885Z DEBUG modem << [00:02:01.098,724] [0m 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 bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:45:04.899Z DEBUG modem << [00:02:01.115,783] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:45:04.908Z DEBUG modem << [00:02:01.122,894] [0m bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:45:04.915Z DEBUG modem << [00:02:01.130,035] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:45:04.924Z DEBUG modem << [00:02:01.137,115] [0m 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 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 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 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 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 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 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 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 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 data_module: data_encode: Data encoded successfully[0m 2022-06-13T16:45:05.049Z DEBUG modem << [00:02:01.255,371] [0m 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 app_event_manager: DATA_EVT_DATA_SEND[0m 2022-06-13T16:45:05.063Z DEBUG modem << [00:02:01.270,751] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:45:05.071Z DEBUG modem << [00:02:01.277,709] [0m 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 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 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 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 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 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 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 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 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 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 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 bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:45:05.186Z DEBUG modem << [00:02:01.397,399] [0m 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 modem_module: lte_evt_handler: RRC mode: Connected[0m 2022-06-13T16:45:05.205Z DEBUG modem << 95] [0m 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 app_event_manager: CLOUD_EVT_DATA_ACK[0m 2022-06-13T16:45:05.220Z DEBUG modem << [00:02:01.432,159] [0m data_module: data_ack: Pending data ACKed: 0x2001d618[0m 2022-06-13T16:45:05.229Z DEBUG modem << [00:02:01.440,948] [0m cloud_module: data_send: Data sent, data pointer: 0x2001d368[0m 2022-06-13T16:45:05.237Z DEBUG modem << [00:02:01.449,371] [0m app_event_manager: CLOUD_EVT_DATA_ACK[0m 2022-06-13T16:45:05.243Z DEBUG modem << [00:02:01.456,329] [0m data_module: data_ack: Pending data ACKed: 0x2001d368[0m 2022-06-13T16:45:06.293Z DEBUG modem << [00:02:02.505,462] [0m 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 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 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 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 watchdog: primary_feed_worker: Feeding watchdog[0m 2022-06-13T16:46:04.043Z DEBUG modem << [00:03:00.261,627] [0m watchdog: primary_feed_worker: Feeding watchdog[0m 2022-06-13T16:46:34.047Z DEBUG modem << [00:03:30.268,890] [0m 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 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 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 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 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 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 watchdog: primary_feed_worker: Feeding watchdog[0m 2022-06-13T16:47:04.256Z DEBUG modem << [00:04:00.480,560] [0m app_event_manager: APP_EVT_DATA_GET_ALL[0m 2022-06-13T16:47:04.267Z DEBUG modem << [00:04:00.487,274] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:47:04.274Z DEBUG modem << [00:04:00.494,232] [0m 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 main: data_get: Main: Entered data_get()[0m 2022-06-13T16:47:04.288Z DEBUG modem << [00:04:00.510,345] [0m 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 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 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 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 main: data_get: BMS data request through data_list[][0m 2022-06-13T16:47:04.330Z DEBUG modem << [00:04:00.551,208] [0m main: data_get: Requested Data count = 4[0m 2022-06-13T16:47:04.338Z DEBUG modem << [00:04:00.557,891] [0m main: data_get: The APP_DATA_COUNT_SIZE = 8[0m 2022-06-13T16:47:04.346Z DEBUG modem << [00:04:00.564,849] [0m 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 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 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 bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:47:04.383Z DEBUG modem << [00:04:00.603,576] [0m 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 main: data_get: Submit APP_EVT_DATA_GET Event[0m 2022-06-13T16:47:04.400Z DEBUG modem << [00:04:00.620,025] [0m bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:47:04.408Z DEBUG modem << [00:04:00.627,166] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:47:04.417Z DEBUG modem << [00:04:00.634,216] [0m bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:47:04.419Z DEBUG modem << [00:04:00.641,296] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:47:04.428Z DEBUG modem << [00:04:00.648,315] [0m 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 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 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 main: MAIN Thread: BMS Event detected and enqueued[0m 2022-06-13T16:47:04.464Z DEBUG modem << [00:04:00.682,861] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:47:04.471Z DEBUG modem << [00:04:00.690,032] [0m 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 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 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 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 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 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 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 app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m 2022-06-13T16:47:04.606Z DEBUG modem << [00:04:00.824,493] [0m 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 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 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 modem_module: lte_evt_handler: Neighbor cell measurements received[0m 2022-06-13T16:47:04.713Z DEBUG modem << [00:04:00.936,279] [0m app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m 2022-06-13T16:47:04.724Z DEBUG modem << 14,123] [0m 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 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 app_event_manager: DATA_EVT_DATA_READY[0m 2022-06-13T16:47:04.749Z DEBUG modem << [00:04:00.971,649] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:47:04.757Z DEBUG modem << [00:04:00.978,607] [0m 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 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 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 data_module: data_encode: Neighbor cell data encoded successfully[0m 2022-06-13T16:47:04.854Z DEBUG modem << [00:04:01.068,542] [0m 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 app_event_manager: DATA_EVT_NEIGHBOR_CELLS_DATA_SEND[0m 2022-06-13T16:47:04.867Z DEBUG modem << [00:04:01.085,296] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:47:04.876Z DEBUG modem << [00:04:01.092,285] [0m 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 bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:47:04.892Z DEBUG modem << [00:04:01.109,344] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:47:04.898Z DEBUG modem << [00:04:01.116,455] [0m bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:47:04.900Z DEBUG modem << [00:04:01.123,596] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:47:04.909Z DEBUG modem << [00:04:01.130,676] [0m 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 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 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 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 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 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 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 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 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 data_module: data_encode: Data encoded successfully[0m 2022-06-13T16:47:05.038Z DEBUG modem << [00:04:01.248,840] [0m 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 app_event_manager: DATA_EVT_DATA_SEND[0m 2022-06-13T16:47:05.047Z DEBUG modem << [00:04:01.264,282] [0m bms_module: BMS Thread Enter in event_handler[0m 2022-06-13T16:47:05.055Z DEBUG modem << [00:04:01.271,240] [0m 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 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 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 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 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 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 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 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 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 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 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 bms_module: New Message Received in BMS Thread[0m 2022-06-13T16:47:05.174Z DEBUG modem << [00:04:01.391,296] [0m bms_module: BMS Thread Enter in Running State[0m 2022-06-13T16:47:05.182Z DEBUG modem << [00:04:01.399,444] [0m cloud_module: neighbor%CESQ: 48,2,13,1 2022-06-13T16:47:05.191Z DEBUG modem << [00:04:01.405,853] [0m 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 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 app_event_manager: CLOUD_EVT_DATA_ACK[0m 2022-06-13T16:47:05.226Z DEBUG modem << [00:04:01.438,201] [0m data_module: data_ack: Pending data ACKed: 0x2001d870[0m 2022-06-13T16:47:05.234Z DEBUG modem << [00:04:01.446,960] [0m cloud_module: data_send: Data sent, data pointer: 0x2001d428[0m 2022-06-13T16:47:05.241Z DEBUG modem << [00:04:01.455,383] [0m app_event_manager: CLOUD_EVT_DATA_ACK[0m 2022-06-13T16:47:05.251Z DEBUG modem << [00:04:01.462,280] [0m data_module: data_ack: Pending data ACKed: 0x2001d428[0m 2022-06-13T16:47:06.301Z DEBUG modem << [00:04:02.521,453] [0m 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 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 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 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 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 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 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 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 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 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 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 modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -92[0m