Change asset tracker (thingy91) example to PASSIVE MODE

[hubert_11]

Hi all,

I'm new to the whole kconfig system, and I can't seem to change the Asset Tracker to passive mode. 

I've tried adding these lines to the prj.conf file:

CONFIG_DATA_DEVICE_MODE_PASSIVE=y

CONFIG_DATA_DEVICE_MODE_ACTIVE=n

and I changed the default of the DATA_DEVICE_MODE choice in Kconfig.data_module to DATA_DEVICE_MODE_PASSIVE

 

Which does seem to add the following line to the autogenerated autoconf.h file:

#define CONFIG_DATA_DEVICE_MODE_PASSIVE 1

But the asset tracker is still in active mode. I checked this by placing break points in the 

apply_config() function in sensor_module.c, which shows me that it is still configuring the system based on messages that turn it into active mode, so it is calling:

accelerometer_callback_set(false);

Where do these messages come from, and how do I change this?

[hubert_11]

I think I got it now, this is the log file from the link monitor (I assume that this is what you need?):

2023-02-16T14:06:34.988Z DEBUG Application data folder: /Users/huub/Library/Application Support/nrfconnect/pc-nrfconnect-linkmonitor
2023-02-16T14:06:35.167Z INFO Using nrf-device-lib-js version: 0.4.15
2023-02-16T14:06:35.167Z INFO Using nrf-device-lib version: 0.12.8
2023-02-16T14:06:35.167Z INFO Using nrfjprog DLL version: 10.16.0
2023-02-16T14:06:35.167Z INFO Using JLink version: JLink_V7.58b
2023-02-16T14:06:35.181Z DEBUG App pc-nrfconnect-linkmonitor v2.0.2 official
2023-02-16T14:06:35.181Z DEBUG App path: /Users/huub/.nrfconnect-apps/node_modules/pc-nrfconnect-linkmonitor
2023-02-16T14:06:35.181Z DEBUG nRFConnect 3.12.1, required by the app is (^3.12.0)
2023-02-16T14:06:35.182Z DEBUG nRFConnect path: /Applications/nRF Connect.app/Contents/Resources/app.asar
2023-02-16T14:06:35.182Z DEBUG HomeDir: /Users/huub
2023-02-16T14:06:35.182Z DEBUG TmpDir: /var/folders/8j/zrt67lcj3_d0tk2ryvygj6h00000gn/T/
2023-02-16T14:06:35.682Z INFO Installed JLink version does not match the provided version (V7.66a)
2023-02-16T14:07:08.160Z INFO Modem port is opened
2023-02-16T14:07:08.175Z DEBUG modem >> AT+CFUN?
2023-02-16T14:07:08.205Z DEBUG modem << +CFUN: 1
2023-02-16T14:07:08.209Z DEBUG modem << OK
2023-02-16T14:07:08.231Z DEBUG modem >> AT+CGSN=1
2023-02-16T14:07:08.241Z DEBUG modem << +CGSN: "352656109434111"
2023-02-16T14:07:08.246Z DEBUG modem << OK
2023-02-16T14:07:08.251Z DEBUG modem >> AT+CGMI
2023-02-16T14:07:08.266Z DEBUG modem << Nordic Semiconductor ASA
2023-02-16T14:07:08.270Z DEBUG modem << OK
2023-02-16T14:07:08.279Z DEBUG modem >> AT+CGMM
2023-02-16T14:07:08.289Z DEBUG modem << nRF9160-SICA
2023-02-16T14:07:08.294Z DEBUG modem << OK
2023-02-16T14:07:08.298Z DEBUG modem >> AT+CGMR
2023-02-16T14:07:08.307Z DEBUG modem << mfw_nrf9160_1.3.2
2023-02-16T14:07:08.309Z DEBUG modem << OK
2023-02-16T14:07:08.312Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.3.2] SerNr: 352656109434111
2023-02-16T14:07:08.313Z DEBUG modem >> AT+CEMODE?
2023-02-16T14:07:08.321Z DEBUG modem << +CEMODE: 2
2023-02-16T14:07:08.323Z DEBUG modem << OK
2023-02-16T14:07:08.345Z DEBUG modem >> AT%XCBAND=?
2023-02-16T14:07:08.361Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,18,19,20,25,26,28,66)
2023-02-16T14:07:08.364Z DEBUG modem << OK
2023-02-16T14:07:08.382Z DEBUG modem >> AT+CMEE?
2023-02-16T14:07:08.390Z DEBUG modem << +CMEE: 0
2023-02-16T14:07:08.393Z DEBUG modem << OK
2023-02-16T14:07:08.401Z DEBUG modem >> AT+CMEE=1
2023-02-16T14:07:08.425Z DEBUG modem << [00:00:48.938,598] [0m<dbg> location: method_gnss_print_pvt: Tracked saOK
2023-02-16T14:07:08.427Z DEBUG modem << tellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:08.430Z DEBUG modem << [00:00:49.894,226] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:10.388Z DEBUG modem << [00:00:50.892,974] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:10.391Z DEBUG modem << [00:00:51.814,056] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:10.393Z DEBUG modem << [00:00:51.814,086] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:07:12.320Z DEBUG modem << [00:00:52.815,002] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:12.325Z DEBUG modem << [00:00:52.815,032] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:07:12.327Z DEBUG modem << [00:00:53.733,825] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:12.329Z DEBUG modem << [00:00:53.733,886] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:07:13.445Z DEBUG modem << %CESQ: 46,2,12,1
2023-02-16T14:07:14.102Z INFO Modem port is closed
2023-02-16T14:07:17.417Z INFO Modem port is opened
2023-02-16T14:07:17.423Z DEBUG modem >> AT+CFUN?
2023-02-16T14:07:17.581Z DEBUG modem << *** Booting Zephyr OS build v3.2.99-ncs1 ***
2023-02-16T14:07:17.928Z DEBUG modem << [00:00:01.506,103] [0m<inf> at_host: UART check failed: 13. Dropping buffer and retrying.[0m
2023-02-16T14:07:17.930Z DEBUG modem << [00:00:01.516,357] [0m<dbg> watchdog: watchdog_timeout_install: Watchdog timeout installed. Timeout: 60[0m
2023-02-16T14:07:17.932Z DEBUG modem << [00:00:01.516,387] [0m<dbg> watchdog: watchdog_start: Watchdog started[0m
2023-02-16T14:07:17.934Z DEBUG modem << [00:00:01.516,418] [0m<dbg> watchdog: watchdog_feed_enable: Watchdog feed enabled. Timeout: 30000[0m
2023-02-16T14:07:17.936Z DEBUG modem << [00:00:01.516,815] [0m<inf> app_event_manager: APP_EVT_START[0m
2023-02-16T14:07:17.939Z DEBUG modem << [00:00:01.516,876] [0m<dbg> location_module: state_set: State: STATE_INIT[0m
2023-02-16T14:07:17.941Z DEBUG modem << [00:00:01.516,998] [0m<dbg> ui_module: state_set: State transition STATE_INIT --> STATE_RUNNING[0m
2023-02-16T14:07:17.943Z DEBUG modem << [00:00:01.517,028] [0m<dbg> ui_module: sub_state_set: Sub state: SUB_STATE_ACTIVE[0m
2023-02-16T14:07:17.946Z DEBUG modem << [00:00:01.517,059] [0m<dbg> ui_module: sub_sub_state_set: Sub state: SUB_SUB_STATE_LOCATION_INACTIVE[0m
2023-02-16T14:07:17.948Z DEBUG modem << [00:00:01.517,150] [0m<dbg> util_module: state_set: State: STATE_INIT[0m
2023-02-16T14:07:17.950Z DEBUG modem << [00:00:01.517,669] [0m<dbg> cloud_module: state_set: State: STATE_LTE_INIT[0m
2023-02-16T14:07:17.952Z DEBUG modem << [00:00:01.517,730] [0m<dbg> cloud_module: sub_state_set: Sub state: SUB_STATE_CLOUD_DISCONNECTED[0m
2023-02-16T14:07:17.962Z DEBUG modem << [00:00:01.517,883] [0m<dbg> data_module: state_set: State: STATE_CLOUD_DISCONNECTED[0m
2023-02-16T14:07:17.964Z DEBUG modem << [00:00:01.518,341] [0m<dbg> data_module: config_settings_handler: Device configuration loaded from flash[0m
2023-02-16T14:07:17.969Z DEBUG modem << [00:00:01.518,371] [0m<dbg> settings: settings_call_set_handler: set-value OK. key: data_module/config[0m
2023-02-16T14:07:17.974Z DEBUG modem << [00:00:01.518,493] [0m<dbg> date_time: date_time_core_register_handler: Registering handler 0x22859[0m
2023-02-16T14:07:17.976Z DEBUG modem << [00:00:01.518,554] [0m<dbg> data_module: config_print_all: Device mode: Active[0m
2023-02-16T14:07:17.980Z DEBUG modem << [00:00:01.518,554] [0m<dbg> data_module: config_print_all: Active wait timeout: 120[0m
2023-02-16T14:07:17.983Z DEBUG modem << [00:00:01.518,585] [0m<dbg> data_module: config_print_all: Movement resolution: 120[0m
2023-02-16T14:07:17.986Z DEBUG modem << [00:00:01.518,585] [0m<dbg> data_module: config_print_all: Movement timeout: 3600[0m
2023-02-16T14:07:17.989Z DEBUG modem << [00:00:01.518,615] [0m<dbg> data_module: config_print_all: Location timeout: 300[0m
2023-02-16T14:07:17.992Z DEBUG modem << [00:00:01.518,615] [0m<dbg> data_module: config_print_all: Accelerometer act threshold: 10.00[0m
2023-02-16T14:07:17.995Z DEBUG modem << [00:00:01.518,646] [0m<dbg> data_module: config_print_all: Accelerometer inact threshold: 5.00[0m
2023-02-16T14:07:18.006Z DEBUG modem << [00:00:01.518,676] [0m<dbg> data_module: config_print_all: Accelerometer inact timeout: 60.00[0m
2023-02-16T14:07:18.009Z DEBUG modem << [00:00:01.518,676] [0m<dbg> data_module: config_print_all: Requesting of neighbor cell data is enabled[0m
2023-02-16T14:07:18.011Z DEBUG modem << [00:00:01.518,707] [0m<dbg> data_module: config_print_all: Requesting of GNSS data is enabled[0m
2023-02-16T14:07:18.015Z DEBUG modem << [00:00:01.518,768] [0m<inf> app_event_manager: DATA_EVT_CONFIG_INIT[0m
2023-02-16T14:07:18.017Z DEBUG modem << [00:00:01.518,859] [0m<dbg> ui_module: sub_state_set: Sub state: SUB_STATE_ACTIVE[0m
2023-02-16T14:07:18.019Z DEBUG modem << [00:00:01.519,348] [0m<dbg> main: active_mode_timers_start_all: Device mode: Active[0m
2023-02-16T14:07:18.021Z DEBUG modem << [00:00:01.519,348] [0m<dbg> main: active_mode_timers_start_all: Start data sample timer: 120 seconds interval[0m
2023-02-16T14:07:18.022Z DEBUG modem << [00:00:01.519,439] [0m<dbg> main: state_set: State transition STATE_INIT --> STATE_RUNNING[0m
2023-02-16T14:07:18.024Z DEBUG modem << [00:00:01.519,470] [0m<dbg> main: sub_state_set: Sub state: SUB_STATE_ACTIVE_MODE[0m
2023-02-16T14:07:18.026Z DEBUG modem << [00:00:01.519,622] [0m<dbg> modem_module: state_set: State transition STATE_INIT --> STATE_DISCONNECTED[0m
2023-02-16T14:07:18.042Z DEBUG modem << [00:00:01.519,714] [0m<inf> app_event_manager: MODEM_EVT_INITIALIZED[0m
2023-02-16T14:07:18.044Z DEBUG modem << [00:00:01.520,355] [0m<dbg> location: location_core_init: Initialized 'GNSS' method successfully[0m
2023-02-16T14:07:18.046Z DEBUG modem << [00:00:01.520,446] [0m<dbg> location: location_core_init: Initialized 'Cellular' method successfully[0m
2023-02-16T14:07:18.049Z DEBUG modem << [00:00:01.520,477] [0m<dbg> location: location_init: Location library initialized[0m
2023-02-16T14:07:18.052Z DEBUG modem << [00:00:01.520,507] [0m<dbg> location_module: state_set: State transition STATE_INIT --> STATE_RUNNING[0m
2023-02-16T14:07:18.056Z DEBUG modem << [00:00:01.520,812] [0m<dbg> sensor_module: state_set: State: STATE_INIT[0m
2023-02-16T14:07:18.058Z DEBUG modem << [00:00:01.521,453] [0m<dbg> sensor_module: state_set: State transition STATE_INIT --> STATE_RUNNING[0m
2023-02-16T14:07:18.059Z DEBUG modem << [00:00:01.853,485] [0m<dbg> cloud_module: state_set: State transition STATE_LTE_INIT --> STATE_LTE_DISCONNECTED[0m
2023-02-16T14:07:18.061Z DEBUG modem << [00:00:01.860,656] [0m<dbg> nrf_cloud_transport: nct_client_id_set: client_id = nrf-352656109434111[0m
2023-02-16T14:07:18.089Z DEBUG modem << [00:00:01.861,114] [0m<dbg> nrf_cloud_transport: nct_topics_populate: accepted_topic: nrf-352656109434111/shadow/get/accepted[0m
2023-02-16T14:07:18.099Z DEBUG modem << [00:00:01.861,145] [0m<dbg> nrf_cloud_transport: nct_topics_populate: rejected_topic: $aws/things/nrf-352656109434111/shadow/get/rejected[0m
2023-02-16T14:07:18.102Z DEBUG modem << [00:00:01.861,206] [0m<dbg> nrf_cloud_transport: nct_topics_populate: update_delta_topic: $aws/things/nrf-352656109434111/shadow/update/delta[0m
2023-02-16T14:07:18.105Z DEBUG modem << [00:00:01.861,236] [0m<dbg> nrf_cloud_transport: nct_topics_populate: update_topic: $aws/things/nrf-352656109434111/shadow/update[0m
2023-02-16T14:07:18.108Z DEBUG modem << [00:00:01.861,297] [0m<dbg> nrf_cloud_transport: nct_topics_populate: shadow_get_topic: $aws/things/nrf-352656109434111/shadow/get[0m
2023-02-16T14:07:18.111Z DEBUG modem << [00:00:01.861,328] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 1[0m
2023-02-16T14:07:18.113Z DEBUG modem << [00:00:01.861,358] [0m<dbg> nrf_cloud_integration: cloud_wrap_init: ********************************************[0m
2023-02-16T14:07:18.115Z DEBUG modem << [00:00:01.861,389] [0m<dbg> nrf_cloud_integration: cloud_wrap_init:  The Asset Tracker v2 has started[0m
2023-02-16T14:07:18.117Z DEBUG modem << [00:00:01.861,419] [0m<dbg> nrf_cloud_integration: cloud_wrap_init:  Version:     0.0.0-development[0m
2023-02-16T14:07:18.134Z DEBUG modem << [00:00:01.861,480] [0m<dbg> nrf_cloud_integration: cloud_wrap_init:  Cloud:       nRF Cloud[0m
2023-02-16T14:07:18.137Z DEBUG modem << [00:00:01.861,511] [0m<dbg> nrf_cloud_integration: cloud_wrap_init:  Endpoint:    mqtt.nrfcloud.com[0m
2023-02-16T14:07:18.139Z DEBUG modem << [00:00:01.861,511] [0m<dbg> nrf_cloud_integration: cloud_wrap_init: ********************************************[0m
2023-02-16T14:07:18.140Z DEBUG modem << [00:00:01.861,541] [0m<dbg> qos: qos_init: Registering handler 0x1e7cd[0m
2023-02-16T14:07:19.164Z DEBUG modem << [00:00:02.021,545] [0m<dbg> modem_module: configure_low_power: PSM requested[0m
2023-02-16T14:07:19.168Z DEBUG modem << [00:00:02.066,467] [0m<inf> app_event_manager: MODEM_EVT_LTE_CONNECTING[0m
2023-02-16T14:07:19.174Z DEBUG modem << [00:00:02.066,680] [0m<dbg> ui_module: s%CESQ: 57,2,17,2
2023-02-16T14:07:19.176Z DEBUG modem << +CEREG: 2,"7EF5","00B6810B",7
2023-02-16T14:07:19.178Z DEBUG modem << tate_set: State transition STATE_RUNNING --> STATE_LTE_CONNECTING[0m
2023-02-16T14:07:19.180Z DEBUG modem << [00:00:02.067,535] [0m<dbg> modem_module: state_set: State transition STATE_DISCONNECTED --> STATE_CONNECTING[0m
2023-02-16T14:07:19.182Z DEBUG modem << [00:00:03.041,290] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -83[0m
2023-02-16T14:07:19.184Z DEBUG modem << [00:00:03.044,677] [0m<dbg> modem_module: lte_evt_handler: LTE cell changed: Cell ID: 11960587, Tracking area: 32501[0m
2023-02-16T14:07:19.186Z DEBUG modem << [00:00:03.044,830] [0m<inf> app_event_manager: MODEM_EVT_LTE_CELL_UPDATE[0m
2023-02-16T14:07:19.194Z DEBUG modem << +CSCON: 1
2023-02-16T14:07:20.184Z DEBUG modem << [00:00:03.102,539] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2023-02-16T14:07:20.251Z DEBUG modem << +CGEV: ME PDN ACT 0,0
2023-02-16T14:07:20.253Z DEBUG modem << +CNEC_ESM: 50,0
2023-02-16T14:07:20.255Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2023-02-16T14:07:20.256Z DEBUG modem << +CEREG: 1,"7EF5","00B6810B",7,,,"00001010","11000001"
2023-02-16T14:07:20.259Z DEBUG modem << %XTIME: "40","32206141700240","00"
2023-02-16T14:07:20.386Z DEBUG modem << [00:00:04.167,449] [0m<dbg> modem_module: pdn_event_handler: PDN_EVENT_ACTIVATED[0m
2023-02-16T14:07:20.388Z DEBUG modem << [00:00:04.169,006] [1;31m<err> modem_module: Event: PDP context 0, PDN type IPv4 only allowed[0m
2023-02-16T14:07:20.394Z DEBUG modem << [00:00:04.171,234] [0m<dbg> modem_module: lte_evt_handler: Modem domain event, type: Search done[0m
2023-02-16T14:07:20.396Z DEBUG modem << [00:00:04.176,574] [0m<dbg> modem_module: lte_evt_handler: Network registration status: Connected - home network[0m
2023-02-16T14:07:20.397Z DEBUG modem << [00:00:04.177,520] [0m<dbg> modem_module: lte_evt_handler: PSM parameter update: TAU: 1152000, Active time: 20[0m
2023-02-16T14:07:20.399Z DEBUG modem << [00:00:04.180,816] [0m<dbg> date_time: date_time_core_current_check: Date time never set[0m
2023-02-16T14:07:20.402Z DEBUG modem << [00:00:04.180,847] [0m<dbg> date_time: date_time_at_xtime_handler: Time obtained from cellular network (XTIME notification)[0m
2023-02-16T14:07:20.403Z DEBUG modem << [00:00:04.180,877] [0m<dbg> date_time: date_time_core_schedule_update: New periodic date time update in: 14400 seconds[0m
2023-02-16T14:07:20.405Z DEBUG modem << [00:00:04.180,938] [0m<dbg> date_time: date_time_core_store: System time updated: 2023-02-16 14:07:20[0m
2023-02-16T14:07:20.406Z DEBUG modem << [00:00:04.180,969] [0m<dbg> date_time: date_time_core_register_handler: Previously registered handler (nil) de-registered[0m
2023-02-16T14:07:20.408Z DEBUG modem << [00:00:04.181,060] [0m<inf> app_event_manager: MODEM_EVT_LTE_CONNECTED[0m
2023-02-16T14:07:20.417Z DEBUG modem << [00:00:04.181,304] [0m<dbg> ui_module: state_set: State transition STATE_LTE_CONNECTING --> STATE_RUNNING[0m
2023-02-16T14:07:20.419Z DEBUG modem << [00:00:04.181,854] [0m<inf> app_event_manager: MODEM_EVT_LTE_PSM_UPDATE[0m
2023-02-16T14:07:20.420Z DEBUG modem << [00:00:04.182,556] [0m<inf> app_event_manager: DATA_EVT_DATE_TIME_OBTAINED[0m
2023-02-16T14:07:20.539Z DEBUG modem << [00:00:04.311,401] [0m<dbg> modem_module: state_set: State transition STATE_CONNECTING --> STATE_CONNECTED[0m
2023-02-16T14:07:20.635Z DEBUG modem << [00:00:04.311,614] [0m<dbg> cloud_module: state_set: State transition STATE_LTE_DISCONNECTED --> STATE_LTE_CONNECTED[0m
2023-02-16T14:07:20.637Z DEBUG modem << [00:00:04.311,645] [0m<dbg> cloud_module: connect_cloud: Connecting to cloud[0m
2023-02-16T14:07:20.643Z DEBUG modem << [00:00:04.311,676] [0m<dbg> cloud_module: connect_cloud: Cloud connection establishment in progress[0m
2023-02-16T14:07:20.645Z DEBUG modem << [00:00:04.311,706] [0m<dbg> cloud_module: connect_cloud: New connection attempt in 32 seconds if not successful[0m
2023-02-16T14:07:20.646Z DEBUG modem << [00:00:04.311,859] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 1[0m
2023-02-16T14:07:20.648Z DEBUG modem << [00:00:04.311,889] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_TRANSPORT_CONNECTING[0m
2023-02-16T14:07:20.650Z DEBUG modem << [00:00:04.311,889] [0m<dbg> cloud_module: cloud_wrap_event_handler: CLOUD_WRAP_EVT_CONNECTING[0m
2023-02-16T14:07:20.653Z DEBUG modem << [00:00:04.311,981] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2023-02-16T14:07:20.655Z DEBUG modem << [00:00:04.312,255] [0m<dbg> ui_module: state_set: State transition STATE_RUNNING --> STATE_CLOUD_CONNECTING[0m
2023-02-16T14:07:20.656Z DEBUG modem << [00:00:04.452,239] [0m<dbg> nrf_cloud_transport: nct_connect: IPv4 address: 54.164.213.12[0m
2023-02-16T14:07:20.657Z DEBUG modem << [00:00:04.452,301] [0m<dbg> nrf_cloud_transport: nct_mqtt_connect: MQTT clean session flag: 1[0m
2023-02-16T14:07:23.948Z DEBUG modem << %CESQ: 54,2,12,1
2023-02-16T14:07:24.914Z DEBUG modem << [00:00:07.874,053] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -86[0m
2023-02-16T14:07:24.921Z DEBUG modem << [00:00:08.299,652] [0m<dbg> nrf_cloud_transport: nct_mqtt_connect: Using socket send timeout of 60 seconds[0m
2023-02-16T14:07:24.922Z DEBUG modem << [00:00:08.299,682] [0m<dbg> nrf_cloud: nrf_cloud_run: Cloud connection request sent[0m
2023-02-16T14:07:24.924Z DEBUG modem << [00:00:08.533,355] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_CONNACK: result 0[0m
2023-02-16T14:07:24.929Z DEBUG modem << [00:00:08.533,386] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 2[0m
2023-02-16T14:07:24.931Z DEBUG modem << [00:00:08.533,416] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_TRANSPORT_CONNECTED[0m
2023-02-16T14:07:24.932Z DEBUG modem << [00:00:08.533,447] [0m<dbg> nrf_cloud_transport: nct_cc_connect: nct_cc_connect[0m
2023-02-16T14:07:24.934Z DEBUG modem << [00:00:08.534,515] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 3[0m
2023-02-16T14:07:24.935Z DEBUG modem << [00:00:08.742,340] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_SUBACK: id = 100 result = 0[0m
2023-02-16T14:07:24.936Z DEBUG modem << [00:00:08.742,370] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 4[0m
2023-02-16T14:07:24.937Z DEBUG modem << [00:00:08.742,401] [0m<dbg> nrf_cloud_transport: nct_cc_send: mqtt_publish: id = 200 opcode = 0 len = 0[0m
2023-02-16T14:07:25.233Z DEBUG modem << Decoded message:
2023-02-16T14:07:25.235Z DEBUG modem << {
2023-02-16T14:07:25.237Z DEBUG modem << "desired":{
2023-02-16T14:07:25.238Z DEBUG modem << "pairing":{
2023-02-16T14:07:25.240Z DEBUG modem << "state":"paired",
2023-02-16T14:07:25.241Z DEBUG modem << "topics":{
2023-02-16T14:07:25.248Z DEBUG modem << "d2c":"prod/8ae9b938-a7aa-4425-aab1-c5d9f493a9b3/m/d/nrf-352656109434111/d2c",
2023-02-16T14:07:25.249Z DEBUG modem << "c2d":"prod/8ae9b938-a7aa-4425-aab1-c5d9f493a9b3/m/d/nrf-352656109434111/+/r"
2023-02-16T14:07:25.251Z DEBUG modem << }
2023-02-16T14:07:25.253Z DEBUG modem << },
2023-02-16T14:07:25.255Z DEBUG modem << "nrfcloud_mqtt_topic_prefix":"prod/8ae9b938-a7aa-4425-aab1-c5d9f493a9b3/"
2023-02-16T14:07:25.260Z DEBUG modem << },
2023-02-16T14:07:25.262Z DEBUG modem << "config":{
2023-02-16T14:07:25.263Z DEBUG modem << "activeMode":true,
2023-02-16T14:07:25.265Z DEBUG modem << "gnssTimeout":30,
2023-02-16T14:07:25.266Z DEBUG modem << "activeWaitTime":120,
2023-02-16T14:07:25.267Z DEBUG modem << "movementResolution":120,
2023-02-16T14:07:25.269Z DEBUG modem << "movementTimeout":3600,
2023-02-16T14:07:25.271Z DEBUG modem << "accThreshAct":10,
2023-02-16T14:07:25.272Z DEBUG modem << "accThreshInact":5,
2023-02-16T14:07:25.273Z DEBUG modem << "accTimeoutInact":60,
2023-02-16T14:07:25.276Z DEBUG modem << "nod":[],
2023-02-16T14:07:25.277Z DEBUG modem << "locationTimeout":300
2023-02-16T14:07:25.278Z DEBUG modem << }
2023-02-16T14:07:25.280Z DEBUG modem << }
2023-02-16T14:07:25.354Z DEBUG modem << Encoded message:
2023-02-16T14:07:25.356Z DEBUG modem << {
2023-02-16T14:07:25.359Z DEBUG modem << "state":{
2023-02-16T14:07:25.360Z DEBUG modem << "reported":{
2023-02-16T14:07:25.362Z DEBUG modem << "config":{
2023-02-16T14:07:25.364Z DEBUG modem << "activeMode":true,
2023-02-16T14:07:25.365Z DEBUG modem << "locationTimeout":300,
2023-02-16T14:07:25.366Z DEBUG modem << "activeWaitTime":120,
2023-02-16T14:07:25.367Z DEBUG modem << "movementResolution":120,
2023-02-16T14:07:25.374Z DEBUG modem << "movementTimeout":3600,
2023-02-16T14:07:25.376Z DEBUG modem << "accThreshAct":10,
2023-02-16T14:07:25.377Z DEBUG modem << "accThreshInact":5,
2023-02-16T14:07:25.380Z DEBUG modem << "accTimeoutInact":60,
2023-02-16T14:07:25.381Z DEBUG modem << "nod":[]
2023-02-16T14:07:25.383Z DEBUG modem << }
2023-02-16T14:07:25.386Z DEBUG modem << }
2023-02-16T14:07:25.387Z DEBUG modem << }
2023-02-16T14:07:25.388Z DEBUG modem << }
2023-02-16T14:07:25.389Z DEBUG modem << [00:00:08.840,332] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 5[0m
2023-02-16T14:07:25.390Z DEBUG modem << [00:00:08.886,413] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 200 result = 0[0m
2023-02-16T14:07:25.391Z DEBUG modem << [00:00:08.886,413] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 5[0m
2023-02-16T14:07:25.392Z DEBUG modem << [00:00:09.097,595] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBLISH: id = 8064 len = 487, topic = nrf-352656109434111/shadow/get/accepted[0m
2023-02-16T14:07:25.394Z DEBUG modem << [00:00:09.099,243] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 5[0m
2023-02-16T14:07:25.395Z DEBUG modem << [00:00:09.099,273] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_RX_DATA_SHADOW[0m
2023-02-16T14:07:25.396Z DEBUG modem << [00:00:09.099,273] [0m<dbg> cloud_module: cloud_wrap_event_handler: CLOUD_WRAP_EVT_DATA_RECEIVED[0m
2023-02-16T14:07:25.397Z DEBUG modem << [00:00:09.159,393] [0m<dbg> cloud_module: config_data_handle: Device configuration encoded[0m
2023-02-16T14:07:25.410Z DEBUG modem << [00:00:09.159,484] [0m<inf> app_event_manager: CLOUD_EVT_CONFIG_RECEIVED[0m
2023-02-16T14:07:25.419Z DEBUG modem << [00:00:09.163,299] [0m<dbg> nrf_cloud_transport: nct_dc_endpoint_set: nct_dc_endpoint_set[0m
2023-02-16T14:07:25.421Z DEBUG modem << [00:00:09.163,787] [0m<dbg> nrf_cloud_transport: nct_dc_endpoint_get: nct_dc_endpoint_get[0m
2023-02-16T14:07:25.422Z DEBUG modem << [00:00:09.165,252] [0m<dbg> nrf_cloud_transport: nct_cc_send: mqtt_publish: id = 301 opcode = 1 len = 361[0m
2023-02-16T14:07:25.424Z DEBUG modem << [00:00:09.165,405] [0m<dbg> data_module: new_config_handle: No new values in incoming device configuration update message[0m
2023-02-16T14:07:25.426Z DEBUG modem << [00:00:09.165,405] [0m<dbg> data_module: new_config_handle: Acknowledge currently applied configuration back to cloud[0m
2023-02-16T14:07:25.427Z DEBUG modem << [00:00:09.198,059] [0m<inf> app_event_manager: DATA_EVT_CONFIG_SEND[0m
2023-02-16T14:07:25.502Z DEBUG modem << [00:00:09.335,876] [0m<dbg> cloud_module: qos_event_handler: QOS_EVT_MESSAGE_NEW[0m
2023-02-16T14:07:25.504Z DEBUG modem << [00:00:09.335,937] [0m<inf> app_event_manager: CLOUD_EVT_DATA_SEND_QOS[0m
2023-02-16T14:07:25.506Z DEBUG modem << [00:00:09.336,151] [0m<dbg> qos: qos_message_print: Notified count: 1[0m
2023-02-16T14:07:25.507Z DEBUG modem << [00:00:09.336,181] [0m<dbg> qos: qos_message_print: Message heap_allocated: 1[0m
2023-02-16T14:07:25.508Z DEBUG modem << [00:00:09.336,181] [0m<dbg> qos: qos_message_print: Message ID: 15000[0m
2023-02-16T14:07:25.510Z DEBUG modem << [00:00:09.336,212] [0m<dbg> qos: qos_message_print: Message Buffer pointer: 0x2001c890[0m
2023-02-16T14:07:25.511Z DEBUG modem << [00:00:09.336,212] [0m<dbg> qos: qos_message_print: Message Buffer length: 211[0m
2023-02-16T14:07:25.513Z DEBUG modem << [00:00:09.336,242] [0m<dbg> qos: qos_message_print: Message Flags: 2[0m
2023-02-16T14:07:25.514Z DEBUG modem << [00:00:09.336,273] [0m<dbg> qos: qos_message_print: Message type: 6[0m
2023-02-16T14:07:25.515Z DEBUG modem << [00:00:09.336,273] [0m<dbg> nrf_cloud_transport: nct_cc_send: mqtt_publish: id = 15000 opcode = 1 len = 211[0m
2023-02-16T14:07:25.517Z DEBUG modem << [00:00:09.336,669] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 7[0m
2023-02-16T14:07:25.518Z DEBUG modem << [00:00:09.336,730] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_USER_ASSOCIATED[0m
2023-02-16T14:07:25.877Z DEBUG modem << [00:00:09.581,329] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 301 result = 0[0m
2023-02-16T14:07:25.879Z DEBUG modem << [00:00:09.581,359] [0m<dbg> nrf_cloud_transport: nct_dc_connect: nct_dc_connect[0m
2023-02-16T14:07:25.886Z DEBUG modem << [00:00:09.582,153] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 8[0m
2023-02-16T14:07:25.888Z DEBUG modem << [00:00:09.704,589] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 15000 result = 0[0m
2023-02-16T14:07:25.889Z DEBUG modem << [00:00:09.704,620] [0m<dbg> nrf_cloud_fsm: cc_tx_ack_handler: Data ACK for user tag: 15000[0m
2023-02-16T14:07:25.891Z DEBUG modem << [00:00:09.704,650] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 8[0m
2023-02-16T14:07:25.893Z DEBUG modem << [00:00:09.704,650] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_SENSOR_DATA_ACK[0m
2023-02-16T14:07:25.895Z DEBUG modem << [00:00:09.704,681] [0m<dbg> cloud_module: cloud_wrap_event_handler: CLOUD_WRAP_EVT_DATA_ACK: 15000[0m
2023-02-16T14:07:25.896Z DEBUG modem << [00:00:09.704,711] [0m<dbg> cloud_module: qos_event_handler: QOS_EVT_MESSAGE_REMOVED_FROM_LIST[0m
2023-02-16T14:07:25.898Z DEBUG modem << [00:00:09.704,711] [0m<dbg> cloud_module: qos_event_handler: Freeing pointer: 0x2001c890[0m
2023-02-16T14:07:25.899Z DEBUG modem << [00:00:09.704,742] [0m<dbg> qos: qos_message_remove: QoS list is empty, cancel ongoing delayed work[0m
2023-02-16T14:07:26.050Z DEBUG modem << [00:00:09.853,454] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_SUBACK: id = 101 result = 0[0m
2023-02-16T14:07:26.053Z DEBUG modem << [00:00:09.854,400] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 9[0m
2023-02-16T14:07:26.055Z DEBUG modem << [00:00:09.854,431] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_READY[0m
2023-02-16T14:07:26.056Z DEBUG modem << [00:00:09.855,316] [0m<dbg> nrf_cloud_transport: nct_cc_send: mqtt_publish: id = 1000 opcode = 1 len = 134[0m
2023-02-16T14:07:26.058Z DEBUG modem << [00:00:09.856,323] [0m<dbg> nrf_cloud_integration: send_service_info: nRF Cloud service info sent[0m
2023-02-16T14:07:26.059Z DEBUG modem << [00:00:09.856,323] [0m<dbg> cloud_module: cloud_wrap_event_handler: CLOUD_WRAP_EVT_CONNECTED[0m
2023-02-16T14:07:26.060Z DEBUG modem << [00:00:09.856,414] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTED[0m
2023-02-16T14:07:26.061Z DEBUG modem << [00:00:09.856,689] [0m<dbg> ui_module: state_set: State transition STATE_CLOUD_CONNECTING --> STATE_RUNNING[0m
2023-02-16T14:07:26.062Z DEBUG modem << [00:00:09.857,391] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, ENV, MOD_STAT, LOCATION)[0m
2023-02-16T14:07:26.063Z DEBUG modem << [00:00:09.857,452] [0m<dbg> location_module: search_start: Requesting location...[0m
2023-02-16T14:07:26.064Z DEBUG modem << [00:00:09.857,482] [0m<dbg> location: location_core_config_log: Location configuration:[0m
2023-02-16T14:07:26.075Z DEBUG modem << [00:00:09.857,513] [0m<dbg> location: location_core_config_log:   Methods count: 2[0m
2023-02-16T14:07:26.076Z DEBUG modem << [00:00:09.857,543] [0m<dbg> location: location_core_config_log:   Interval: 0[0m
2023-02-16T14:07:26.225Z DEBUG modem << [00:00:09.857,543] [0m<dbg> location: location_core_config_log:   Timeout: 300000ms[0m
2023-02-16T14:07:26.227Z DEBUG modem << [00:00:09.857,574] [0m<dbg> location: location_core_config_log:   Mode: 0[0m
2023-02-16T14:07:26.236Z DEBUG modem << [00:00:09.857,574] [0m<dbg> location: location_core_config_log:   List of methods:[0m
2023-02-16T14:07:26.237Z DEBUG modem << [00:00:09.857,604] [0m<dbg> location: location_core_config_log:     Method #0[0m
2023-02-16T14:07:26.239Z DEBUG modem << [00:00:09.857,635] [0m<dbg> location: location_core_config_log:       Method type: GNSS (2)[0m
2023-02-16T14:07:26.240Z DEBUG modem << [00:00:09.857,666] [0m<dbg> location: location_core_config_log:       Timeout: 90000ms[0m
2023-02-16T14:07:26.242Z DEBUG modem << [00:00:09.857,696] [0m<dbg> location: location_core_config_log:       Accuracy: normal (1)[0m
2023-02-16T14:07:26.245Z DEBUG modem << [00:00:09.857,696] [0m<dbg> location: location_core_config_log:     Method #1[0m
2023-02-16T14:07:26.247Z DEBUG modem << [00:00:09.857,757] [0m<dbg> location: location_core_config_log:       Method type: Cellular (1)[0m
2023-02-16T14:07:26.248Z DEBUG modem << [00:00:09.857,757] [0m<dbg> location: location_core_config_log:       Timeout: 11000ms[0m
2023-02-16T14:07:26.250Z DEBUG modem << [00:00:09.857,788] [0m<dbg> location: location_core_config_log:       Service: Any (0)[0m
2023-02-16T14:07:26.251Z DEBUG modem << [00:00:09.857,849] [0m<dbg> location: location_core_location_get_pos: Requesting location with 'GNSS' method[0m
2023-02-16T14:07:26.262Z DEBUG modem << [00:00:09.857,940] [0m<dbg> location: location_core_location_get_pos: Starting request timer with timeout=300000[0m
2023-02-16T14:07:26.263Z DEBUG modem << [00:00:09.858,489] [0m<inf> app_event_manager: LOCATION_MODULE_EVT_ACTIVE[0m
2023-02-16T14:07:26.265Z DEBUG modem << [00:00:09.858,612] [0m<dbg> ui_module: sub_sub_state_set: Sub state transition SUB_SUB_STATE_LOCATION_INACTIVE --> SUB_SUB_STATE_LOCATION_ACTIVE[0m
2023-02-16T14:07:26.267Z DEBUG modem << [00:00:09.859,008] [0m<dbg> location_module: sub_state_set: Sub state transition SUB_STATE_IDLE --> SUB_STATE_SEARCH[0m
2023-02-16T14:07:26.268Z DEBUG modem << [00:00:09.859,466] [0m<dbg> cloud_module: sub_state_set: Sub state transition SUB_STATE_CLOUD_DISCONNECTED --> SUB_STATE_CLOUD_CONNECTED[0m
2023-02-16T14:07:26.269Z DEBUG modem << [00:00:09.859,802] [0m<dbg> data_module: state_set: State transition STATE_CLOUD_DISCONNECTED --> STATE_CLOUD_CONNECTED[0m
2023-02-16T14:07:26.270Z DEBUG modem << [00:00:09.862,213] [0m<dbg> location: method_gnss_request_assistance: A-GPS request from modem (ephe: 0xffffffff alm: 0xffffffff flags: 0x3b)[0m
2023-02-16T14:07:27.313Z DEBUG modem << [00:00:10.152,557] [0m<dbg> location: method_gnss_allowed_to_start: Waiting for the RRC connection release...[0m
2023-02-16T14:07:27.324Z DEBUG modem << [00:00:10.155,151] [0m<inf> app_event_manager: SENSOR_EVT_ENVIRONMENTAL_DATA_READY[0m
2023-02-16T14:07:27.325Z DEBUG modem << [00:00:10.155,761] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_sensor_buffer: Entry: 1 of 9 in sensor buffer filled[0m
2023-02-16T14:07:27.327Z DEBUG modem << [00:00:10.174,530] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 1000 result = 0[0m
2023-02-16T14:07:27.330Z DEBUG modem << [00:00:10.183,746] [0m<inf> app_event_manager: MODEM_EVT_MODEM_STATIC_DATA_READY[0m
2023-02-16T14:07:27.332Z DEBUG modem << [00:00:10.216,064] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2023-02-16T14:07:27.334Z DEBUG modem << [00:00:10.217,041] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_modem_dynamic_buffer: Entry: 1 of 2 in dynamic modem buffer filled[0m
2023-02-16T14:07:27.336Z DEBUG modem << [00:00:10.247,985] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2023-02-16T14:07:27.339Z DEBUG modem << [00:00:10.248,840] [0m<dbg> cloud_codec_ringbuffer: cloud_codec_populate_bat_buffer: Entry: 1 of 2 in battery buffer filled[0m
2023-02-16T14:07:38.455Z DEBUG modem << +CSCON: 0
2023-02-16T14:07:38.538Z DEBUG modem << +CSCON: 1
2023-02-16T14:07:39.441Z DEBUG modem << [00:00:22.381,866] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Idle[0m
2023-02-16T14:07:39.443Z DEBUG modem << [00:00:22.384,643] [0m<dbg> location: location_core_timer_start: Starting timer with timeout=90000[0m
2023-02-16T14:07:39.445Z DEBUG modem << [00:00:22.384,704] [0m<dbg> location: location_core_event_cb_agps_request: Request A-GPS data from application: ephe 0xffffffff, alm 0xffffffff, data_flags 0x3b[0m
2023-02-16T14:07:39.447Z DEBUG modem << [00:00:22.384,704] [0m<dbg> location_module: location_event_handler: Requested A-GPS data[0m
2023-02-16T14:07:39.449Z DEBUG modem << [00:00:22.384,796] [0m<inf> app_event_manager: LOCATION_MODULE_EVT_AGPS_NEEDED[0m
2023-02-16T14:07:39.451Z DEBUG modem << [00:00:22.426,208] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:39.452Z DEBUG modem << [00:00:22.430,816] [0m<dbg> nrf_cloud_codec: json_send_to_cloud: Created request: {"appId":"AGPS","messageType":"DATA","data":{"mcc":204,"mnc":8,"tac":32501,"eci":11960587,"rsrp":-140,"types":[1,2,3,4,6,7,8,9]}}[0m
2023-02-16T14:07:39.454Z DEBUG modem << [00:00:22.432,556] [0m<dbg> nrf_cloud_codec: json_send_to_cloud: Request sent to cloud[0m
2023-02-16T14:07:39.456Z DEBUG modem << [00:00:22.432,739] [0m<dbg> data_module: agps_request_handle: A-GPS request sent[0m
2023-02-16T14:07:39.474Z DEBUG modem << [00:00:22.464,599] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2023-02-16T14:07:39.476Z DEBUG modem << [00:00:23.272,064] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 1001 result = 0[0m
2023-02-16T14:07:40.590Z DEBUG modem << [00:00:23.443,939] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:40.593Z DEBUG modem << [00:00:23.703,277] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBLISH: id = 1 len = 1883, topic = prod/8ae9b938-a7aa-4425-aab1-c5d9f493a9b3/m/d/nrf-352656109434111/agps/r[0m
2023-02-16T14:07:40.595Z DEBUG modem << [00:00:23.802,551] [0m<dbg> nrf_cloud_fsm: agps_process: A-GPS data processed[0m
2023-02-16T14:07:40.597Z DEBUG modem << [00:00:23.805,267] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBLISH: id = 2 len = 1179, topic = prod/8ae9b938-a7aa-4425-aab1-c5d9f493a9b3/m/d/nrf-352656109434111/agps/r[0m
2023-02-16T14:07:40.599Z DEBUG modem << [00:00:23.864,349] [0m<dbg> nrf_cloud_fsm: agps_process: A-GPS data processed[0m
2023-02-16T14:07:40.601Z DEBUG modem << [00:00:24.444,152] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:42.399Z DEBUG modem << %CESQ: 55,2,17,2
2023-02-16T14:07:42.552Z DEBUG modem << [00:00:25.444,366] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:42.554Z DEBUG modem << [00:00:26.325,683] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -85[0m
2023-02-16T14:07:42.564Z DEBUG modem << [00:00:26.445,007] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:44.542Z DEBUG modem << [00:00:27.445,373] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:44.545Z DEBUG modem << [00:00:28.445,556] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:46.543Z DEBUG modem << [00:00:29.446,166] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:46.553Z DEBUG modem << [00:00:30.446,258] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:48.551Z DEBUG modem << [00:00:31.446,472] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:48.553Z DEBUG modem << [00:00:31.516,510] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2023-02-16T14:07:48.562Z DEBUG modem << [00:00:32.446,716] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:50.544Z DEBUG modem << [00:00:33.446,899] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:50.546Z DEBUG modem << [00:00:34.447,387] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:52.545Z DEBUG modem << [00:00:35.447,662] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:52.547Z DEBUG modem << [00:00:36.447,906] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:07:52.843Z DEBUG modem << +CSCON: 0
2023-02-16T14:07:53.874Z DEBUG modem << [00:00:36.769,439] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:53.876Z DEBUG modem << [00:00:36.770,599] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Idle[0m
2023-02-16T14:07:53.878Z DEBUG modem << [00:00:37.765,686] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:55.861Z DEBUG modem << [00:00:38.765,228] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:55.863Z DEBUG modem << [00:00:39.765,655] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:57.862Z DEBUG modem << [00:00:40.766,235] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:57.866Z DEBUG modem << [00:00:41.765,411] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:59.862Z DEBUG modem << [00:00:42.765,472] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:07:59.864Z DEBUG modem << [00:00:43.766,662] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:01.885Z DEBUG modem << [00:00:44.768,981] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:01.887Z DEBUG modem << [00:00:44.769,042] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.6, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:01.889Z DEBUG modem << [00:00:45.768,859] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:01.893Z DEBUG modem << [00:00:45.768,890] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:03.907Z DEBUG modem << [00:00:46.770,996] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:03.909Z DEBUG modem << [00:00:46.771,057] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:03.911Z DEBUG modem << [00:00:46.771,087] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:03.912Z DEBUG modem << [00:00:47.770,080] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:03.913Z DEBUG modem << [00:00:47.770,111] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:03.915Z DEBUG modem << [00:00:47.770,141] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:05.285Z DEBUG modem << %CESQ: 55,2,12,1
2023-02-16T14:08:06.054Z DEBUG modem << [00:00:48.928,741] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:06.056Z DEBUG modem << [00:00:48.928,771] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:06.058Z DEBUG modem << [00:00:49.213,287] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -85[0m
2023-02-16T14:08:06.059Z DEBUG modem << [00:00:49.883,178] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:06.061Z DEBUG modem << [00:00:49.883,209] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:08.001Z DEBUG modem << [00:00:50.885,345] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:08.003Z DEBUG modem << [00:00:50.885,375] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:08.005Z DEBUG modem << [00:00:51.803,161] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:08.007Z DEBUG modem << [00:00:51.803,192] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:09.898Z DEBUG modem << [00:00:52.802,917] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:09.901Z DEBUG modem << [00:00:53.723,876] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:11.685Z DEBUG modem << %CESQ: 54,2,17,2
2023-02-16T14:08:11.828Z DEBUG modem << [00:00:54.723,022] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:11.830Z DEBUG modem << [00:00:55.613,342] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -86[0m
2023-02-16T14:08:11.832Z DEBUG modem << [00:00:55.642,608] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:12.951Z DEBUG modem << %CESQ: 255,0,255,0
2023-02-16T14:08:13.739Z DEBUG modem << [00:00:56.643,280] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:13.747Z DEBUG modem << [00:00:57.643,707] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:15.760Z DEBUG modem << [00:00:58.645,263] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:15.763Z DEBUG modem << [00:00:58.645,324] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:15.765Z DEBUG modem << [00:00:59.645,416] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:15.767Z DEBUG modem << [00:00:59.645,446] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:17.755Z DEBUG modem << [00:01:00.645,477] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:17.763Z DEBUG modem << [00:01:00.645,538] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:17.765Z DEBUG modem << [00:01:01.516,601] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2023-02-16T14:08:17.777Z DEBUG modem << [00:01:01.647,155] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:17.779Z DEBUG modem << [00:01:01.647,216] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:17.792Z DEBUG modem << [00:01:01.647,247] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:19.781Z DEBUG modem << [00:01:02.645,996] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:19.785Z DEBUG modem << [00:01:02.646,026] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:19.795Z DEBUG modem << [00:01:03.651,153] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:19.797Z DEBUG modem << [00:01:03.651,184] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 25.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:19.798Z DEBUG modem << [00:01:03.651,214] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 23.6, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:19.800Z DEBUG modem << [00:01:03.651,275] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:21.817Z DEBUG modem << [00:01:04.652,069] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: true[0m
2023-02-16T14:08:21.820Z DEBUG modem << [00:01:04.652,099] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:21.830Z DEBUG modem << [00:01:04.652,160] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:21.832Z DEBUG modem << [00:01:04.652,191] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:21.836Z DEBUG modem << [00:01:04.652,221] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.4, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:21.838Z DEBUG modem << [00:01:05.648,620] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:21.840Z DEBUG modem << [00:01:05.648,651] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:21.841Z DEBUG modem << [00:01:05.648,651] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:21.842Z DEBUG modem << [00:01:05.648,712] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.2, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:23.869Z DEBUG modem << [00:01:06.654,724] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:23.873Z DEBUG modem << [00:01:06.654,785] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.883Z DEBUG modem << [00:01:06.654,815] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 25.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.885Z DEBUG modem << [00:01:06.654,846] [0m<dbg> location: method_gnss_print_pvt: PRN:  25, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.888Z DEBUG modem << [00:01:06.654,876] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.0, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:23.891Z DEBUG modem << [00:01:06.654,907] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 25.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.892Z DEBUG modem << [00:01:07.659,332] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 7, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:23.894Z DEBUG modem << [00:01:07.659,362] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.895Z DEBUG modem << [00:01:07.659,393] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.897Z DEBUG modem << [00:01:07.659,423] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.4, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:23.898Z DEBUG modem << [00:01:07.659,454] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.912Z DEBUG modem << [00:01:07.659,484] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.915Z DEBUG modem << [00:01:07.659,515] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 26.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:23.918Z DEBUG modem << [00:01:07.659,545] [0m<dbg> location: method_gnss_print_pvt: PRN:  18, C/N0: 25.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.765Z DEBUG modem << [00:01:08.653,991] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:25.768Z DEBUG modem << [00:01:08.654,052] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.769Z DEBUG modem << [00:01:08.654,083] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.848Z DEBUG modem << [00:01:08.654,113] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.8, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:25.851Z DEBUG modem << [00:01:08.654,144] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.852Z DEBUG modem << [00:01:08.654,174] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.863Z DEBUG modem << [00:01:09.654,846] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:25.864Z DEBUG modem << [00:01:09.654,876] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.867Z DEBUG modem << [00:01:09.654,907] [0m<dbg> location: method_gnss_print_pvt: PRN:   2, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.869Z DEBUG modem << [00:01:09.654,937] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.0, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:25.872Z DEBUG modem << [00:01:09.654,968] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:25.874Z DEBUG modem << [00:01:09.654,998] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:27.805Z DEBUG modem << [00:01:10.651,214] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:27.808Z DEBUG modem << [00:01:10.651,245] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:27.809Z DEBUG modem << [00:01:10.651,306] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.4, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:27.811Z DEBUG modem << [00:01:10.651,306] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:27.812Z DEBUG modem << [00:01:10.651,367] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 23.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:27.814Z DEBUG modem << [00:01:11.651,977] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:27.815Z DEBUG modem << [00:01:11.652,038] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.2, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:27.816Z DEBUG modem << [00:01:11.652,038] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:29.814Z DEBUG modem << [00:01:12.649,932] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:29.817Z DEBUG modem << [00:01:12.649,963] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:29.827Z DEBUG modem << [00:01:12.649,993] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:29.829Z DEBUG modem << [00:01:12.650,024] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:29.831Z DEBUG modem << [00:01:13.651,550] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:29.833Z DEBUG modem << [00:01:13.651,580] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:29.835Z DEBUG modem << [00:01:13.651,641] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 23.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:29.837Z DEBUG modem << [00:01:13.651,672] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 25.4, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:29.838Z DEBUG modem << [00:01:13.651,702] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.823Z DEBUG modem << [00:01:14.654,754] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:31.825Z DEBUG modem << [00:01:14.654,785] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 23.6, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.836Z DEBUG modem << [00:01:14.654,846] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.839Z DEBUG modem << [00:01:14.654,876] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.2, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:31.841Z DEBUG modem << [00:01:14.654,907] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 25.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.843Z DEBUG modem << [00:01:14.654,937] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.846Z DEBUG modem << [00:01:15.651,855] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:31.847Z DEBUG modem << [00:01:15.651,885] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.848Z DEBUG modem << [00:01:15.651,916] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 23.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.861Z DEBUG modem << [00:01:15.651,947] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.2, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:31.863Z DEBUG modem << [00:01:15.651,977] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:31.865Z DEBUG modem << [00:01:15.652,038] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.385Z DEBUG modem << [00:01:16.652,618] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:34.387Z DEBUG modem << [00:01:16.652,648] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 26.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.394Z DEBUG modem << [00:01:16.652,709] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.7, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:34.396Z DEBUG modem << [00:01:16.652,709] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 23.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.399Z DEBUG modem << [00:01:16.652,770] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.402Z DEBUG modem << [00:01:17.655,090] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:34.407Z DEBUG modem << [00:01:17.655,151] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.409Z DEBUG modem << [00:01:17.655,181] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.430Z DEBUG modem << [00:01:17.655,212] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.433Z DEBUG modem << [00:01:17.655,242] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:34.438Z DEBUG modem << [00:01:17.655,273] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 23.6, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:35.805Z DEBUG modem << [00:01:18.651,489] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:35.807Z DEBUG modem << [00:01:18.651,550] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:35.820Z DEBUG modem << [00:01:18.651,580] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:35.822Z DEBUG modem << [00:01:18.651,611] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:35.826Z DEBUG modem << [00:01:19.650,726] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:35.828Z DEBUG modem << [00:01:19.650,756] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:35.830Z DEBUG modem << [00:01:19.650,787] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:35.832Z DEBUG modem << [00:01:19.650,817] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:37.763Z DEBUG modem << [00:01:20.648,742] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:37.766Z DEBUG modem << [00:01:20.648,803] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:37.768Z DEBUG modem << [00:01:20.648,834] [0m<dbg> location: method_gnss_print_pvt: PRN:  32, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:37.784Z DEBUG modem << [00:01:21.644,500] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:39.760Z DEBUG modem << [00:01:22.646,362] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:39.763Z DEBUG modem << [00:01:22.646,392] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 23.8, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:39.780Z DEBUG modem << [00:01:23.646,575] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:39.784Z DEBUG modem << [00:01:23.646,606] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 23.9, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:41.760Z DEBUG modem << [00:01:24.646,331] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:41.772Z DEBUG modem << [00:01:24.646,392] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 24.3, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:41.774Z DEBUG modem << [00:01:25.647,003] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:41.776Z DEBUG modem << [00:01:25.647,033] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 23.6, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:43.727Z DEBUG modem << [00:01:26.646,240] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:43.760Z DEBUG modem << [00:01:26.646,270] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 23.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:43.772Z DEBUG modem << [00:01:27.647,125] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:43.774Z DEBUG modem << [00:01:27.647,155] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:45.782Z DEBUG modem << [00:01:28.648,559] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:45.785Z DEBUG modem << [00:01:28.648,590] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:45.799Z DEBUG modem << [00:01:28.648,620] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.5, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:45.800Z DEBUG modem << [00:01:29.648,040] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:45.802Z DEBUG modem << [00:01:29.648,071] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:45.804Z DEBUG modem << [00:01:29.648,101] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.0, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:47.767Z DEBUG modem << [00:01:30.646,514] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:47.778Z DEBUG modem << [00:01:30.646,545] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.8, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:47.780Z DEBUG modem << [00:01:31.516,693] [0m<dbg> watchdog: primary_feed_worker: Feeding watchdog[0m
2023-02-16T14:08:47.783Z DEBUG modem << [00:01:31.647,064] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:47.786Z DEBUG modem << [00:01:31.647,094] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.7, in fix: 0, unhealthy: 1[0m
2023-02-16T14:08:49.739Z DEBUG modem << [00:01:32.645,202] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:49.740Z DEBUG modem << [00:01:33.644,531] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:51.739Z DEBUG modem << [00:01:34.645,629] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:51.741Z DEBUG modem << [00:01:35.644,714] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:53.739Z DEBUG modem << [00:01:36.645,935] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:53.757Z DEBUG modem << [00:01:37.645,019] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:55.740Z DEBUG modem << [00:01:38.646,728] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:55.751Z DEBUG modem << [00:01:39.645,019] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:57.738Z DEBUG modem << [00:01:40.645,294] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:57.750Z DEBUG modem << [00:01:41.645,507] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 0, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:59.771Z DEBUG modem << [00:01:42.648,681] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 1, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:59.773Z DEBUG modem << [00:01:42.648,742] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 23.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:59.783Z DEBUG modem << [00:01:43.649,841] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:08:59.785Z DEBUG modem << [00:01:43.649,871] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:08:59.787Z DEBUG modem << [00:01:43.649,902] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.767Z DEBUG modem << [00:01:44.654,937] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:01.769Z DEBUG modem << [00:01:44.654,968] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.770Z DEBUG modem << [00:01:44.654,998] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.771Z DEBUG modem << [00:01:44.655,059] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 23.6, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.837Z DEBUG modem << [00:01:44.655,090] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.6, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.839Z DEBUG modem << [00:01:45.655,181] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:01.841Z DEBUG modem << [00:01:45.655,242] [0m<dbg> location: method_gnss_print_pvt: PRN:  29, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.842Z DEBUG modem << [00:01:45.655,273] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.845Z DEBUG modem << [00:01:45.655,303] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.847Z DEBUG modem << [00:01:45.655,334] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:01.850Z DEBUG modem << [00:01:45.655,364] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.9, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:03.835Z DEBUG modem << [00:01:46.655,609] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 5, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:03.847Z DEBUG modem << [00:01:46.655,639] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 23.8, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:03.850Z DEBUG modem << [00:01:46.655,670] [0m<dbg> location: method_gnss_print_pvt: PRN:  12, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:03.851Z DEBUG modem << [00:01:46.655,700] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:03.853Z DEBUG modem << [00:01:46.655,731] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:03.856Z DEBUG modem << [00:01:46.655,761] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.3, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:03.857Z DEBUG modem << [00:01:47.652,191] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:03.859Z DEBUG modem << [00:01:47.652,221] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 24.1, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:03.861Z DEBUG modem << [00:01:47.652,252] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:03.863Z DEBUG modem << [00:01:47.652,313] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 23.9, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:03.879Z DEBUG modem << [00:01:47.652,343] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 24.2, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:05.791Z DEBUG modem << [00:01:48.648,468] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 2, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:05.794Z DEBUG modem << [00:01:48.648,529] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 24.4, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:05.813Z DEBUG modem << [00:01:48.648,559] [0m<dbg> location: method_gnss_print_pvt: PRN:  28, C/N0: 23.8, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:05.815Z DEBUG modem << [00:01:49.651,672] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:05.817Z DEBUG modem << [00:01:49.651,702] [0m<dbg> location: method_gnss_print_pvt: PRN:  22, C/N0: 24.2, in fix: 0, unhealthy: 1[0m
2023-02-16T14:09:05.819Z DEBUG modem << [00:01:49.651,733] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 25.3, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:05.822Z DEBUG modem << [00:01:49.651,794] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 23.8, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.816Z DEBUG modem << [00:01:50.654,418] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 3, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:07.828Z DEBUG modem << [00:01:50.654,449] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.5, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.831Z DEBUG modem << [00:01:50.654,479] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 24.0, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.834Z DEBUG modem << [00:01:50.654,510] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 24.2, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.835Z DEBUG modem << [00:01:51.655,364] [0m<dbg> location: method_gnss_print_pvt: Tracked satellites: 4, fix valid: false, insuf. time window: false[0m
2023-02-16T14:09:07.837Z DEBUG modem << [00:01:51.655,395] [0m<dbg> location: method_gnss_print_pvt: PRN:  20, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.839Z DEBUG modem << [00:01:51.655,426] [0m<dbg> location: method_gnss_print_pvt: PRN:  31, C/N0: 24.1, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.841Z DEBUG modem << [00:01:51.655,456] [0m<dbg> location: method_gnss_print_pvt: PRN:  26, C/N0: 23.7, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:07.842Z DEBUG modem << [00:01:51.655,487] [0m<dbg> location: method_gnss_print_pvt: PRN:  11, C/N0: 24.4, in fix: 0, unhealthy: 0[0m
2023-02-16T14:09:08.560Z DEBUG modem << %CESQ: 58,2,21,3
2023-02-16T14:09:09.293Z DEBUG modem << %CESQ: 54,2,15,2
2023-02-16T14:09:09.296Z DEBUG modem << %NCELLMEAS: 0,"00B6810B","20408","7EF5",65535,6400,225,60,19,112751,6400,495,53,5,0,0
2023-02-16T14:09:09.333Z DEBUG modem << Encoded message:
2023-02-16T14:09:09.335Z DEBUG modem << {
2023-02-16T14:09:09.337Z DEBUG modem << "appId":"GROUND_FIX",
2023-02-16T14:09:09.339Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.340Z DEBUG modem << "data":{
2023-02-16T14:09:09.341Z DEBUG modem << "lte":[{
2023-02-16T14:09:09.342Z DEBUG modem << "eci":11960587,
2023-02-16T14:09:09.344Z DEBUG modem << "mcc":204,
2023-02-16T14:09:09.345Z DEBUG modem << "mnc":8,
2023-02-16T14:09:09.348Z DEBUG modem << "tac":32501,
2023-02-16T14:09:09.350Z DEBUG modem << "earfcn":6400,
2023-02-16T14:09:09.367Z DEBUG modem << "rsrp":-80,
2023-02-16T14:09:09.369Z DEBUG modem << "rsrq":-10,
2023-02-16T14:09:09.371Z DEBUG modem << "nmr":[{
2023-02-16T14:09:09.373Z DEBUG modem << "earfcn":6400,
2023-02-16T14:09:09.374Z DEBUG modem << "pci":495,
2023-02-16T14:09:09.376Z DEBUG modem << "rsrp":-87,
2023-02-16T14:09:09.377Z DEBUG modem << "rsrq":-17
2023-02-16T14:09:09.379Z DEBUG modem << }]
2023-02-16T14:09:09.381Z DEBUG modem << }]
2023-02-16T14:09:09.383Z DEBUG modem << }
2023-02-16T14:09:09.384Z DEBUG modem << }
2023-02-16T14:09:09.463Z DEBUG modem << Encoded batch message:
2023-02-16T14:09:09.465Z DEBUG modem << [{
2023-02-16T14:09:09.466Z DEBUG modem << "appId":"HUMID",
2023-02-16T14:09:09.467Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.469Z DEBUG modem << "ts":1676556445974,
2023-02-16T14:09:09.470Z DEBUG modem << "data":"27.12"
2023-02-16T14:09:09.471Z DEBUG modem << }, {
2023-02-16T14:09:09.473Z DEBUG modem << "appId":"TEMP",
2023-02-16T14:09:09.475Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.488Z DEBUG modem << "ts":1676556445974,
2023-02-16T14:09:09.490Z DEBUG modem << "data":"30.25"
2023-02-16T14:09:09.492Z DEBUG modem << }, {
2023-02-16T14:09:09.493Z DEBUG modem << "appId":"AIR_PRESS",
2023-02-16T14:09:09.495Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.497Z DEBUG modem << "ts":1676556445974,
2023-02-16T14:09:09.499Z DEBUG modem << "data":"102.02"
2023-02-16T14:09:09.500Z DEBUG modem << }, {
2023-02-16T14:09:09.505Z DEBUG modem << "appId":"VOLTAGE",
2023-02-16T14:09:09.508Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.510Z DEBUG modem << "ts":1676556446066,
2023-02-16T14:09:09.511Z DEBUG modem << "data":"4394"
2023-02-16T14:09:09.513Z DEBUG modem << }, {
2023-02-16T14:09:09.515Z DEBUG modem << "appId":"DEVICE",
2023-02-16T14:09:09.516Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.518Z DEBUG modem << "ts":1676556446002,
2023-02-16T14:09:09.520Z DEBUG modem << "data":{
2023-02-16T14:09:09.521Z DEBUG modem << "deviceInfo":{
2023-02-16T14:09:09.524Z DEBUG modem << "imei":"352656109434111",
2023-02-16T14:09:09.526Z DEBUG modem << "iccid":"8931080520035848868",
2023-02-16T14:09:09.528Z DEBUG modem << "modemFirmware":"mfw_nrf9160_1.3.2",
2023-02-16T14:09:09.530Z DEBUG modem << "board":"thingy91_nrf9160",
2023-02-16T14:09:09.531Z DEBUG modem << "appVersion":"0.0.0-development"
2023-02-16T14:09:09.533Z DEBUG modem << }
2023-02-16T14:09:09.535Z DEBUG modem << }
2023-02-16T14:09:09.537Z DEBUG modem << }, {
2023-02-16T14:09:09.539Z DEBUG modem << "appId":"DEVICE",
2023-02-16T14:09:09.540Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.543Z DEBUG modem << "ts":1676556446035,
2023-02-16T14:09:09.545Z DEBUG modem << "data":{
2023-02-16T14:09:09.546Z DEBUG modem << "networkInfo":{
2023-02-16T14:09:09.548Z DEBUG modem << "currentBand":20,
2023-02-16T14:09:09.549Z DEBUG modem << "networkMode":"LTE-M",
2023-02-16T14:09:09.550Z DEBUG modem << "rsrp":-86,
2023-02-16T14:09:09.552Z DEBUG modem << "areaCode":32501,
2023-02-16T14:09:09.553Z DEBUG modem << "mccmnc":20408,
2023-02-16T14:09:09.554Z DEBUG modem << "cellID":11960587,
2023-02-16T14:09:09.556Z DEBUG modem << "ipAddress":"10.160.64.133"
2023-02-16T14:09:09.557Z DEBUG modem << }
2023-02-16T14:09:09.558Z DEBUG modem << }
2023-02-16T14:09:09.559Z DEBUG modem << }, {
2023-02-16T14:09:09.560Z DEBUG modem << "appId":"RSRP",
2023-02-16T14:09:09.561Z DEBUG modem << "messageType":"DATA",
2023-02-16T14:09:09.563Z DEBUG modem << "ts":1676556446035,
2023-02-16T14:09:09.566Z DEBUG modem << "data":"-86"
2023-02-16T14:09:09.567Z DEBUG modem << }]
2023-02-16T14:09:09.697Z DEBUG modem << [00:01:52.384,765] [0m<inf> location: Method specific timeout expired[0m
2023-02-16T14:09:09.699Z DEBUG modem << [00:01:52.385,498] [0m<inf> location: Location retrieval failed using 'GNSS', trying with 'Cellular' next[0m
2023-02-16T14:09:09.712Z DEBUG modem << [00:01:52.385,589] [0m<dbg> location: location_core_timer_start: Starting timer with timeout=11000[0m
2023-02-16T14:09:09.714Z DEBUG modem << [00:01:52.385,620] [0m<dbg> location: method_cellular_positioning_work_fn: Triggering neighbor cell measurements[0m
2023-02-16T14:09:09.717Z DEBUG modem << [00:01:52.385,650] [0m<dbg> location: method_cellular_ncellmeas_start: Triggering cell measurements[0m
2023-02-16T14:09:09.719Z DEBUG modem << [00:01:52.491,363] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -82[0m
2023-02-16T14:09:09.721Z DEBUG modem << [00:01:53.216,339] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -86[0m
2023-02-16T14:09:09.723Z DEBUG modem << [00:01:53.224,670] [0m<dbg> location: method_cellular_lte_ind_handler: Cell measurements results received[0m
2023-02-16T14:09:09.724Z DEBUG modem << [00:01:53.224,975] [0m<dbg> location_module: location_event_handler: Getting cellular request[0m
2023-02-16T14:09:09.725Z DEBUG modem << [00:01:53.225,067] [0m<inf> app_event_manager: LOCATION_MODULE_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2023-02-16T14:09:09.727Z DEBUG modem << %CESQ: 58,2,20,2
2023-02-16T14:09:09.728Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2023-02-16T14:09:09.729Z DEBUG modem << [00:01:53.225,524] [0m<dbg> location: location_core_cellular_ext_result_set: Location cellular result set with result=unknown[0m
2023-02-16T14:09:09.747Z DEBUG modem << [00:01:53.225,616] [0m<dbg> location: location_core_event_cb_fn: Location acquisition completed and fallbacks are also done[0m
2023-02-16T14:09:09.750Z DEBUG modem << [00:01:53.225,616] [0m<dbg> location_module: location_event_handler: Getting location completed with undefined result[0m
2023-02-16T14:09:09.751Z DEBUG modem << [00:01:53.225,646] [0m<dbg> location_module: location_event_handler:   search time: 103367[0m
2023-02-16T14:09:09.753Z DEBUG modem << [00:01:53.225,738] [0m<inf> app_event_manager: LOCATION_MODULE_EVT_INACTIVE[0m
2023-02-16T14:09:09.755Z DEBUG modem << [00:01:53.225,830] [0m<dbg> ui_module: sub_sub_state_set: Sub state transition SUB_SUB_STATE_LOCATION_ACTIVE --> SUB_SUB_STATE_LOCATION_INACTIVE[0m
2023-02-16T14:09:09.757Z DEBUG modem << [00:01:53.226,226] [0m<dbg> location_module: sub_state_set: Sub state transition SUB_STATE_SEARCH --> SUB_STATE_IDLE[0m
2023-02-16T14:09:09.759Z DEBUG modem << [00:01:53.226,745] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2023-02-16T14:09:09.762Z DEBUG modem << [00:01:53.264,160] [0m<dbg> data_module: data_encode: Neighbor cell data encoded successfully[0m
2023-02-16T14:09:09.763Z DEBUG modem << [00:01:53.264,251] [0m<inf> app_event_manager: DATA_EVT_NEIGHBOR_CELLS_DATA_SEND[0m
2023-02-16T14:09:09.779Z DEBUG modem << [00:01:53.264,984] [0m<dbg> data_module: data_encode: Regular data updates are not supported[0m
2023-02-16T14:09:09.782Z DEBUG modem << [00:01:53.395,019] [0m<dbg> data_module: data_encode: Batch data encoded successfully[0m
2023-02-16T14:09:09.785Z DEBUG modem << [00:01:53.395,111] [0m<inf> app_event_manager: DATA_EVT_DATA_SEND_BATCH[0m
2023-02-16T14:09:09.787Z DEBUG modem << [00:01:53.395,935] [0m<dbg> cloud_module: qos_event_handler: QOS_EVT_MESSAGE_NEW[0m
2023-02-16T14:09:09.788Z DEBUG modem << [00:01:53.395,996] [0m<inf> app_event_manager: CLOUD_EVT_DATA_SEND_QOS[0m
2023-02-16T14:09:09.806Z DEBUG modem << 
2023-02-16T14:09:09.808Z DEBUG modem << [00:01:53.396,240] [0m<dbg> cloud_module: qos_event_handler: QOS_EVT_MESSAGE_NEW[0m
2023-02-16T14:09:09.810Z DEBUG modem << [00:01:53.396,301] [0m<inf> app_event_manager: CLOUD_EVT_DATA_SEND_QOS[0m
2023-02-16T14:09:09.812Z DEBUG modem << [00:01:53.396,514] [0m<dbg> qos: qos_message_print: Notified count: 1[0m
2023-02-16T14:09:09.813Z DEBUG modem << [00:01:53.396,514] [0m<dbg> qos: qos_message_print: Message heap_allocated: 1[0m
2023-02-16T14:09:09.815Z DEBUG modem << [00:01:53.396,545] [0m<dbg> qos: qos_message_print: Message ID: 15001[0m
2023-02-16T14:09:09.817Z DEBUG modem << [00:01:53.396,545] [0m<dbg> qos: qos_message_print: Message Buffer pointer: 0x2001c718[0m
2023-02-16T14:09:09.819Z DEBUG modem << [00:01:53.396,575] [0m<dbg> qos: qos_message_print: Message Buffer length: 199[0m
2023-02-16T14:09:09.822Z DEBUG modem << [00:01:53.396,575] [0m<dbg> qos: qos_message_print: Message Flags: 2[0m
2023-02-16T14:09:09.823Z DEBUG modem << [00:01:53.396,606] [0m<dbg> qos: qos_message_print: Message type: 3[0m
2023-02-16T14:09:09.825Z DEBUG modem << [00:01:53.492,187] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -82[0m
2023-02-16T14:09:09.826Z DEBUG modem << [00:01:53.494,384] [0m<dbg> modem_module: lte_evt_handler: Modem domain event, type: Search done[0m
2023-02-16T14:09:09.924Z DEBUG modem << %CESQ: 55,2,12,1
2023-02-16T14:09:10.066Z DEBUG modem << +CSCON: 1
2023-02-16T14:09:10.508Z DEBUG modem << [00:01:53.713,073] [0m<dbg> qos: qos_message_print: Notified count: 1[0m
2023-02-16T14:09:10.510Z DEBUG modem << [00:01:53.713,073] [0m<dbg> qos: qos_message_print: Message heap_allocated: 1[0m
2023-02-16T14:09:10.511Z DEBUG modem << [00:01:53.713,104] [0m<dbg> qos: qos_message_print: Message ID: 15002[0m
2023-02-16T14:09:10.514Z DEBUG modem << [00:01:53.713,134] [0m<dbg> qos: qos_message_print: Message Buffer pointer: 0x2001d128[0m
2023-02-16T14:09:10.515Z DEBUG modem << [00:01:53.713,134] [0m<dbg> qos: qos_message_print: Message Buffer length: 814[0m
2023-02-16T14:09:10.516Z DEBUG modem << [00:01:53.713,165] [0m<dbg> qos: qos_message_print: Message Flags: 2[0m
2023-02-16T14:09:10.518Z DEBUG modem << [00:01:53.713,165] [0m<dbg> qos: qos_message_print: Message type: 1[0m
2023-02-16T14:09:10.519Z DEBUG modem << [00:01:53.855,163] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -85[0m
2023-02-16T14:09:10.522Z DEBUG modem << [00:01:53.997,894] [0m<dbg> modem_module: lte_evt_handler: RRC mode: Connected[0m
2023-02-16T14:09:10.523Z DEBUG modem << [00:01:54.309,478] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 15001 result = 0[0m
2023-02-16T14:09:10.525Z DEBUG modem << [00:01:54.309,509] [0m<dbg> nrf_cloud_fsm: cc_tx_ack_handler: Data ACK for user tag: 15001[0m
2023-02-16T14:09:10.542Z DEBUG modem << [00:01:54.309,539] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 9[0m
2023-02-16T14:09:10.544Z DEBUG modem << [00:01:54.309,570] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_SENSOR_DATA_ACK[0m
2023-02-16T14:09:10.546Z DEBUG modem << [00:01:54.309,570] [0m<dbg> cloud_module: cloud_wrap_event_handler: CLOUD_WRAP_EVT_DATA_ACK: 15001[0m
2023-02-16T14:09:10.548Z DEBUG modem << [00:01:54.309,600] [0m<dbg> cloud_module: qos_event_handler: QOS_EVT_MESSAGE_REMOVED_FROM_LIST[0m
2023-02-16T14:09:10.550Z DEBUG modem << [00:01:54.309,631] [0m<dbg> cloud_module: qos_event_handler: Freeing pointer: 0x2001c718[0m
2023-02-16T14:09:10.706Z DEBUG modem << [00:01:54.457,641] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id = 15002 result = 0[0m
2023-02-16T14:09:10.708Z DEBUG modem << [00:01:54.457,672] [0m<dbg> nrf_cloud_fsm: cc_tx_ack_handler: Data ACK for user tag: 15002[0m
2023-02-16T14:09:10.710Z DEBUG modem << [00:01:54.457,702] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 9[0m
2023-02-16T14:09:10.711Z DEBUG modem << [00:01:54.457,733] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_SENSOR_DATA_ACK[0m
2023-02-16T14:09:10.712Z DEBUG modem << [00:01:54.457,733] [0m<dbg> cloud_module: cloud_wrap_event_handler: CLOUD_WRAP_EVT_DATA_ACK: 15002[0m
2023-02-16T14:09:10.714Z DEBUG modem << [00:01:54.457,763] [0m<dbg> cloud_module: qos_event_handler: QOS_EVT_MESSAGE_REMOVED_FROM_LIST[0m
2023-02-16T14:09:10.716Z DEBUG modem << [00:01:54.457,794] [0m<dbg> cloud_module: qos_event_handler: Freeing pointer: 0x2001d128[0m
2023-02-16T14:09:10.719Z DEBUG modem << [00:01:54.457,824] [0m<dbg> qos: qos_message_remove: QoS list is empty, cancel ongoing delayed work[0m
2023-02-16T14:09:10.720Z DEBUG modem << [00:01:54.529,937] [0m<dbg> nrf_cloud_transport: nct_mqtt_evt_handler: MQTT_EVT_PUBLISH: id = 8064 len = 126, topic = prod/8ae9b938-a7aa-4425-aab1-c5d9f493a9b3/m/d/nrf-352656109434111/ground_fix/r[0m
2023-02-16T14:09:10.736Z DEBUG modem << [00:01:54.530,059] [0m<dbg> nrf_cloud: nfsm_set_current_state_and_notify: state: 9[0m
2023-02-16T14:09:10.738Z DEBUG modem << [00:01:54.530,090] [0m<dbg> nrf_cloud_integration: nrf_cloud_event_handler: NRF_CLOUD_EVT_RX_DATA_LOCATION[0m
2023-02-16T14:09:10.868Z DEBUG modem << %CESQ: 55,2,17,2
2023-02-16T14:09:11.878Z DEBUG modem << [00:01:54.798,797] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -85[0m
2023-02-16T14:09:12.960Z DEBUG modem << %CESQ: 55,2,12,1
2023-02-16T14:09:13.970Z DEBUG modem << [00:01:56.891,174] [0m<dbg> modem_module: modem_rsrp_handler: Incoming RSRP status message, RSRP value is -85[0m
2023-02-16T14:09:16.564Z DEBUG modem << %CESQ: 55,2,17,2

[Øyvind]

Hello, 

Thanks for providing this information. 

Looks like Cloud is setting your device to active ("activeMode":true)

2023-02-16T14:07:25.262Z DEBUG modem << "config":{
2023-02-16T14:07:25.263Z DEBUG modem << "activeMode":true,
2023-02-16T14:07:25.265Z DEBUG modem << "gnssTimeout":30,
2023-02-16T14:07:25.266Z DEBUG modem << "activeWaitTime":120,
2023-02-16T14:07:25.267Z DEBUG modem << "movementResolution":120,
2023-02-16T14:07:25.269Z DEBUG modem << "movementTimeout":3600,
2023-02-16T14:07:25.271Z DEBUG modem << "accThreshAct":10,
2023-02-16T14:07:25.272Z DEBUG modem << "accThreshInact":5,
2023-02-16T14:07:25.273Z DEBUG modem << "accTimeoutInact":60,
2023-02-16T14:07:25.276Z DEBUG modem << "nod":[],
2023-02-16T14:07:25.277Z DEBUG modem << "locationTimeout":300
2023-02-16T14:07:25.278Z DEBUG modem << }

Can you ensure that the project does enable passive mode, then before programming the project please try to do a nrfjprog --eraseall first?

[hubert_11]

Thanks! I does seem like it was the cloud al along. I changed the mode in the cloud to passive and now all is working great. Thank you for the help!