AGPS issues with nRF9160 Dev Kit

[Chetan Kale]

Hello, 

I am using nRF9160 dev kit in Chicago (USA) and in Aldingen (Germany) where I am using same firmware for both DK to get GPS Fixes for location. But surprisingly in Aldingen without any single failure my Firmware works fine. But in Chicago the DK always gets stuck at  GPS_EVT_OPERATION_BLOCKED which will never get the GPS fix sometimes in 24 hours. Then next day if it gets the fix there are errors while downloading and feeding AGPS data to Modem. So can you please answer below question. 

Questions: 

1. If device is in Roaming status then is it will create an issues for GPS fixes? 

2. How to check the which operator we are connecting to?  

3. Does network operator can create issues for Dev Kits to be in PSM mode? Example: network operator can reject PSM mode settings even if Dev kit asks for 10 mins (TAU) of PSM mode and 1 minute of Active mode (RAT).

Console Logs:

GPS_EVT_SEARCH_STARTED 
GPS_EVT_AGPS_DATA_NEEDED 
[00:00:07.833,068] <dbg> nrf9160_gps.configure_antenna: MAGPIO set: AT%XMAGPIO=1,0,0,1,1,1565,1586
[00:00:07.833,740] <dbg> nrf9160_gps.configure_antenna: COEX0 set: AT%XCOEX0=1,1,1565,1586
[00:00:07.836,395] <dbg> nrf9160_gps.enable_gps: GPS mode is enabled
[00:00:07.845,153] <dbg> nrf9160_gps.start: GPS operational
[00:00:07.847,412] <dbg> nrf9160_gps.gps_thread: A-GPS data update needed
[00:00:07.849,792] <dbg> agps.init_supl: Using GPS driver to input assistance data
[00:00:07.849,822] <inf> agps: SUPL is initialized
[00:00:08.082,794] <dbg> agps.open_supl_socket: Connecting to 142.250.138.192 port 7276
[00:00:08.177,886] <inf> agps: Starting SUPL session
[00:00:08.180,053] <dbg> agps.supl_logger: ULP encoding length: 35
[00:00:08.180,664] <dbg> agps.supl_logger: Bytes sent: 35
[mqtt_evt_handler:391] MQTT PUBLISH result=0 len=7

Received Data from Server: No Data
[00:00:08.280,212] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:08.280,761] <dbg> agps.supl_logger: ULP ossDecode success, choice 3
[00:00:08.280,883] <dbg> agps.supl_logger: SUPL server responded using version 2.0.4
[00:00:08.280,975] <dbg> agps.supl_logger: SUPL response received
[00:00:08.281,158] <dbg> agps.supl_logger: ULP encoding length: 54
[00:00:08.281,799] <dbg> agps.supl_logger: Bytes sent: 54
[00:00:08.642,700] <dbg> agps.supl_logger: Bytes received: 708, total 708
[00:00:08.643,249] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:08.757,629] <dbg> agps.supl_logger: Bytes received: 708, total 1416
[00:00:08.758,148] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:08.981,079] <dbg> agps.supl_logger: Bytes received: 708, total 2124
[00:00:08.981,597] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:09.135,406] <dbg> agps.supl_logger: Bytes received: 708, total 2832
[00:00:09.135,925] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:09.191,772] <dbg> agps.supl_logger: Bytes received: 242, total 3074
[00:00:09.192,657] <dbg> agps.supl_logger: ULP ossDecode success, choice 5
--- 3 messages dropped ---
[00:00:09.203,155] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:09.216,003] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:09.231,140] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:09.240,478] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 15 messages dropped ---
[00:00:09.286,163] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 47 messages dropped ---
[00:00:09.313,873] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
--- 13 messages dropped ---
[00:00:09.313,873] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.314,666] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.314,697] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.315,246] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.315,246] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.315,826] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.315,856] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.316,436] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.316,436] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.317,138] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.317,138] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.317,901] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.317,901] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.318,634] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.318,634] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.319,213] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.319,213] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.319,976] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.319,976] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.320,709] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.320,709] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.321,289] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.321,289] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.321,350] <dbg> agps.supl_logger: No integrity data available
[00:00:09.322,296] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 4
[00:00:09.322,326] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 4, size: 8
[00:00:09.323,059] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 7
[00:00:09.323,059] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 6, size: 144
[00:00:09.323,669] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 8
[00:00:09.323,669] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 7, size: 16
[00:00:09.323,730] <dbg> agps.supl_logger: SUPL POS received
[00GPS_EVT_OPERATION_BLOCKED 
:00:10.323,181] <dbg> agps.supl_logger: read again
[00:00:10.430,084] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:10.430,603] <dbg> agps.supl_logger: ULP ossDecode success, choice 6
[00:00:10.430,664] <dbg> agps.supl_logger: SUPLEND:
[00:00:10.430,725] <dbg> agps.supl_logger: 	Mask: 0
[00:00:10.430,786] <dbg> agps.supl_logger: 	Status: 0
[00:00:10.430,847] <dbg> agps.supl_logger: SUPL END received
[00:00:10.430,938] <dbg> agps.supl_logger: SUPL session internal resources released
[00:00:10.430,969] <dbg> agps.supl_logger: SUPL session finished
[00:00:10.430,969] <inf> agps: SUPL session finished successfully
[00:00:10.431,488] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate

TracMile GPS Search Crossed 6 Seconds. 

[00:00:14.364,410] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:00:14.368,499] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:14.368,499] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 14
[00:00:15.256,622] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:15.256,652] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 15
[00:00:16.256,774] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:16.256,774] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 16
[00:00:17.259,002] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:17.259,002] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 17
[00:00:18.256,500] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:18.256,530] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 18
[00:00:19.256,652] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:19.256,683] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 19
[00:00:20.257,293] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:20.257,324] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 20
[00:00:21.257,568] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:21.257,568] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 21
[00:00:22.257,232] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:22.257,232] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 22
[00:00:23.256,469] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:23.256,469] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 23
[00:00:24.256,988] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:24.257,019] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 24
[00:00:25.256,958] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:25.256,958] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 25
[00:00:26.423,004] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:26.423,034] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 26
[00:00:27.425,262] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:27.425,262] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 27
GPS_EVT_OPERATION_BLOCKED 
[00:00:28.483,489] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate
[mqtt_evt_handler:512] default: 9
[mqtt_evt_handler:512] default: 9

TracMile GPS Search Crossed 139 Seconds. 

[00:02:27.456,115] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:02:27.460,296] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:02:27.460,296] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 147
[00:02:28.456,146] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:02:28.456,146] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 148
[00:02:29.454,742] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:02:29.454,742] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 149
[00:02:30.455,780] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:02:30.455,810] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 150
GPS_EVT_OPERATION_BLOCKED 
[00:02:31.690,460] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate
[mqtt_evt_handler:512] default: 9

TracMile GPS Search Crossed 195 Seconds. 

[00:03:23.456,848] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:03:23.461,029] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:03:23.461,059] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 203
[00:03:24.457,427] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:03:24.457,427] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 204
[00:03:25.456,970] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:03:25.456,970] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 205
[00:03:26.457,855] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:03:26.457,855] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 206
GPS_EVT_OPERATION_BLOCKED 
[00:03:27.671,905] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate

NCS version : 1.7.0

Modem Firmware version: 1.3.1

Device Revision : NRF9160_xxAA_REV2

Board Version : PCA10090

SIM used: iBasis

Thank you !

Regards,

Chetan

[Albrecht Markus Schellenberger]

Hello Chetan, 

1. If device is in Roaming status then is it will create an issues for GPS fixes? 

Nothing that I’m aware of, no. 

2. How to check the which operator we are connecting to?  

You can use the <plmn> section in %XMONITOR and check MCC/MNC from this list. That way you can figure out which carrier the device is connected to. If you would like to know which PLMN the device is about to connect to, you need to check the SystemInformationBlock1 in a Wireshark trace. 

3. Does network operator can create issues for Dev Kits to be in PSM mode? Example: network operator can reject PSM mode settings even if Dev kit asks for 10 mins (TAU) of PSM mode and 1 minute of Active mode (RAT).

Absolutely! PSM values are negotiated each time a UE attempts to connect to a carrier. Depending on the network, it can either accept, reject or renegotiate the values the device is asking for. That will differ from network to network, as each carrier has their own requirements and specifications. And that I assume is what happens here. In Chicago, the device probably is not allowed to enter PSM and thus blocking GNSS operations. I recommend setting +CEREG unsolicited result codes level 5 and cross-check the <Active-Time>. If bits 8 to 6 are 111, the UE is not allowed to enter PSM. Otherwise, you should then use the <Periodic-Tau-ext> section in %XMONITOR to cross-check the PSM value itself.

Cheers, 

Markus 

[Chetan Kale]

Hi,

Albrecht Markus Schellenberger said:

Absolutely! PSM values are negotiated each time a UE attempts to connect to a carrier. Depending on the network, it can either accept, reject or renegotiate the values the device is asking for. That will differ from network to network, as each carrier has their own requirements and specifications. And that I assume is what happens here. In Chicago, the device probably is not allowed to enter PSM and thus blocking GNSS operations. I recommend setting +CEREG unsolicited result codes level 5 and cross-check the <Active-Time>. If bits 8 to 6 are 111, the UE is not allowed to enter PSM. Otherwise, you should then use the <Periodic-Tau-ext> section in %XMONITOR to cross-check the PSM value itself.

This was my doubt and I printed the AT+CEREG response in the Firmware for both the location which you can see in logs.

Aldingen DK logs and PSM Settings: 

AT+CEREG Response     - +CEREG: 5,5,"63AA","01DE7502",7,,,"00000010","11100000"

GPS_EVT_SEARCH_STARTED
GPS_EVT_AGPS_DATA_NEEDED
[00:00:13.736,755] <dbg> nrf9160_gps.start: GPS operational
[00:00:13.739,074] <dbg> nrf9160_gps.gps_thread: A-GPS data update needed
[00:00:13.741,516] <dbg> agps.init_supl: Using GPS driver to input assistance data
[00:00:13.741,516] <inf> agps: SUPL is initialized
[00:00:13.813,537] <dbg> agps.open_supl_socket: Connecting to 173.194.76.192 port 7276
[00:00:13.888,916] <inf> agps: Starting SUPL session
[00:00:13.890,930] <dbg> agps.supl_logger: ULP encoding length: 34
[00:00:13.891,571] <dbg> agps.supl_logger: Bytes sent: 34
[00:00:13.974,243] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:13.974,761] <dbg> agps.supl_logger: ULP ossDecode success, choice 3
[00:00:13.974,884] <dbg> agps.supl_logger: SUPL server responded using version 2.0.4
[00:00:13.974,975] <dbg> agps.supl_logger: SUPL response received
[00:00:13.975,189] <dbg> agps.supl_logger: ULP encoding length: 53
[00:00:13.976,074] <dbg> agps.supl_logger: Bytes sent: 53
[00:00:14.143,615] <dbg> agps.supl_logger: Bytes received: 1416, total 1416
[00:00:14.144,134] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:14.208,557] <dbg> agps.supl_logger: Bytes received: 1024, total 2440
[00:00:14.209,197] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:14.209,472] <dbg> agps.supl_logger: Bytes received: 634, total 3074
[00:00:14.210,388] <dbg> agps.supl_logger: ULP ossDecode success, choice 5
[00:00:14.214,294] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 1
--- 4 messages dropped ---
[00:00:14.224,761] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 5 messages dropped ---
[00:00:14.235,382] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:14.249,359] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:14.263,336] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 5 messages dropped ---
[00:00:14.291,717] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 49 messages dropped ---
[00:00:14.337,829] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
--- 21 messages dropped ---
[00:00:14.339,965] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.340,545] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.340,545] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.341,156] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.341,156] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.341,949] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.341,949] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.342,590] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.342,620] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.343,200] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.343,200] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.343,780] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.343,811] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.344,757] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.344,757] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.345,520] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.345,550] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.346,130] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.346,130] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.346,954] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.346,984] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.347,534] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:14.347,564] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:14.347,625] <dbg> agps.supl_logger: No integrity data available
[00:00:14.348,083] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 4
[00:00:14.348,114] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 4, size: 8
[00:00:14.349,060] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 7
[00:00:14.349,090] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 6, size: 144
[00:00:14.349,792] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 8
[00:00:14.349,792] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 7, size: 16
[00:00:14.349,853] <dbg> agps.supl_logger: SUPL POS received
[00:00GPS_EVT_OPERATION_BLOCKED
:15.349,304] <dbg> agps.supl_logger: read again
[00:00:16.257,507] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:16.258,026] <dbg> agps.supl_logger: ULP ossDecode success, choice 6
[00:00:16.258,087] <dbg> agps.supl_logger: SUPLEND:
[00:00:16.258,148] <dbg> agps.supl_logger:      Mask: 0
[00:00:16.258,209] <dbg> agps.supl_logger:      Status: 0
[00:00:16.258,239] <dbg> agps.supl_logger: SUPL END received
[00:00:16.258,331] <dbg> agps.supl_logger: SUPL session internal resources released
[00:00:16.258,392] <dbg> agps.supl_logger: SUPL session finished
[00:00:16.258,392] <inf> agps: SUPL session finished successfully
[00:00:16.259,216] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate

[00:00:33.352,416] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:00:33.356,567] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:33.356,567] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 33
[00:00:34.244,659] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8: not used,     healthy
[00:00:34.244,659] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 1 Using: 0 Unhealthy: 0
[00:00:34.244,689] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 34
[00:00:35.253,143] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8: not used,     healthy
[00:00:35.253,173] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  3: not used,     healthy
[00:00:35.253,173] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4: not used,     healthy
[00:00:35.253,173] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:00:35.253,173] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:00:35.253,204] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 5 Using: 0 Unhealthy: 1
[00:00:35.253,204] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 35
[00:00:36.252,838] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8: not used,     healthy
[00:00:36.252,868] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  3: not used,     healthy
[00:00:36.252,868] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4: not used,     healthy
[00:00:36.252,899] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:00:36.252,899] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:00:36.252,899] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 5 Using: 0 Unhealthy: 1
[00:00:36.252,899] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 36
[00:00:37.254,028] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8: not used,     healthy
[00:00:37.254,028] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  3: not used,     healthy
[00:00:37.254,028] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4: not used,     healthy
[00:00:37.254,058] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:00:37.254,058] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:00:37.254,058] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 5 Using: 0 Unhealthy: 1
[00:00:37.254,058] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 37
[00:00:38.252,685] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8: not used,     healthy
[00:00:38.252,685] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  3: not used,     healthy
[00:00:38.252,685] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4: not used,     healthy
[00:00:38.252,716] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:00:38.252,716] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:00:38.252,746] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 5 Using: 0 Unhealthy: 1
[00:00:38.252,746] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 38
[00:00:39.251,129] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:39.251,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4: not used,     healthy
[00:00:39.251,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:39.251,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 3 Using: 2 Unhealthy: 0
[00:00:39.251,159] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 39
[00:00:40.249,298] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:40.249,298] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4: not used,     healthy
[00:00:40.249,298] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:40.249,328] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 3 Using: 2 Unhealthy: 0
[00:00:40.249,328] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 40
[00:00:41.248,168] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:41.248,199] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4:     used,     healthy
[00:00:41.248,199] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:41.248,199] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 3 Using: 3 Unhealthy: 0
[00:00:41.248,229] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 41
[00:00:42.248,931] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:42.248,962] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4:     used,     healthy
[00:00:42.248,962] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:42.248,962] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 3 Using: 3 Unhealthy: 0
[00:00:42.248,992] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 42
[00:00:43.247,680] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:43.247,711] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4:     used,     healthy
[00:00:43.247,711] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:43.247,711] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 3 Using: 3 Unhealthy: 0
[00:00:43.247,711] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 43
[00:00:44.251,342] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:44.251,373] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4:     used,     healthy
[00:00:44.251,373] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:44.251,373] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 3 Using: 3 Unhealthy: 0
[00:00:44.251,403] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 44
[00:00:45.414,337] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:45.414,367] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:00:45.414,367] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4:     used,     healthy
[00:00:45.414,367] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:45.414,398] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 4 Using: 3 Unhealthy: 1
[00:00:45.414,398] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 45
[00:00:46.419,525] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  8:     used,     healthy
[00:00:46.419,555] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:00:46.419,555] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  4:     used,     healthy
[00:00:46.419,555] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  3: not used,     healthy
[00:00:46.419,555] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17:     used,     healthy
[00:00:46.419,586] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 5 Using: 3 Unhealthy: 1
[00:00:46.419,586] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 46

---------   A-GPS FIX   -----------
Time to fix : 33 seconds
-----------------------------------
GPS_EVT_SEARCH_STOPPED

seconds since the Epoch: 3566246400

Latitude  :  48.097130
Longitude :  8.707822
Altitude  :  669.8 m
Speed     :  0.2 m/s
Heading   :  0.0 deg
Date      :  2022-07-12
Time (UTC):  14:25:03

 

Menuconfig PSM settings: 

Chicago DK logs and PSM Settings:

AT+CEREG Response     - +CEREG: 5,5,"A21B","005CD918",7,,,"11100000","11100000"

GPS_EVT_SEARCH_STARTED
GPS_EVT_AGPS_DATA_NEEDED
[00:00:08.692,840] <dbg> nrf9160_gps.start: GPS operational
[00:00:08.695,220] <dbg> nrf9160_gps.gps_thread: A-GPS data update needed
[00:00:08.697,723] <dbg> agps.init_supl: Using GPS driver to input assistance data
[00:00:08.697,723] <inf> agps: SUPL is initialized
[00:00:08.971,191] <dbg> agps.open_supl_socket: Connecting to 142.250.138.192 port 7276
[00:00:09.089,630] <inf> agps: Starting SUPL session
[00:00:09.091,827] <dbg> agps.supl_logger: ULP encoding length: 35
[00:00:09.092,407] <dbg> agps.supl_logger: Bytes sent: 35
[00:00:09.193,389] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:09.193,939] <dbg> agps.supl_logger: ULP ossDecode success, choice 3
[00:00:09.194,061] <dbg> agps.supl_logger: SUPL server responded using version 2.0.4
[00:00:09.194,152] <dbg> agps.supl_logger: SUPL response received
[00:00:09.194,366] <dbg> agps.supl_logger: ULP encoding length: 54
[00:00:09.194,976] <dbg> agps.supl_logger: Bytes sent: 54
[00:00:09.600,891] <dbg> agps.supl_logger: Bytes received: 708, total 708
[00:00:09.601,440] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:09.850,372] <dbg> agps.supl_logger: Bytes received: 708, total 1416
[00:00:09.850,891] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:10.081,420] <dbg> agps.supl_logger: Bytes received: 708, total 2124
[00:00:10.081,970] <dbg> agps.supl_logger: ULP ossDecode more input 4
--- 3 messages dropped ---
[00:00:10.336,639] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 1
--- 7 messages dropped ---
[00:00:10.349,548] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:10.362,365] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:10.375,335] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:10.388,458] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 29 messages dropped ---
[00:00:10.444,000] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
--- 43 messages dropped ---
[00:00:10.454,284] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.455,078] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.455,078] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.455,657] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.455,688] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.456,359] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.456,359] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.456,939] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.456,939] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.457,733] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.457,763] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.458,587] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.458,618] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.459,197] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.459,197] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.459,777] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.459,808] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.460,540] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.460,571] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.461,303] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.461,303] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.461,883] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:10.461,883] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:10.461,944] <dbg> agps.supl_logger: No integrity data available
[00:00:10.462,585] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 4
[00:00:10.462,585] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 4, size: 8
[00:00:10.463,500] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 7
[00:00:10.463,531] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 6, size: 144
[00:00:10.463,989] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 8
[00:00:10.464,019] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 7, size: 16
[00:00:10.464,080] <dbg> agps.supl_logger: SUPL POS received
GPS_EVT_OPERATION_BLOCKED
[00:00:11.343,078] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:11.343,597] <dbg> agps.supl_logger: ULP ossDecode success, choice 6
[00:00:11.343,658] <dbg> agps.supl_logger: SUPLEND:
[00:00:11.343,719] <dbg> agps.supl_logger:      Mask: 0
[00:00:11.343,780] <dbg> agps.supl_logger:      Status: 0
[00:00:11.343,841] <dbg> agps.supl_logger: SUPL END received
[00:00:11.343,933] <dbg> agps.supl_logger: SUPL session internal resources released
[00:00:11.343,994] <dbg> agps.supl_logger: SUPL session finished
[00:00:11.343,994] <inf> agps: SUPL session finished successfully
[00:00:11.344,543] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate


---------   A-GPS FIX   -----------
Time to fix : 7 seconds
-----------------------------------
GPS_EVT_SEARCH_STOPPED

seconds since the Epoch: 3566246400

Latitude  :  42.117605
Longitude :  -87.949295
Altitude  :  180.5 m
Speed     :  0.3 m/s
Heading   :  0.0 deg
Date      :  2022-07-12
Time (UTC):  14:28:19



------------------------------------------------------------------------------------------------------------------------

AT+CEREG Response     - +CEREG: 5,5,"4119","0334D410",7,,,"00001000","00011111"

GPS_EVT_SEARCH_STARTED
GPS_EVT_AGPS_DATA_NEEDED
[00:00:36.933,410] <inf> lwm2m: Deregistered as SMS listener
[00:00:37.329,833] <dbg> nrf9160_gps.start: GPS operational
[00:00:37.332,183] <dbg> nrf9160_gps.gps_thread: A-GPS data update needed
[00:00:37.334,716] <dbg> agps.init_supl: Using GPS driver to input assistance data
[00:00:37.334,716] <inf> agps: SUPL is initialized
[00:00:40.892,150] <inf> lwm2m: Deregistered as SMS listener
[00:00:43.336,090] <wrn> date_time: sntp_query, error: -116
[00:00:43.732,574] <dbg> agps.open_supl_socket: Connecting to 142.250.138.192 port 7276
[mqtt_evt_handler:350] MQTT client connected!
Subscribing to: my/subscribe/topic len 18
Connected with TLS

[00:00:45.284,942] <inf> agps: Starting SUPL session
[00:00:45.287,322] <dbg> agps.supl_logger: ULP encoding length: 35
[00:00:45.287,902] <dbg> agps.supl_logger: Bytes sent: 35
[00:00:45.894,592] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:45.895,141] <dbg> agps.supl_logger: ULP ossDecode success, choice 3
[00:00:45.895,263] <dbg> agps.supl_logger: SUPL server responded using version 2.0.4
[00:00:45.895,355] <dbg> agps.supl_logger: SUPL response received
[00:00:45.895,538] <dbg> agps.supl_logger: ULP encoding length: 54
[00:00:45.896,301] <dbg> agps.supl_logger: Bytes sent: 54
[00:00:46.896,331] <dbg> agps.supl_logger: read again
[00:00:47.205,871] <dbg> agps.supl_logger: Bytes received: 708, total 708
[00:00:47.206,420] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:48.206,512] <dbg> agps.supl_logger: read again
[00:00:49.206,542] <dbg> agps.supl_logger: read again
[00:00:50.206,665] <dbg> agps.supl_logger: read again
[00:00:51.206,176] <dbg> agps.supl_logger: read again
[00:00:52.206,237] <dbg> agps.supl_logger: read again
[00:00:53.206,604] <dbg> agps.supl_logger: read again
[00:00:54.207,092] <dbg> agps.supl_logger: read again
[00:00:55.207,427] <dbg> agps.supl_logger: read again
[00:00:56Failed to request A-GPS data, error: -13GPS_EVT_OPERATION_BLOCKED

GPS_EVT_OPERATION_BLOCKED
.207,733] <dbg> agps.supl_logger: read again
[00:00:57.207,946] <dbg> agps.supl_logger: Timeout expired
[00:00:57.208,038] <dbg> agps.supl_logger: SUPL error: 3
[00:00:57.208,190] <dbg> agps.supl_logger: ULP encoding length: 31
[00:00:57.208,953] <dbg> agps.supl_logger: Bytes sent: 31
[00:00:57.209,045] <dbg> agps.supl_logger: SUPL session internal resources released
[00:00:57.209,106] <dbg> agps.supl_logger: SUPL session finished
[00:00:57.209,106] <err> agps: SUPL session failed, error: -13
[00:00:57.209,564] <err> agps: SUPL request failed, error: -13
[00:00:57.213,256] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate
[00:00:57.215,911] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:00:57.220,184] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 0 Using: 0 Unhealthy: 0
[00:00:57.220,214] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 57
[00:00:57.220,275] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate

[mqtt_evt_handler:508] SUBACK packet id: 1234
[mqtt_evt_handler:391] MQTT PUBLISH result=0 len=7

Received Data from Server: No Data


[00:01:15.780,548] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:01:15.785,034] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:15.785,034] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:15.785,034] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:15.785,034] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:15.785,064] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:15.785,064] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:15.785,064] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:15.785,064] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:15.785,095] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:15.785,095] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 75
[00:01:16.780,883] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:16.780,914] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:16.780,914] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:16.780,914] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:16.780,944] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:16.780,944] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:16.780,944] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:16.780,944] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:16.780,975] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:16.780,975] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 76
[00:01:17.780,944] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:17.780,975] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:17.780,975] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:17.780,975] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:17.781,005] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:17.781,005] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:17.781,005] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:17.781,005] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:17.781,036] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:17.781,036] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 77
GPS_EVT_OPERATION_BLOCKED
[00:01:19.069,488] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate
[00:01:20.054,199] <inf> lwm2m: Deregistered as SMS listener


[00:01:31.191,375] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:01:31.195,617] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:31.195,648] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:31.195,648] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:31.195,648] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:31.195,648] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:31.195,678] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:31.195,678] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:31.195,678] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:31.195,678] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:31.195,709] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 91
[00:01:32.191,375] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:32.191,375] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:32.191,406] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:32.191,406] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:32.191,406] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:32.191,406] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:32.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:32.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:32.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:32.191,436] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 92
[00:01:33.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:33.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:33.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:33.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:33.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:33.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:33.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:33.191,497] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:33.191,497] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:33.191,497] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 93
[00:01:34.191,864] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:34.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:34.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:34.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:34.191,925] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:34.191,925] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:34.191,925] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:34.191,925] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  7: not used,     healthy
[00:01:34.191,955] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:34.191,955] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:01:34.191,986] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 94
[00:01:35.191,711] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:35.191,711] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:35.191,711] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:35.191,741] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:35.191,741] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:35.191,741] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:35.191,741] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:35.191,772] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  7: not used,     healthy
[00:01:35.191,772] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:35.191,772] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:01:35.191,802] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 95
[00:01:36.190,979] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:36.190,979] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:36.190,979] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:36.191,009] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:36.191,009] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:36.191,009] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:36.191,009] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:36.191,040] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  7: not used,     healthy
[00:01:36.191,040] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:36.191,040] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:01:36.191,070] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 96
[00:01:37.191,406] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:37.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:37.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:37.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:37.191,436] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:37.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:37.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:37.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  7: not used,     healthy
[00:01:37.191,467] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:37.191,497] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:01:37.191,497] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 97
[00:01:38.191,864] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:38.191,864] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:38.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:38.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:38.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:38.191,894] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:38.192,047] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:38.192,047] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:38.192,047] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:38.192,077] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 98
[00:01:39.191,497] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:39.191,528] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:39.191,528] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:39.191,528] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:39.191,558] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:39.191,558] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:39.191,558] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:39.191,558] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:39.191,589] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:39.191,589] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 99
[00:01:40.190,795] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:40.190,795] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:40.190,795] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:40.190,826] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:40.190,826] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:40.190,826] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:40.190,826] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:40.190,856] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:40.190,856] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:40.190,856] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 100
[00:01:41.191,802] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:41.191,802] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:41.191,802] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:41.191,833] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:41.191,833] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:41.191,833] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:41.191,833] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:41.191,864] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:41.191,864] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:41.191,864] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 101
[00:01:42.191,589] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:42.191,589] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:42.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:42.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:42.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:42.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:42.191,650] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:42.191,650] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:42.191,650] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:42.191,650] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 102
[00:01:43.191,589] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:43.191,589] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:43.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:43.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:43.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:43.191,619] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:43.191,650] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:43.191,650] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:43.191,650] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 8 Using: 0 Unhealthy: 1
[00:01:43.191,650] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 103
[00:01:44.193,023] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:44.193,054] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:44.193,054] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:44.193,054] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:44.193,084] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:44.193,084] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:44.193,084] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:44.193,084] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 25: not used,     healthy
[00:01:44.193,115] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:44.193,115] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:01:44.193,115] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 104
[00:01:45.192,749] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:01:45.192,749] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:01:45.192,779] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:01:45.192,779] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:01:45.192,779] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:01:45.192,779] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:01:45.192,810] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:01:45.192,810] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 25: not used,     healthy
[00:01:45.192,810] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:01:45.192,810] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:01:45.192,840] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 105
GPS_EVT_OPERATION_BLOCKED
[00:01:47.098,876] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate
[mqtt_evt_handler:512] default: 9


[00:02:03.871,643] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:02:03.876,129] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:03.876,129] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:03.876,129] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:03.876,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:03.876,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:03.876,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:03.876,159] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:03.876,190] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:03.876,190] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:03.876,220] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:03.876,220] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 123
[00:02:04.871,246] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:04.871,276] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:04.871,276] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:04.871,276] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:04.871,276] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:04.871,307] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:04.871,307] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:04.871,307] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:04.871,307] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:04.871,337] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:04.871,337] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 124
[00:02:05.871,704] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:05.871,734] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:05.871,734] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:05.871,765] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:05.871,765] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:05.871,765] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:05.871,765] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:05.871,795] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:05.871,795] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:05.871,795] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:05.871,826] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 125
GPS_EVT_OPERATION_BLOCKED
[00:02:07.269,805] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate
[00:02:08.135,498] <inf> lwm2m: Deregistered as SMS listener


[00:02:19.834,594] <dbg> nrf9160_gps.gps_thread: GPS has time window to operate
[00:02:19.839,233] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:19.839,233] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:19.839,233] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:19.839,233] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:19.839,263] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:19.839,263] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:19.839,263] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:19.839,263] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:19.839,294] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:19.839,294] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 12: not used,     healthy
[00:02:19.839,294] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 10 Using: 0 Unhealthy: 1
[00:02:19.839,324] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 139
[00:02:20.834,594] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:20.834,625] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:20.834,625] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:20.834,625] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:20.834,625] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:20.834,655] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:20.834,655] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:20.834,655] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:20.834,655] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:20.834,686] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:20.834,686] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 140
[00:02:21.833,862] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:21.833,892] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:21.833,892] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:21.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:21.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:21.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:21.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:21.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:21.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:21.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:21.833,984] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 141
[00:02:22.833,892] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:22.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:22.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:22.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:22.833,923] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:22.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:22.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:22.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:22.833,953] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:22.833,984] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:22.833,984] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 142
[00:02:23.833,709] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:23.833,740] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:23.833,740] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:23.833,740] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:23.833,770] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:23.833,770] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:23.833,770] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:23.833,770] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:23.833,801] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:23.833,801] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 9 Using: 0 Unhealthy: 1
[00:02:23.833,801] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 143
[00:02:24.835,144] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:24.835,174] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:24.835,174] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:24.835,174] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:24.835,174] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:24.835,205] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:24.835,205] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:24.835,205] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:24.835,205] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:24.835,235] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 23: not used,     healthy
[00:02:24.835,235] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 10 Using: 0 Unhealthy: 1
[00:02:24.835,235] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 144
[00:02:25.835,479] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  2: not used,     healthy
[00:02:25.835,510] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 14: not used,     healthy
[00:02:25.835,510] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 11: not used,     healthy
[00:02:25.835,510] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV  6: not used,     healthy
[00:02:25.835,510] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 19: not used,     healthy
[00:02:25.835,540] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 17: not used,     healthy
[00:02:25.835,540] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 20: not used,     healthy
[00:02:25.835,540] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 28: not used, not healthy
[00:02:25.835,540] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 24: not used,     healthy
[00:02:25.835,571] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 23: not used,     healthy
[00:02:25.835,571] <dbg> nrf9160_gps.print_satellite_stats: Tracking SV 32: not used,     healthy
[00:02:25.835,571] <dbg> nrf9160_gps.print_satellite_stats: Tracking: 11 Using: 0 Unhealthy: 1
[00:02:25.835,601] <dbg> nrf9160_gps.print_satellite_stats: Seconds since last fix 145

---------   A-GPS FIX   -----------
Time to fix : 109 seconds
-----------------------------------
GPS_EVT_SEARCH_STOPPED

seconds since the Epoch: 3566246400

Latitude  :  42.117706
Longitude :  -87.949538
Altitude  :  174.0 m
Speed     :  0.1 m/s
Heading   :  0.0 deg
Date      :  2022-07-12
Time (UTC):  15:01:54



------------------------------------------------------------------------------------------------------------------------

AT+CEREG Response     - +CEREG: 5,5,"4119","0339910F",7,,,"00001000","00011111"

GPS_EVT_SEARCH_STARTED
GPS_EVT_AGPS_DATA_NEEDED
[00:00:07.753,326] <dbg> nrf9160_gps.start: GPS operational
[00:00:07.755,676] <dbg> nrf9160_gps.gps_thread: A-GPS data update needed
[00:00:07.758,178] <dbg> agps.init_supl: Using GPS driver to input assistance data
[00:00:07.758,178] <inf> agps: SUPL is initialized
[00:00:07.954,681] <dbg> agps.open_supl_socket: Connecting to 142.250.138.192 port 7276
[00:00:08.119,903] <inf> agps: Starting SUPL session
[00:00:08.122,253] <dbg> agps.supl_logger: ULP encoding length: 35
[00:00:08.122,833] <dbg> agps.supl_logger: Bytes sent: 35
[00:00:08.276,153] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:08.276,702] <dbg> agps.supl_logger: ULP ossDecode success, choice 3
[00:00:08.276,824] <dbg> agps.supl_logger: SUPL server responded using version 2.0.4
[00:00:08.276,916] <dbg> agps.supl_logger: SUPL response received
[00:00:08.277,130] <dbg> agps.supl_logger: ULP encoding length: 54
[00:00:08.277,770] <dbg> agps.supl_logger: Bytes sent: 54
[00:00:08.521,362] <dbg> agps.supl_logger: Bytes received: 708, total 708
[00:00:08.521,911] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:08.725,097] <dbg> agps.supl_logger: Bytes received: 708, total 1416
[00:00:08.725,646] <dbg> agps.supl_logger: ULP ossDecode more input 4
[00:00:08.939,361] <dbg> agps.supl_logger: Bytes received: 708, total 2124
[00:00:08.939,880] <dbg> agps.supl_logger: ULP ossDecode more input 4
--- 1 messages dropped ---
[00:00:09.276,824] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 1
--- 7 messages dropped ---
[00:00:09.287,078] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 8 messages dropped ---
[00:00:09.303,588] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 6 messages dropped ---
[00:00:09.313,690] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 7 messages dropped ---
[00:00:09.326,995] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 2
--- 23 messages dropped ---
[00:00:09.383,697] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
--- 51 messages dropped ---
[00:00:09.395,965] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.396,728] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.396,728] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.397,308] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.397,308] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.397,949] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.397,949] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.398,712] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.398,742] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.399,322] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.399,322] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.399,963] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.399,963] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.400,726] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.400,756] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.401,306] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.401,336] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.401,947] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.401,977] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.402,740] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.402,740] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.403,320] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 3
[00:00:09.403,320] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 3, size: 32
[00:00:09.403,411] <dbg> agps.supl_logger: No integrity data available
[00:00:09.403,869] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 4
[00:00:09.403,869] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 4, size: 8
[00:00:09.404,815] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 7
[00:00:09.404,815] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 6, size: 144
[00:00:09.405,303] <dbg> nrf9160_gps.agps_write: Sent A-GPS data to modem, type: 8
[00:00:09.405,303] <dbg> agps.inject_agps_type: Injected A-GPS data, type: 7, size: 16
[00:00:09.405,364] <dbg> agps.supl_logger: SUPL POS received
[00:GPS_EVT_OPERATION_BLOCKED
00:10.405,426] <dbg> agps.supl_logger: read again
[00:00:10.453,308] <dbg> agps.supl_logger: Bytes received: 30, total 30
[00:00:10.453,857] <dbg> agps.supl_logger: ULP ossDecode success, choice 6
[00:00:10.453,887] <dbg> agps.supl_logger: SUPLEND:
[00:00:10.453,948] <dbg> agps.supl_logger:      Mask: 0
[00:00:10.454,010] <dbg> agps.supl_logger:      Status: 0
[00:00:10.454,071] <dbg> agps.supl_logger: SUPL END received
[00:00:10.454,162] <dbg> agps.supl_logger: SUPL session internal resources released
[00:00:10.454,223] <dbg> agps.supl_logger: SUPL session finished
[00:00:10.454,223] <inf> agps: SUPL session finished successfully
[00:00:10.454,803] <dbg> nrf9160_gps.gps_thread: Waiting for time window to operate


[00:00:16.312,866] <dbg> n
---------   A-GPS FIX   -----------
Time to fix : 9 seconds
-----------------------------------
rfGPS_EVT_SEARCH_STOPPED


seconds since the Epoch: 3566246400

Latitude  :  42.117715
Longitude :  -87.949566
Altitude  :  172.1 m
Speed     :  0.3 m/s
Heading   :  0.0 deg
Date      :  2022-07-12
Time (UTC):  14:54:16

Menuconfig PSM settings: 

Observation: 

Aldingen DK Unit: It always download AGPS data without any trouble and works fine for TTFF. 

Chicago DK unit: I have observed 3 different cases. 

1. AT+CEREG Response     - +CEREG: 5,5,"A21B","005CD918",7,,,"11100000","11100000" 

- We can see here that the RAU and TAU 6 to 8 bits are "111" but still it was able to get GPS FIX without any issue. What is wrong here ? 

2. AT+CEREG Response     - +CEREG: 5,5,"4119","0334D410",7,,,"00001000","00011111"

- In this case DK was in PSM mode but somehow it struggled to download the AGPS data and agps.supl_logger: Timeout happened. But finally after searching for long time it got the GPS fix. I think it can be because of the MQTT keep alive time. 

3. AT+CEREG Response     - +CEREG: 5,5,"4119","0339910F",7,,,"00001000","00011111"

- I think here it all went good and DK gets its TTFF within 9 Seconds.

Questions: 

1. Can you please explain what is wrong with 1st and 2nd observation logs of Chicago Unit ?

2. What is the meaning / abbreviation for "A21B" and "4119" in CEREG response? 

Solution I thought: 

I have created an alternative to solve this issue which is mentioned in below steps: 

1. Connect to LTE.

2. Download the AGPS data.

3. Deactivates LTE without shutting down GNSS services.

4. Get the AGPS fix.

5. Activates LTE without changing GNSS.

6. Stop. 

Please provide your suggestion if there are any additional steps are require or need to take care to make the firmware more robust. Or please suggest any other alternatives are there with you. 

Thank you!

Regards,

Chetan