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?

[Øyvind]

Hello, 

Looking at the documentation for the Data Module of the Asset Tracker v2,  CONFIG_DATA_DEVICE_MODE_PASSIVE is default mode for Thingy:91. 

Could you please elaborate more on where you are configuring the settings? What version of nRF Connect SDK you using?

Kind regards,
Øyvind

[hubert_11]

Thank you for the response, but it does seem to initialise in active mode. 

This is what is encountered when stepping through the initialization:

Upon booting, in sensor_module.c, this function is called:

/* Message handler for STATE_INIT. */
static void on_state_init(struct sensor_msg_data *msg)
{
	if (IS_EVENT(msg, data, DATA_EVT_CONFIG_INIT)) {
		apply_config(msg);
		state_set(STATE_RUNNING);
	}
}

From there, the apply_config function is called:

static void apply_config(struct sensor_msg_data *msg)
{
#if defined(CONFIG_EXTERNAL_SENSORS)
	configure_acc(&msg->module.data.data.cfg);

	if (msg->module.data.data.cfg.active_mode) {
		accelerometer_callback_set(false);
	} else {
		accelerometer_callback_set(true);
	}
#endif /* CONFIG_EXTERNAL_SENSORS */
}

Which then sets the accelerometer callback to false (so the msg passed to it has active_mode set to true). 
As far as I understand, this deactivates any possibility to trigger movement registered by the Zephyr os.

I believe this innitial configuragtion message is based upon this struct in data_module.c:

/* Default device configuration. */
static struct cloud_data_cfg current_cfg = {
	.gnss_timeout		 = CONFIG_DATA_GNSS_TIMEOUT_SECONDS,
	.active_mode		 = IS_ENABLED(CONFIG_DATA_DEVICE_MODE_ACTIVE),
	.active_wait_timeout	 = CONFIG_DATA_ACTIVE_TIMEOUT_SECONDS,
	.movement_resolution	 = CONFIG_DATA_MOVEMENT_RESOLUTION_SECONDS,
	.movement_timeout	 = CONFIG_DATA_MOVEMENT_TIMEOUT_SECONDS,
	.accelerometer_activity_threshold	= CONFIG_DATA_ACCELEROMETER_ACT_THRESHOLD,
	.accelerometer_inactivity_threshold	= CONFIG_DATA_ACCELEROMETER_INACT_THRESHOLD,
	.accelerometer_inactivity_timeout	= CONFIG_DATA_ACCELEROMETER_INACT_TIMEOUT_SECONDS,
	.no_data.gnss		 = !IS_ENABLED(CONFIG_DATA_SAMPLE_GNSS_DEFAULT),
	.no_data.neighbor_cell	 = !IS_ENABLED(CONFIG_DATA_SAMPLE_NEIGHBOR_CELLS_DEFAULT)
};

and if I hover over CONFIG_DATA_DEVICE_MODE_ACTIVE I can see that it is set to "n". So I would think this means that the message would not turn on the active mode. But apparently I'm missing something.

I have tried changing the configuration in multiple places:

prj.conf file:
CONFIG_DATA_DEVICE_MODE_PASSIVE=y
CONFIG_DATA_DEVICE_MODE_ACTIVE=n

Kconfig.data_module:

DATA_DEVICE_MODE changed to DATA_DEVICE_MODE_PASSIVE

I'm using 2.1.1 for both the SDK and the Toolchain.

p.s. I also have another issue, not related but it does make debugging a lot harder. Often when I try to add a breakppoint, i and up stuck in "FIH_PANIC" in the debugger. Some breakpoints do work tough, and if I remove the breakpoint that caused me to end up in FIH_PANIC is also works again. Do you know what causes this?

    if (fih_not_eq(fih_rc, FIH_SUCCESS)) {
        BOOT_LOG_ERR("Unable to find bootable image");

        mcuboot_status_change(MCUBOOT_STATUS_NO_BOOTABLE_IMAGE_FOUND);

        FIH_PANIC;
    }

    BOOT_LOG_INF("Bootloader chainload address offset: 0x%x",
                 rsp.br_image_off);

[hubert_11]

Thanks! I'm not sure where to find the version number, but on the sticker with the IMEI code it also states:

1.6.0

2021.16

[Øyvind]

I've tested building the default project for thingy91_nrf9160_ns, and see that active mode is on by default in NCS v2.1.1, while in 2.2.0 passive is default when building for Thingy:91. See nrf\applications\asset_tracker_v2\src\modules\Kconfig.data_module

CONFIG_DATA_DEVICE_MODE_ACTIVE=y
# CONFIG_DATA_DEVICE_MODE_PASSIVE is not set

Adding the following lines to the prj.conf

CONFIG_DATA_DEVICE_MODE_ACTIVE=n
CONFIG_DATA_DEVICE_MODE_PASSIVE=y

gave the following in build\zephyr\.config

# CONFIG_DATA_DEVICE_MODE_ACTIVE is not set
CONFIG_DATA_DEVICE_MODE_PASSIVE=y

Can you verify that you see the same on your side?

[Øyvind]

Sorry, I just repilcated what you stated.

[hubert_11]

I tried using 2.2.0 and setting those lines in the prj.conf but this still evaluates to true:

msg->module.data.data.cfg.active_mode

in:

static void apply_config(struct sensor_msg_data *msg)
{
#if defined(CONFIG_EXTERNAL_SENSORS)
	configure_acc(&msg->module.data.data.cfg);

	if (msg->module.data.data.cfg.active_mode) {
		accelerometer_callback_set(false);
	} else {
		accelerometer_callback_set(true);
	}
#endif /* CONFIG_EXTERNAL_SENSORS */
}

So the accelerometer callback is still set to false in 2.2.0, while it should be set to true right?

[Øyvind]

Are you able to rebuild your project with the overlay-debug.conf? This should provide more information for our developers to see what is going on. 

Thanks!

[hubert_11]

How exactly should I do this? I can see this already in the kconfig:

rsource "src/modules/Kconfig.debug_module"

and I do see the overlay-debug.conf in my file browser in vs code.

If I select debug options when creating the build configuration the build fails, which I already reported here:
https://devzone.nordicsemi.com/f/nordic-q-a/96553/build-configuration-fails-for-sdk-2-2-0/409413

[Øyvind]

hubert_11 said:

If I select debug options when creating the build configuration the build fails, which I already reported here:
https://devzone.nordicsemi.com/f/nordic-q-a/96553/build-configuration-fails-for-sdk-2-2-0/409413

I am not talking about the debug options in VS Code, this is the debug configuration which enables misc. debug settings in the application itself. 

In VS Code you can add this to your build configuration under Add Fragment, which will give you a dropdown menu of available overlay config files

[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!