Difficulty to get agps data

[ActiTAG]

Hello,

I have a problem with the GPS and more precisely the AGPS. I would like to retrieve my position with the thingy91, I created a project by taking the AGPS sample and add the sending of information (GPS and RSRP) via MQTT in NB-IoT. To retrieve the AGPS data I use the NRF Cloud. However, I face two problems.

The first is when connecting to the cloud, the event CLOUD_EVT_CONNECTING is very long and it ends up failing to connect. The connection is very weak at my place (I have an RSRP of -115 dB, but I can send data), is the problem the power? I tried to move to one with a slightly better RSRP (-100 dB) and there I managed to connect to the cloud but the AGPS request fails. So this is the second problem. Here are the logs:

021-06-10T06:26:44.474Z DEBUG modem << +CEREG: 2,"C38C","002580CF",9,0,0,"11100000","11100000"
2021-06-10T06:26:44.879Z DEBUG modem << +CSCON: 1
2021-06-10T06:26:45.174Z DEBUG modem << +CEREG: 4,"C38C","002580CF",9,0,15,"11100000","11100000"
2021-06-10T06:26:45.442Z DEBUG modem << +CSCON: 0
2021-06-10T06:26:45.887Z DEBUG modem << +CEREG: 4,"FFFE","FFFFFFFF",9,0,15,"11100000","11100000"
2021-06-10T06:26:49.666Z DEBUG modem << +CEREG: 2,"174D","01626B66",9,0,15,"11100000","11100000"
2021-06-10T06:26:50.359Z DEBUG modem << +CSCON: 1
2021-06-10T06:27:19.406Z DEBUG modem << +CEREG: 4,"174D","01626B66",9,0,15,"11100000","11100000"
2021-06-10T06:27:24.257Z DEBUG modem << +CSCON: 0
2021-06-10T06:27:24.517Z DEBUG modem << +CEREG: 4,"FFFE","FFFFFFFF",9,0,15,"11100000","11100000"
2021-06-10T06:27:24.835Z DEBUG modem << +CEREG: 4,"174D","01626B66",9,0,15,"11100000","11100000"
2021-06-10T06:27:26.909Z DEBUG modem << W: getaddrinfo, error: -11
2021-06-10T06:27:26.923Z DEBUG modem << W: getaddrinfo, error: -11
2021-06-10T06:27:26.927Z DEBUG modem << W: getaddrinfo, error: -11
2021-06-10T06:27:26.936Z DEBUG modem << W: getaddrinfo, error: -11
2021-06-10T06:27:26.948Z DEBUG modem << W: getaddrinfo, error: -11
2021-06-10T06:27:26.950Z DEBUG modem << W: Not getting time from any NTP server
2021-06-10T06:28:13.083Z DEBUG modem << +CEREG: 2,"BAEB","01136C65",9,0,15,"11100000","11100000"
2021-06-10T06:28:13.645Z DEBUG modem << +CSCON: 1
2021-06-10T06:28:14.861Z DEBUG modem << +CEREG: 5,"BAEB","01136C65",9,,,"11100000","11100000"
2021-06-10T06:28:14.878Z DEBUG modem << LTE Link Connected!
2021-06-10T06:28:15.465Z DEBUG modem << [Info] Operator: 26202 
2021-06-10T06:28:15.468Z DEBUG modem << I: A-GPS has started
2021-06-10T06:28:15.476Z DEBUG modem << I: CLOUD_EVT_CONNECTING
2021-06-10T06:28:24.866Z DEBUG modem << I: CLOUD_EVT_CONNECTED
2021-06-10T06:28:26.950Z DEBUG modem << I: CLOUD_EVT_DATA_RECEIVED
2021-06-10T06:28:26.963Z DEBUG modem << I: CLOUD_EVT_PAIR_DONE
2021-06-10T06:28:30.627Z DEBUG modem << I: CLOUD_EVT_READY
2021-06-10T06:28:30.629Z DEBUG modem << I: Service info sent to cloud
2021-06-10T06:28:30.642Z DEBUG modem << I: GPS search started with timeout 60 s
2021-06-10T06:28:30.645Z DEBUG modem << I: GPS_EVT_SEARCH_STARTED
2021-06-10T06:28:30.648Z DEBUG modem << I: GPS_EVT_AGPS_DATA_NEEDED
2021-06-10T06:28:30.681Z DEBUG modem << E: Link data not obtained: 20 -5
2021-06-10T06:28:30.683Z DEBUG modem << E: Network data not obtained: -5
2021-06-10T06:28:30.687Z DEBUG modem << E: Could not obtain cell information
2021-06-10T06:28:30.689Z DEBUG modem << E: nRF Cloud A-GPS request failed, error: -11
2021-06-10T06:28:30.694Z DEBUG modem << E: Failed to request A-GPS data, error: -11
2021-06-10T06:28:30.713Z DEBUG modem << E: Link data not obtained: 20 -5
2021-06-10T06:28:30.718Z DEBUG modem << E: Network data not obtained: -5
2021-06-10T06:28:30.720Z DEBUG modem << E: Could not obtain cell information

My goal is to make a heat map of the NB-IoT connection power in my city. If you have an idea and tips to solve or work around the problem I thank you in advance!

[Didrik Rokhaug]

Hi,

It looks like the device is struggeling to get connected to the network.

Could you enable some more logging, and send the log to me?

CONFIG_AT_CMD_LOG_LEVEL_DBG=y
CONFIG_NRF_CLOUD_LOG_LEVEL_DBG=y
CONFIG_AGPS_LOG_LEVEL_DBG=y
CONFIG_NRF_CLOUD_AGPS_LOG_LEVEL_DBG=y

Best regards,

Didrik

[ActiTAG]

Didrik Rokhaug said:

It looks like the device is struggeling to get connected to the network.

Yes, I know. Our city has a poor NB-IoT connection.

2021-06-11T06:52:35.549Z INFO Application data folder: C:\Users\tgasser\AppData\Roaming\nrfconnect\pc-nrfconnect-linkmonitor
2021-06-11T06:52:35.609Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-06-11T06:52:35.609Z DEBUG App path: C:\Users\tgasser\.nrfconnect-apps\node_modules\pc-nrfconnect-linkmonitor
2021-06-11T06:52:35.610Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-06-11T06:52:35.610Z DEBUG nRFConnect path: C:\Users\tgasser\AppData\Local\Programs\nrfconnect\resources\app.asar
2021-06-11T06:52:35.611Z DEBUG HomeDir: C:\Users\tgasser
2021-06-11T06:52:35.611Z DEBUG TmpDir: C:\Users\tgasser\AppData\Local\Temp
2021-06-11T06:52:43.601Z INFO Modem port is opened
2021-06-11T06:53:03.548Z DEBUG modem << D: at_cmd_rx 58 bytes, +CEREG: 2,"D2FF","023C5009",9,0,0,"11100000","11100000"
2021-06-11T06:53:03.552Z DEBUG modem << +CEREG: 2,"D2FF","023C5009",9,0,0,"11100000","11100000"
2021-06-11T06:53:03.575Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:03.577Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:03.988Z DEBUG modem << D: at_cmd_rx 12 bytes, +CSCON: 1
2021-06-11T06:53:03.990Z DEBUG modem << +CSCON: 1
2021-06-11T06:53:03.991Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:03.993Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:09.376Z DEBUG modem << D: at_cmd_rx 56 bytes, +CEREG: 5,"D2FF","023C5009",9,,,"11100000","01011111"
2021-06-11T06:53:09.378Z DEBUG modem << +CEREG: 5,"D2FF","023C5009",9,,,"11100000","01011111"
2021-06-11T06:53:09.390Z DEBUG modem << LTE Link Connected!
2021-06-11T06:53:09.392Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:09.393Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:09.974Z DEBUG modem << D: Sending command AT+COPS?
2021-06-11T06:53:10.003Z DEBUG modem << D: Awaiting response for AT+COPS?
2021-06-11T06:53:10.006Z DEBUG modem << D: at_cmd_rx 27 bytes, +COPS: 0,2,"26201",9
2021-06-11T06:53:10.008Z DEBUG modem << OK
2021-06-11T06:53:10.010Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:53:10.014Z DEBUG modem << D: Awaiting response for AT+CESQ
2021-06-11T06:53:10.019Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:10.022Z DEBUG modem << D: Sending command AT+CESQ
2021-06-11T06:53:10.036Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:10.042Z DEBUG modem << D: at_cmd_rx 32 bytes, +CESQ: 99,99,255,255,4,18
2021-06-11T06:53:10.044Z DEBUG modem << OK
2021-06-11T06:53:10.046Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:53:10.047Z DEBUG modem << [Info] Operator: 26201 , rsrp : 18
2021-06-11T06:53:10.048Z DEBUG modem << I: A-GPS has started
2021-06-11T06:53:10.050Z DEBUG modem << D: Awaiting response for AT+CGSN
2021-06-11T06:53:10.051Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:10.053Z DEBUG modem << D: Sending command AT+CGSN
2021-06-11T06:53:10.112Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:10.114Z DEBUG modem << D: at_cmd_rx 22 bytes, 352656101500240
2021-06-11T06:53:10.116Z DEBUG modem << OK
2021-06-11T06:53:10.123Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:53:10.124Z DEBUG modem << D: client_id = nrf-352656101500240
2021-06-11T06:53:10.126Z DEBUG modem << D: shadow_base_topic: $aws/things/nrf-352656101500240/shadow
2021-06-11T06:53:10.129Z DEBUG modem << D: accepted_topic: nrf-352656101500240/shadow/get/accepted
2021-06-11T06:53:10.130Z DEBUG modem << D: rejected_topic: $aws/things/nrf-352656101500240/shadow/get/rejected
2021-06-11T06:53:10.132Z DEBUG modem << D: update_delta_topic: $aws/things/nrf-352656101500240/shadow/update/delta
2021-06-11T06:53:10.133Z DEBUG modem << D: update_topic: $aws/things/nrf-352656101500240/shadow/update
2021-06-11T06:53:10.134Z DEBUG modem << D: shadow_get_topic: $aws/things/nrf-352656101500240/shadow/get
2021-06-11T06:53:10.136Z DEBUG modem << D: Awaiting response for AT%XMAGPIO=1,1,1,7,1,746,803,2,698,748,2,1710,2200,3,824,894,4,880,960,5,791,849,7,1565,1586
2021-06-11T06:53:10.138Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:10.143Z DEBUG modem << D: Sending command AT%XMAGPIO=1,1,1,7,1,746,803,2,698,748,2,1710,2200,3,824,894,4,880,960,5,791,849,7,1565,1586
2021-06-11T06:53:10.160Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:10.164Z DEBUG modem << D: at_cmd_rx 5 bytes, OK
2021-06-11T06:53:10.166Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:53:10.170Z DEBUG modem << D: MAGPIO set: AT%XMAGPIO=1,1,1,7,1,746,803,2,698,748,2,1710,2200,3,824,894,4,880,960,5,791,849,7,1565,1586
2021-06-11T06:53:10.173Z DEBUG modem << D: Awaiting response for AT%XCOEX0=1,1,1565,1586
2021-06-11T06:53:10.174Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:10.177Z DEBUG modem << D: Sending command AT%XCOEX0=1,1,1565,1586
2021-06-11T06:53:10.190Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:10.192Z DEBUG modem << D: at_cmd_rx 5 bytes, OK
2021-06-11T06:53:10.195Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:53:10.198Z DEBUG modem << D: COEX0 set: AT%XCOEX0=1,1,1565,1586
2021-06-11T06:53:10.200Z DEBUG modem << D: GPS socket created, fd: 1232491587
2021-06-11T06:53:10.202Z DEBUG modem << D: Writing any pending command
2021-06-11T06:53:10.203Z DEBUG modem << D: Listening on socket
2021-06-11T06:53:10.204Z DEBUG modem << D: NRF_CLOUD_EVT_TRANSPORT_CONNECTING
2021-06-11T06:53:10.206Z DEBUG modem << I: CLOUD_EVT_CONNECTING
2021-06-11T06:53:11.603Z DEBUG modem << D: IPv4 address: 54.205.81.248
2021-06-11T06:53:11.605Z DEBUG modem << D: MQTT clean session flag: 1
2021-06-11T06:53:58.696Z DEBUG modem << D: Cloud connection request sent.
2021-06-11T06:54:01.743Z DEBUG modem << D: MQTT_EVT_CONNACK: result 0
2021-06-11T06:54:01.745Z DEBUG modem << D: state: 2
2021-06-11T06:54:01.746Z DEBUG modem << D: NRF_CLOUD_EVT_TRANSPORT_CONNECTED
2021-06-11T06:54:01.747Z DEBUG modem << I: CLOUD_EVT_CONNECTED
2021-06-11T06:54:01.748Z DEBUG modem << D: nct_cc_connect
2021-06-11T06:54:01.749Z DEBUG modem << D: state: 3
2021-06-11T06:54:02.414Z DEBUG modem << D: MQTT_EVT_SUBACK: id = 1234 result = 0
2021-06-11T06:54:02.416Z DEBUG modem << D: state: 4
2021-06-11T06:54:02.418Z DEBUG modem << D: mqtt_publish: id = 5678 opcode = 0 len = 0
2021-06-11T06:54:02.419Z DEBUG modem << D: state: 5
2021-06-11T06:54:03.061Z DEBUG modem << D: MQTT_EVT_PUBACK: id = 5678 result = 0
2021-06-11T06:54:03.063Z DEBUG modem << D: state: 5
2021-06-11T06:54:03.994Z DEBUG modem << D: MQTT_EVT_PUBLISH: id = 26656 len = 317
2021-06-11T06:54:03.995Z DEBUG modem << D: state: 5
2021-06-11T06:54:03.997Z DEBUG modem << D: NRF_CLOUD_EVT_RX_DATA
2021-06-11T06:54:03.998Z DEBUG modem << I: CLOUD_EVT_DATA_RECEIVED
2021-06-11T06:54:04.007Z DEBUG modem << D: nct_dc_endpoint_set
2021-06-11T06:54:04.009Z DEBUG modem << D: nct_dc_endpoint_get
2021-06-11T06:54:04.013Z DEBUG modem << D: mqtt_publish: id = 7890 opcode = 1 len = 361
2021-06-11T06:54:04.017Z DEBUG modem << D: state: 7
2021-06-11T06:54:04.019Z DEBUG modem << D: NRF_CLOUD_EVT_USER_ASSOCIATED
2021-06-11T06:54:04.021Z DEBUG modem << I: CLOUD_EVT_PAIR_DONE
2021-06-11T06:54:06.313Z DEBUG modem << D: MQTT_EVT_PUBACK: id = 7890 result = 0
2021-06-11T06:54:06.315Z DEBUG modem << D: nct_dc_connect
2021-06-11T06:54:06.316Z DEBUG modem << D: state: 8
2021-06-11T06:54:07.701Z DEBUG modem << D: MQTT_EVT_SUBACK: id = 8765 result = 0
2021-06-11T06:54:07.704Z DEBUG modem << D: state: 9
2021-06-11T06:54:07.705Z DEBUG modem << D: NRF_CLOUD_EVT_READY
2021-06-11T06:54:07.707Z DEBUG modem << I: CLOUD_EVT_READY
2021-06-11T06:54:07.708Z DEBUG modem << D: mqtt_publish: id = 1 opcode = 1 len = 70
2021-06-11T06:54:07.709Z DEBUG modem << I: Service info sent to cloud
2021-06-11T06:54:07.711Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:07.711Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:07.713Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:07.714Z DEBUG modem << OK
2021-06-11T06:54:07.716Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.718Z DEBUG modem << D: GPS mode is enabled
2021-06-11T06:54:07.720Z DEBUG modem << D: Awaiting response for AT+CFUN?
2021-06-11T06:54:07.722Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.723Z DEBUG modem << D: Sending command AT+CFUN?
2021-06-11T06:54:07.724Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.726Z DEBUG modem << D: at_cmd_rx 15 bytes, +CFUN: 1
2021-06-11T06:54:07.728Z DEBUG modem << OK
2021-06-11T06:54:07.729Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.730Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.730Z DEBUG modem << D:D: GPS operational
2021-06-11T06:54:07.731Z DEBUG modem << I: GPS search started with timeout 60 s
2021-06-11T06:54:07.732Z DEBUG modem <<  Listening on socket
2021-06-11T06:54:07.733Z DEBUG modem << I: GPS_EVT_SEARCH_STARTED
2021-06-11T06:54:07.733Z DEBUG modem << D: A-GPS data update needed
2021-06-11T06:54:07.734Z DEBUG modem << I: GPS_EVT_AGPS_DATA_NEEDED
2021-06-11T06:54:07.735Z DEBUG modem << D: Sending command AT%XCBAND
2021-06-11T06:54:07.739Z DEBUG modem << D: Awaiting response for AT%XCBAND
2021-06-11T06:54:07.740Z DEBUG modem << D: at_cmd_rx 17 bytes, %XCBAND: 8
2021-06-11T06:54:07.742Z DEBUG modem << OK
2021-06-11T06:54:07.890Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.891Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.892Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.894Z DEBUG modem << D: Sending command AT%XCBAND=?
2021-06-11T06:54:07.894Z DEBUG modem << D: Awaiting response for AT%XCBAND=?
2021-06-11T06:54:07.895Z DEBUG modem << D: at_cmd_rx 22 bytes, %XCBAND: (8,20)
2021-06-11T06:54:07.896Z DEBUG modem << OK
2021-06-11T06:54:07.896Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.897Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.898Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.898Z DEBUG modem << D: Sending command AT+CGDCONT?
2021-06-11T06:54:07.899Z DEBUG modem << D: Awaiting response for AT+CGDCONT?
2021-06-11T06:54:07.900Z DEBUG modem << D: at_cmd_rx 58 bytes, +CGDCONT: 0,"IP","iot.1nce.net","100.119.232.1",0,0
2021-06-11T06:54:07.901Z DEBUG modem << OK
2021-06-11T06:54:07.902Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.903Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.904Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.905Z DEBUG modem << D: Sending command AT+CEMODE?
2021-06-11T06:54:07.906Z DEBUG modem << D: at_cmd_rx 17 bytes, +CEMODE: 0
2021-06-11T06:54:07.907Z DEBUG modem << OK
2021-06-11T06:54:07.907Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.908Z DEBUG modem << D: Awaiting response for AT+CEMODE?
2021-06-11T06:54:07.909Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.910Z DEBUG modem << D: Sending command AT+COPS?
2021-06-11T06:54:07.910Z DEBUG modem << D: Awaiting response for AT+COPS?
2021-06-11T06:54:07.911Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.912Z DEBUG modem << D: at_cmd_rx 27 bytes, +COPS: 0,2,"26201",9
2021-06-11T06:54:07.915Z DEBUG modem << OK
2021-06-11T06:54:07.916Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.917Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.919Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.920Z DEBUG modem << D: Sending command AT+CEREG?
2021-06-11T06:54:07.922Z DEBUG modem << D: Awaiting response for AT+CEREG?
2021-06-11T06:54:07.922Z DEBUG modem << D: at_cmd_rx 62 bytes, +CEREG: 5,5,"D2FF","023C5009",9,,,"11100000","01011111"
2021-06-11T06:54:07.923Z DEBUG modem << OK
2021-06-11T06:54:07.924Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.925Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.925Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.927Z DEBUG modem << D: Sending command AT+CEREG?
2021-06-11T06:54:07.929Z DEBUG modem << D: Awaiting response for AT+CEREG?
2021-06-11T06:54:07.930Z DEBUG modem << D: at_cmd_rx 62 bytes, +CEREG: 5,5,"D2FF","023C5009",9,,,"11100000","01011111"
2021-06-11T06:54:07.930Z DEBUG modem << OK
2021-06-11T06:54:07.933Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.935Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.936Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.938Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:07.939Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:07.940Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:07.942Z DEBUG modem << OK
2021-06-11T06:54:07.944Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.946Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.949Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.950Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:07.952Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:07.953Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:07.954Z DEBUG modem << OK
2021-06-11T06:54:07.955Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:07.956Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:07.958Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:07.959Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:07.961Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:07.962Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:07.963Z DEBUG modem << OK
2021-06-11T06:54:08.074Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.076Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.077Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.078Z DEBUG modem << D: Sending command AT+CGDCONT?
2021-06-11T06:54:08.079Z DEBUG modem << D: Awaiting response for AT+CGDCONT?
2021-06-11T06:54:08.080Z DEBUG modem << D: at_cmd_rx 58 bytes, +CGDCONT: 0,"IP","iot.1nce.net","100.119.232.1",0,0
2021-06-11T06:54:08.080Z DEBUG modem << OK
2021-06-11T06:54:08.081Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.081Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.082Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.083Z DEBUG modem << D: Sending command AT+CCLK?
2021-06-11T06:54:08.083Z DEBUG modem << D: Awaiting response for AT+CCLK?
2021-06-11T06:54:08.085Z DEBUG modem << D: at_cmd_rx 8 bytes, ERROR
2021-06-11T06:54:08.085Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.086Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.087Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.088Z DEBUG modem << E: Link data not obtained: 20 -5
2021-06-11T06:54:08.089Z DEBUG modem << E: Network data not obtained: -5
2021-06-11T06:54:08.091Z DEBUG modem << E: Could not obtain cell information
2021-06-11T06:54:08.093Z DEBUG modem << E: nRF Cloud A-GPS request failed, error: -11
2021-06-11T06:54:08.093Z DEBUG modem << E: Failed to request A-GPS data, error: -11
2021-06-11T06:54:08.094Z DEBUG modem << D: Sending command AT%XCBAND
2021-06-11T06:54:08.095Z DEBUG modem << D: Awaiting response for AT%XCBAND
2021-06-11T06:54:08.097Z DEBUG modem << D: at_cmd_rx 17 bytes, %XCBAND: 8
2021-06-11T06:54:08.099Z DEBUG modem << OK
2021-06-11T06:54:08.099Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.100Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.101Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.103Z DEBUG modem << D: Sending command AT%XCBAND=?
2021-06-11T06:54:08.104Z DEBUG modem << D: Awaiting response for AT%XCBAND=?
2021-06-11T06:54:08.105Z DEBUG modem << D: at_cmd_rx 22 bytes, %XCBAND: (8,20)
2021-06-11T06:54:08.106Z DEBUG modem << OK
2021-06-11T06:54:08.107Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.108Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.110Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.111Z DEBUG modem << D: Sending command AT+CGDCONT?
2021-06-11T06:54:08.112Z DEBUG modem << D: Awaiting response for AT+CGDCONT?
2021-06-11T06:54:08.114Z DEBUG modem << D: at_cmd_rx 58 bytes, +CGDCONT: 0,"IP","iot.1nce.net","100.119.232.1",0,0
2021-06-11T06:54:08.115Z DEBUG modem << OK
2021-06-11T06:54:08.116Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.118Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.119Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.120Z DEBUG modem << D: Sending command AT+CEMODE?
2021-06-11T06:54:08.121Z DEBUG modem << D: at_cmd_rx 17 bytes, +CEMODE: 0
2021-06-11T06:54:08.123Z DEBUG modem << OK
2021-06-11T06:54:08.124Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.126Z DEBUG modem << D: Awaiting response for AT+CEMODE?
2021-06-11T06:54:08.127Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.128Z DEBUG modem << D: Sending command AT+COPS?
2021-06-11T06:54:08.129Z DEBUG modem << D: Awaiting response for AT+COPS?
2021-06-11T06:54:08.130Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.132Z DEBUG modem << D: at_cmd_rx 27 bytes, +COPS: 0,2,"26201",9
2021-06-11T06:54:08.136Z DEBUG modem << OK
2021-06-11T06:54:08.137Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.139Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.139Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.140Z DEBUG modem << D: Sending command AT+CEREG?
2021-06-11T06:54:08.141Z DEBUG modem << D: Awaiting response for AT+CEREG?
2021-06-11T06:54:08.141Z DEBUG modem << D: at_cmd_rx 62 bytes, +CEREG: 5,5,"D2FF","023C5009",9,,,"11100000","01011111"
2021-06-11T06:54:08.142Z DEBUG modem << OK
2021-06-11T06:54:08.142Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.143Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.216Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.217Z DEBUG modem << D: Sending command AT+CEREG?
2021-06-11T06:54:08.218Z DEBUG modem << D: Awaiting response for AT+CEREG?
2021-06-11T06:54:08.219Z DEBUG modem << D: at_cmd_rx 62 bytes, +CEREG: 5,5,"D2FF","023C5009",9,,,"11100000","01011111"
2021-06-11T06:54:08.219Z DEBUG modem << OK
2021-06-11T06:54:08.220Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.220Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.221Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.222Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:08.222Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:08.223Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:08.226Z DEBUG modem << OK
2021-06-11T06:54:08.227Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.228Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.229Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.230Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:08.231Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:08.232Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:08.232Z DEBUG modem << OK
2021-06-11T06:54:08.233Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.234Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.235Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.236Z DEBUG modem << D: Sending command AT%XSYSTEMMODE?
2021-06-11T06:54:08.237Z DEBUG modem << D: Awaiting response for AT%XSYSTEMMODE?
2021-06-11T06:54:08.239Z DEBUG modem << D: at_cmd_rx 28 bytes, %XSYSTEMMODE: 0,1,1,0
2021-06-11T06:54:08.240Z DEBUG modem << OK
2021-06-11T06:54:08.240Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.241Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.242Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.243Z DEBUG modem << D: Sending command AT+CGDCONT?
2021-06-11T06:54:08.244Z DEBUG modem << D: Awaiting response for AT+CGDCONT?
2021-06-11T06:54:08.246Z DEBUG modem << D: at_cmd_rx 58 bytes, +CGDCONT: 0,"IP","iot.1nce.net","100.119.232.1",0,0
2021-06-11T06:54:08.247Z DEBUG modem << OK
2021-06-11T06:54:08.247Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.248Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.248Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.249Z DEBUG modem << D: Sending command AT+CCLK?
2021-06-11T06:54:08.250Z DEBUG modem << D: Awaiting response for AT+CCLK?
2021-06-11T06:54:08.250Z DEBUG modem << D: at_cmd_rx 8 bytes, ERROR
2021-06-11T06:54:08.251Z DEBUG modem << D: Enqueueing response for sync call
2021-06-11T06:54:08.252Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:08.253Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:08.254Z DEBUG modem << E: Link data not obtained: 20 -5
2021-06-11T06:54:08.255Z DEBUG modem << E: Network data not obtained: -5
2021-06-11T06:54:08.258Z DEBUG modem << E: Could not obtain cell information
2021-06-11T06:54:08.259Z DEBUG modem << E: nRF Cloud A-GPS request failed, error: -11
2021-06-11T06:54:08.261Z DEBUG modem << D: MQTT_EVT_PUBACK: id = 1 result = 0
2021-06-11T06:54:08.286Z DEBUG modem << IPv4 Address found 137.135.83.217
2021-06-11T06:54:08.687Z DEBUG modem << D: Waiting for time window to operate
2021-06-11T06:54:08.688Z DEBUG modem << I: GPS_EVT_OPERATION_BLOCKED
2021-06-11T06:54:08.870Z DEBUG modem << [mqtt_evt_handler:811] MQTT client connected!
2021-06-11T06:54:08.920Z DEBUG modem << Subscribing to: c712c63d8998/python 
2021-06-11T06:54:10.620Z DEBUG modem << [mqtt_evt_handler:869] SUBACK packet id: 36466
2021-06-11T06:54:18.690Z DEBUG modem << D: GPS priority enabled
2021-06-11T06:54:43.526Z DEBUG modem << D: at_cmd_rx 12 bytes, +CSCON: 0
2021-06-11T06:54:43.527Z DEBUG modem << +CSCON: 0
2021-06-11T06:54:43.528Z DEBUG modem << D: Writing any pending command
2021-06-11T06:54:43.529Z DEBUG modem << D: Listening on socket
2021-06-11T06:54:43.530Z DEBUG modem << D: GPS has time window to operate
2021-06-11T06:54:43.531Z DEBUG modem << I: GPS_EVT_OPERATION_UNBLOCKED
2021-06-11T06:54:43.533Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:43.546Z DEBUG modem << D: Seconds since last fix 121
2021-06-11T06:54:44.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:44.520Z DEBUG modem << D: Seconds since last fix 122
2021-06-11T06:54:45.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:45.520Z DEBUG modem << D: Seconds since last fix 123
2021-06-11T06:54:46.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:46.519Z DEBUG modem << D: Seconds since last fix 124
2021-06-11T06:54:47.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:47.518Z DEBUG modem << D: Seconds since last fix 125
2021-06-11T06:54:48.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:48.519Z DEBUG modem << D: Seconds since last fix 126
2021-06-11T06:54:49.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:49.520Z DEBUG modem << D: Seconds since last fix 127
2021-06-11T06:54:50.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:50.519Z DEBUG modem << D: Seconds since last fix 128
2021-06-11T06:54:51.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:51.519Z DEBUG modem << D: Seconds since last fix 129
2021-06-11T06:54:52.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:52.520Z DEBUG modem << D: Seconds since last fix 130
2021-06-11T06:54:53.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:53.520Z DEBUG modem << D: Seconds since last fix 131
2021-06-11T06:54:54.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:54.518Z DEBUG modem << D: Seconds since last fix 132
2021-06-11T06:54:55.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:55.519Z DEBUG modem << D: Seconds since last fix 133
2021-06-11T06:54:56.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:56.518Z DEBUG modem << D: Seconds since last fix 134
2021-06-11T06:54:57.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:57.519Z DEBUG modem << D: Seconds since last fix 135
2021-06-11T06:54:58.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:58.519Z DEBUG modem << D: Seconds since last fix 136
2021-06-11T06:54:59.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:54:59.518Z DEBUG modem << D: Seconds since last fix 137
2021-06-11T06:55:00.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:00.519Z DEBUG modem << D: Seconds since last fix 138
2021-06-11T06:55:01.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:01.518Z DEBUG modem << D: Seconds since last fix 139
2021-06-11T06:55:02.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:02.519Z DEBUG modem << D: Seconds since last fix 140
2021-06-11T06:55:03.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:03.519Z DEBUG modem << D: Seconds since last fix 141
2021-06-11T06:55:04.517Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:04.518Z DEBUG modem << D: Seconds since last fix 142
2021-06-11T06:55:05.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:05.519Z DEBUG modem << D: Seconds since last fix 143
2021-06-11T06:55:06.518Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:06.519Z DEBUG modem << D: Seconds since last fix 144
2021-06-11T06:55:08.010Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:08.012Z DEBUG modem << D: Seconds since last fix 146
2021-06-11T06:55:09.012Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:09.014Z DEBUG modem << D: Seconds since last fix 147
2021-06-11T06:55:09.945Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:09.947Z DEBUG modem << D: Seconds since last fix 148
2021-06-11T06:55:10.947Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:10.948Z DEBUG modem << D: Seconds since last fix 149
2021-06-11T06:55:11.947Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:11.948Z DEBUG modem << D: Seconds since last fix 150
2021-06-11T06:55:12.947Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:12.948Z DEBUG modem << D: Seconds since last fix 151
2021-06-11T06:55:13.946Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:13.948Z DEBUG modem << D: Seconds since last fix 152
2021-06-11T06:55:15.092Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:15.093Z DEBUG modem << D: Seconds since last fix 153
2021-06-11T06:55:16.094Z DEBUG modem << D: Tracking: 0 Using: 0 Unhealthy: 0
2021-06-11T06:55:16.096Z DEBUG modem << D: Seconds since last fix 154

[Didrik Rokhaug]

Which SDK version are you using?

I believe this is a known issue in the error handling of the modem_info and nrf_cloud_agps libraries. The problem is that the nrf_cloud_agps library uses the modem_info library to get information about the network, without specifying what information it needs. The modem info library will therefore try to get all the info it can, including the time. However, this is not supported in all networks. The modem therefore returns an error to the modem_info library, which in turn returns an error to the nrf_cloud_agps library, despite having collected all the information the agps library really needs. Because the agps library gets an error, it aborts the request.

You can work around this problem easily by setting CONFIG_MODEM_INFO_ADD_DATE_TIME=n in you prj.conf file.

This workaround was also added in the SDK a while ago: https://github.com/nrfconnect/sdk-nrf/pull/3276

Best regards,

Didrik