Thingy 91: Asset Tracker V2: Connection to nRF Cloud lost for days after switching to "Passive Mode"

Question

We are evaluating a number of Thingy:91 to track GNSS positions and reporting them to nRF Cloud using the Asset Tracker V2 firmware. When running the Asset Tracker in Active Mode, the Thingy:91s behave as expected, reporting back to nRF Cloud on scheduled intervals. However, when switching to "Passive Mode", the devices stops reporting after some hours and don't come back until they are power-recycled. This last for several days. We have verified this behaviour on 3 different Thingy:91 devices.

Steps to reproduce:

1. Program the Thingy:91 with required firmware as described in Getting started with Thingy:91. Zip file CONTENTS.txt reads git commit SHA 880c82dba48655ee3a75edaefa0208782967ff17
Modem FW: mfw_nrf9160_1.3.2.zip
Connectivity Bridge: thingy91_nrf52_connectivity_bridge_2022-06-02_880c82db.hex
Asset Tracker V2: thingy91_asset_tracker_v2_ltem_2022-06-02_880c82db.hex

2. Register the Thingy:91 in nRF Cloud and observe that is starts reporting sensor data in https://nrfcloud.com/#/devices

3. In the "Device Configuration" dashboard, change the isActive mode from true to false and observe that the device is notified. The device shadow reported config parameters look like

"config":{
    "activeMode":false
    "gnssTimeout":60
    "activeWaitTime":120
    "movementResolution":120
    "movementTimeout":3600
    "movementThreshold":10
    "nod":[]0 items
}

4. Wait some hours (or days) and the device stops reporting. It does not help to shake the device to wake up from the accelerometer.

Attached to USB port, started the nrf Connect LTE Link Monitor and traced for some hours:

Fullscreen 2022-08-17T10_34_55.705Z-log.txt Download

2022-08-17T10:34:56.260Z DEBUG Application data folder: /home/per/.config/nrfconnect/pc-nrfconnect-linkmonitor
2022-08-17T10:34:56.350Z DEBUG App pc-nrfconnect-linkmonitor v2.0.1 official
2022-08-17T10:34:56.350Z DEBUG App path: /home/per/.nrfconnect-apps/node_modules/pc-nrfconnect-linkmonitor
2022-08-17T10:34:56.350Z DEBUG nRFConnect 3.11.1, required by the app is (^3.8.0)
2022-08-17T10:34:56.350Z DEBUG nRFConnect path: /tmp/.mount_nrfconHF9ibm/resources/app.asar
2022-08-17T10:34:56.350Z DEBUG HomeDir: /home/per
2022-08-17T10:34:56.350Z DEBUG TmpDir: /tmp
2022-08-17T10:34:56.363Z INFO Using nrf-device-lib-js version: 0.4.11
2022-08-17T10:34:56.363Z INFO Using nrf-device-lib version: 0.11.8
2022-08-17T10:34:56.363Z INFO Using nrfjprog DLL version: 10.15.4
2022-08-17T10:34:56.363Z INFO Using JLink version: JLink_V7.70a
2022-08-17T10:34:56.363Z INFO Installed JLink version does not match the provided version (V7.58b)
2022-08-17T10:35:00.913Z INFO Modem port is opened
2022-08-17T10:35:00.925Z DEBUG modem >> AT+CFUN?
2022-08-17T10:35:00.999Z DEBUG modem << +CFUN: 1
2022-08-17T10:35:01.001Z DEBUG modem << OK
2022-08-17T10:35:01.012Z DEBUG modem >> AT+CGSN=1
2022-08-17T10:35:01.027Z DEBUG modem << +CGSN: "351358815322604"
2022-08-17T10:35:01.029Z DEBUG modem << OK
2022-08-17T10:35:01.031Z DEBUG modem >> AT+CGMI
2022-08-17T10:35:01.045Z DEBUG modem << Nordic Semiconductor ASA
2022-08-17T10:35:01.047Z DEBUG modem << 
2022-08-17T10:35:01.048Z DEBUG modem << OK
2022-08-17T10:35:01.051Z DEBUG modem >> AT+CGMM
2022-08-17T10:35:01.063Z DEBUG modem << nRF9160-SICA
2022-08-17T10:35:01.064Z DEBUG modem << OK
2022-08-17T10:35:01.067Z DEBUG modem >> AT+CGMR
2022-08-17T10:35:01.075Z DEBUG modem << mfw_nrf9160_1.3.2
2022-08-17T10:35:01.076Z DEBUG modem << OK
2022-08-17T10:35:01.077Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.3.2] SerNr: 351358815322604
2022-08-17T10:35:01.078Z DEBUG modem >> AT+CEMODE?
2022-08-17T10:35:01.084Z DEBUG modem << +CEMODE: 2
2022-08-17T10:35:01.088Z DEBUG modem << OK
2022-08-17T10:35:01.096Z DEBUG modem >> AT%XCBAND=?
2022-08-17T10:35:01.108Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,18,19,20,25,26,28,66)
2022-08-17T10:35:01.109Z DEBUG modem << OK
2022-08-17T10:35:01.118Z DEBUG modem >> AT+CMEE?
2022-08-17T10:35:01.124Z DEBUG modem << +CMEE: 0
2022-08-17T10:35:01.126Z DEBUG modem << OK
2022-08-17T10:35:01.129Z DEBUG modem >> AT+CMEE=1
2022-08-17T10:35:01.134Z DEBUG modem << OK
2022-08-17T10:35:01.137Z DEBUG modem >> AT+CNEC?
2022-08-17T10:35:01.144Z DEBUG modem << +CNEC: 0
2022-08-17T10:35:01.146Z DEBUG modem << 
2022-08-17T10:35:01.147Z DEBUG modem << OK
2022-08-17T10:35:01.150Z DEBUG modem >> AT+CNEC=24
2022-08-17T10:35:01.160Z DEBUG modem << OK
2022-08-17T10:35:01.163Z DEBUG modem >> AT+CGEREP?
2022-08-17T10:35:01.175Z DEBUG modem << +CGEREP: 0,0
2022-08-17T10:35:01.176Z DEBUG modem << OK
2022-08-17T10:35:01.178Z DEBUG modem >> AT+CGDCONT?
2022-08-17T10:35:01.188Z DEBUG modem << +CGDCONT: 0,"IP","ibasis.iot","10.160.239.151",0,0
2022-08-17T10:35:01.190Z DEBUG modem << OK
2022-08-17T10:35:01.196Z DEBUG modem >> AT+CGACT?
2022-08-17T10:35:01.205Z DEBUG modem << +CGACT: 0,1
2022-08-17T10:35:01.207Z DEBUG modem << OK
2022-08-17T10:35:01.213Z DEBUG modem >> AT+CGEREP=1
2022-08-17T10:35:01.224Z DEBUG modem << OK
2022-08-17T10:35:01.227Z DEBUG modem >> AT+CIND=1,1,1
2022-08-17T10:35:01.234Z DEBUG modem << OK
2022-08-17T10:35:01.238Z DEBUG modem >> AT+CEREG=5
2022-08-17T10:35:01.245Z DEBUG modem << OK
2022-08-17T10:35:01.247Z DEBUG modem >> AT+CEREG?
2022-08-17T10:35:01.259Z DEBUG modem << +CEREG: 5,5,"7AA9","01261B02",7,,,"00001010","11000001"
2022-08-17T10:35:01.261Z DEBUG modem << 
2022-08-17T10:35:01.262Z DEBUG modem << OK
2022-08-17T10:35:01.272Z DEBUG modem >> AT+COPS=3,2
2022-08-17T10:35:01.282Z DEBUG modem << OK
2022-08-17T10:35:01.287Z DEBUG modem >> AT+COPS?
2022-08-17T10:35:01.294Z DEBUG modem << +COPS: 0,2,"24201",7
2022-08-17T10:35:01.296Z DEBUG modem << OK
2022-08-17T10:35:01.301Z DEBUG modem >> AT%XCBAND
2022-08-17T10:35:01.312Z DEBUG modem << %XCBAND: 20
2022-08-17T10:35:01.313Z DEBUG modem << OK
2022-08-17T10:35:01.318Z DEBUG modem >> AT+CGDCONT?
2022-08-17T10:35:01.327Z DEBUG modem << +CGDCONT: 0,"IP","ibasis.iot","10.160.239.151",0,0
2022-08-17T10:35:01.328Z DEBUG modem << OK
2022-08-17T10:35:01.333Z DEBUG modem >> AT+CGACT?
2022-08-17T10:35:01.344Z DEBUG modem << +CGACT: 0,1
2022-08-17T10:35:01.345Z DEBUG modem << 
2022-08-17T10:35:01.347Z DEBUG modem << OK
2022-08-17T10:35:01.354Z DEBUG modem >> AT%CESQ=1
2022-08-17T10:35:01.363Z DEBUG modem << OK
2022-08-17T10:35:01.366Z DEBUG modem >> AT+CESQ
2022-08-17T10:35:01.376Z DEBUG modem << +CESQ: 99,99,255,255,255,255
2022-08-17T10:35:01.377Z DEBUG modem << OK
2022-08-17T10:35:01.381Z DEBUG modem >> AT%XSIM=1
2022-08-17T10:35:01.391Z DEBUG modem << OK
2022-08-17T10:35:01.393Z DEBUG modem >> AT%XSIM?
2022-08-17T10:35:01.404Z DEBUG modem << %XSIM: 1
2022-08-17T10:35:01.406Z DEBUG modem << OK
2022-08-17T10:35:01.412Z DEBUG modem >> AT+CPIN?
2022-08-17T10:35:01.422Z DEBUG modem << +CPIN: READY
2022-08-17T10:35:01.424Z DEBUG modem << OK
2022-08-17T10:35:01.428Z DEBUG modem >> AT+CPINR="SIM PIN"
2022-08-17T10:35:01.437Z DEBUG modem << +CME ERROR: 0
2022-08-17T10:37:23.640Z DEBUG modem << [05:05:31.008,789] [0m<inf> ext_sensors_bsec: Storing state to flash[0m
2022-08-17T10:37:25.526Z DEBUG modem << %CESQ: 63,3,16,2
2022-08-17T10:37:25.530Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T10:37:26.039Z DEBUG modem << +CSCON: 1
2022-08-17T10:37:26.443Z DEBUG modem << [05:05:33.811,676] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T10:37:26.454Z DEBUG modem << [05:05:33.812,194] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T10:37:26.461Z DEBUG modem << [05:05:33.812,225] [1;33m<wrn> cloud_module: New connection attempt in 8192 seconds if not successful[0m
2022-08-17T10:37:26.470Z DEBUG modem << [05:05:33.812,377] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T10:37:26.474Z DEBUG modem << [05:05:34.672,027] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T10:37:26.481Z DEBUG modem << [05:05:34.672,119] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T10:37:32.425Z DEBUG modem << +CSCON: 0
2022-08-17T10:37:52.426Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T10:43:57.995Z DEBUG modem << %CESQ: 62,3,10,1
2022-08-17T10:43:58.572Z DEBUG modem << %CESQ: 64,3,21,3
2022-08-17T10:43:58.580Z DEBUG modem << %NCELLMEAS: 0,"01261B01","24201","7AA9",65535,6400,169,67,24,149793008,6400,170,59,8,0,0
2022-08-17T10:43:58.838Z DEBUG modem << [05:12:06.218,750] [0m<inf> app_event_manager: SENSOR_EVT_MOVEMENT_DATA_READY - X: -17.24, Y: 4.56, Z: -3.05[0m
2022-08-17T10:43:58.845Z DEBUG modem << [05:12:06.219,116] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-08-17T10:43:58.856Z DEBUG modem << [05:12:06.219,818] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, ENV, NEIGHBOR_CELLS, GNSS)[0m
2022-08-17T10:43:58.862Z DEBUG modem << [05:12:06.233,154] [0m<inf> app_event_manager: GNSS_EVT_ACTIVE[0m
2022-08-17T10:43:58.870Z DEBUG modem << [05:12:06.233,825] [0m<inf> app_event_manager: SENSOR_EVT_ENVIRONMENTAL_DATA_READY[0m
2022-08-17T10:43:58.879Z DEBUG modem << [05:12:06.290,893] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-08-17T10:43:58.885Z DEBUG modem << [05:12:06.304,016] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-08-17T10:43:58.896Z DEBUG modem << [05:12:06.971,618] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-08-17T10:44:59.507Z DEBUG modem << [05:13:06.894,287] [0m<inf> app_event_manager: GNSS_EVT_TIMEOUT[0m
2022-08-17T10:44:59.513Z DEBUG modem << [05:13:06.894,744] [0m<inf> app_event_manager: GNSS_EVT_INACTIVE[0m
2022-08-17T10:44:59.519Z DEBUG modem << [05:13:06.895,202] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-08-17T10:45:58.831Z DEBUG modem << [05:14:06.219,726] [0m<inf> app_event_manager: APP_EVT_ACTIVITY_DETECTION_ENABLE[0m
2022-08-17T11:03:26.953Z DEBUG modem << %CESQ: 53,2,21,3
2022-08-17T11:03:27.217Z DEBUG modem << %NCELLMEAS: 0,"01261B01","24201","7AA9",65535,6400,169,55,26,150961647,6400,170,46,9,0,0
2022-08-17T11:03:27.219Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T11:03:27.777Z DEBUG modem << [05:31:35.203,613] [0m<inf> app_event_manager: SENSOR_EVT_MOVEMENT_DATA_READY - X: 4.54, Y: 0.27, Z: -11.30[0m
2022-08-17T11:03:27.786Z DEBUG modem << [05:31:35.203,948] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-08-17T11:03:27.795Z DEBUG modem << [05:31:35.204,650] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, ENV, NEIGHBOR_CELLS, GNSS)[0m
2022-08-17T11:03:27.804Z DEBUG modem << [05:31:35.217,926] [0m<inf> app_event_manager: GNSS_EVT_ACTIVE[0m
2022-08-17T11:03:27.809Z DEBUG modem << [05:31:35.218,597] [0m<inf> app_event_manager: SENSOR_EVT_ENVIRONMENTAL_DATA_READY[0m
2022-08-17T11:03:27.820Z DEBUG modem << [05:31:35.276,000] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-08-17T11:03:27.824Z DEBUG modem << [05:31:35.288,787] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-08-17T11:03:27.831Z DEBUG modem << [05:31:35.656,524] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-08-17T11:04:28.146Z DEBUG modem << [05:32:35.578,277] [0m<inf> app_event_manager: GNSS_EVT_TIMEOUT[0m
2022-08-17T11:04:28.154Z DEBUG modem << [05:32:35.578,765] [0m<inf> app_event_manager: GNSS_EVT_INACTIVE[0m
2022-08-17T11:04:28.158Z DEBUG modem << [05:32:35.579,223] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-08-17T11:05:27.771Z DEBUG modem << [05:33:35.204,559] [0m<inf> app_event_manager: APP_EVT_ACTIVITY_DETECTION_ENABLE[0m
2022-08-17T11:07:23.635Z DEBUG modem << [05:35:31.073,883] [0m<inf> ext_sensors_bsec: Storing state to flash[0m
2022-08-17T11:37:23.641Z DEBUG modem << [06:05:31.139,312] [0m<inf> ext_sensors_bsec: Storing state to flash[0m
2022-08-17T12:03:26.812Z DEBUG modem << %CESQ: 49,2,12,1
2022-08-17T12:03:27.637Z DEBUG modem << [06:31:35.204,620] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-08-17T12:03:27.649Z DEBUG modem << [06:31:35.205,291] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, ENV, NEIGHBOR_CELLS, GNSS)[0m
2022-08-17T12:03:27.655Z DEBUG modem << [06:31:35.220,916] [0m<inf> app_event_manager: GNSS_EVT_ACTIVE[0m
2022-08-17T12:03:27.667Z DEBUG modem << [06:31:35.221,374] [0m<inf> app_event_manager: GNSS_EVT_AGPS_NEEDED[0m
2022-08-17T12:03:27.670Z DEBUG modem << [06:31:35.222,076] [0m<inf> app_event_manager: SENSOR_EVT_ENVIRONMENTAL_DATA_READY[0m
2022-08-17T12:03:27.677Z DEBUG modem << [06:31:35.222,442] [1;33m<wrn> data_module: Failed to request A-GPS data, error: -13[0m
2022-08-17T12:03:27.687Z DEBUG modem << [06:31:35.222,473] [1;33m<wrn> data_module: This is expected to fail if we are not in a connected state[0m
2022-08-17T12:03:27.699Z DEBUG modem << [06:31:35.278,045] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-08-17T12:03:27.702Z DEBUG modem << [06:31:35.290,832] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-08-17T12:03:27.856Z DEBUG modem << %NCELLMEAS: 0,"01261B02","24201","7AA9",65535,6400,170,56,23,154562288,6400,169,54,19,0,0
2022-08-17T12:03:27.859Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T12:03:28.867Z DEBUG modem << [06:31:36.433,105] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-08-17T12:04:28.786Z DEBUG modem << [06:32:36.355,987] [0m<inf> app_event_manager: GNSS_EVT_TIMEOUT[0m
2022-08-17T12:04:28.792Z DEBUG modem << [06:32:36.356,475] [0m<inf> app_event_manager: GNSS_EVT_INACTIVE[0m
2022-08-17T12:04:28.799Z DEBUG modem << [06:32:36.356,933] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-08-17T12:07:23.626Z DEBUG modem << [06:35:31.203,002] [0m<inf> ext_sensors_bsec: Storing state to flash[0m
2022-08-17T12:37:23.628Z DEBUG modem << [07:05:31.268,188] [0m<inf> ext_sensors_bsec: Storing state to flash[0m
2022-08-17T12:53:57.212Z DEBUG modem << %CESQ: 67,3,14,2
2022-08-17T12:53:57.226Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T12:53:57.228Z DEBUG modem << +CEREG: 5,"7AA9","01261B01",7,,,"00001010","11000001"
2022-08-17T12:53:57.246Z DEBUG modem >> AT+COPS=3,2
2022-08-17T12:53:57.252Z DEBUG modem << OK
2022-08-17T12:53:57.255Z DEBUG modem >> AT+COPS?
2022-08-17T12:53:57.261Z DEBUG modem << +COPS: 0,2,"24201",7
2022-08-17T12:53:57.262Z DEBUG modem << 
2022-08-17T12:53:57.263Z DEBUG modem << OK
2022-08-17T12:53:57.273Z DEBUG modem >> AT%XCBAND
2022-08-17T12:53:57.278Z DEBUG modem << %XCBAND: 20
2022-08-17T12:53:57.279Z DEBUG modem << 
2022-08-17T12:53:57.280Z DEBUG modem << OK
2022-08-17T12:53:57.288Z DEBUG modem >> AT+CGDCONT?
2022-08-17T12:53:57.298Z DEBUG modem << +CGDCONT: 0,"IP","ibasis.iot","10.160.239.151",0,0
2022-08-17T12:53:57.303Z DEBUG modem << OK
2022-08-17T12:53:57.311Z DEBUG modem >> AT+CGACT?
2022-08-17T12:53:57.315Z DEBUG modem << +CGACT: 0,1
2022-08-17T12:53:57.320Z DEBUG modem << 
2022-08-17T12:53:57.322Z DEBUG modem << OK
2022-08-17T12:53:57.725Z DEBUG modem << +CSCON: 1
2022-08-17T12:53:57.852Z DEBUG modem << +CGEV: ME PDN DEACT 0
2022-08-17T12:53:57.857Z DEBUG modem << +CNEC_EMM: 10
2022-08-17T12:53:57.861Z DEBUG modem << +CEREG: 2,"7AA9","01261B01",7
2022-08-17T12:53:57.871Z DEBUG modem << +CIND: "service",0
2022-08-17T12:53:57.875Z DEBUG modem << +CIND: "roam",0
2022-08-17T12:53:57.878Z DEBUG modem << +CGEV: NW DETACH
2022-08-17T12:53:57.882Z DEBUG modem >> AT+CGDCONT?
2022-08-17T12:53:57.888Z DEBUG modem << OK
2022-08-17T12:53:57.891Z DEBUG modem >> AT+CGDCONT?
2022-08-17T12:53:57.894Z DEBUG modem << OK
2022-08-17T12:53:57.896Z DEBUG modem >> AT+CGACT?
2022-08-17T12:53:57.903Z DEBUG modem << OK
2022-08-17T12:53:57.906Z DEBUG modem >> AT+CGACT?
2022-08-17T12:53:57.909Z DEBUG modem << OK
2022-08-17T12:53:58.128Z DEBUG modem << [07:22:05.812,377] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T12:53:58.137Z DEBUG modem << [07:22:05.812,896] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T12:53:58.145Z DEBUG modem << [07:22:05.812,927] [1;33m<wrn> cloud_module: New connection attempt in 16384 seconds if not successful[0m
2022-08-17T12:53:58.153Z DEBUG modem << [07:22:05.813,079] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:53:58.159Z DEBUG modem << [07:22:05.911,804] [0m<inf> app_event_manager: MODEM_EVT_LTE_CELL_UPDATE[0m
2022-08-17T12:53:58.170Z DEBUG modem << [07:22:06.553,436] [0m<inf> app_event_manager: MODEM_EVT_LTE_DISCONNECTED[0m
2022-08-17T12:53:58.174Z DEBUG modem << [07:22:06.554,107] [1;31m<err> nrf_cloud_integration: nrf_cloud_disconnect, error: -13[0m
2022-08-17T12:53:59.102Z DEBUG modem << +CSCON: 0
2022-08-17T12:53:59.175Z DEBUG modem << +CSCON: 1
2022-08-17T12:54:01.887Z DEBUG modem << [07:22:09.572,814] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:54:01.912Z DEBUG modem << +CGEV: ME PDN ACT 0,0
2022-08-17T12:54:01.919Z DEBUG modem << +CNEC_ESM: 50,0
2022-08-17T12:54:01.924Z DEBUG modem >> AT+CGDCONT?
2022-08-17T12:54:01.925Z DEBUG modem << +CEREG: 5,"7AA9","01261B01",7,,,"00001010","11000001"
2022-08-17T12:54:01.926Z DEBUG modem << +CIND: "service",1
2022-08-17T12:54:01.927Z DEBUG modem << +CIND: "roam",1
2022-08-17T12:54:01.935Z DEBUG modem << %XTIME: "80","22807121451080","01"
2022-08-17T12:54:01.936Z DEBUG modem << +CGDCONT: 0,"IP","ibasis.iot","10.160.123.160",0,0
2022-08-17T12:54:01.937Z DEBUG modem << OK
2022-08-17T12:54:01.942Z DEBUG modem >> AT+CGACT?
2022-08-17T12:54:01.946Z DEBUG modem << +CGACT: 0,1
2022-08-17T12:54:01.947Z DEBUG modem << 
2022-08-17T12:54:01.948Z DEBUG modem << OK
2022-08-17T12:54:02.929Z DEBUG modem << [07:22:10.614,440] [0m<inf> app_event_manager: MODEM_EVT_LTE_CONNECTED[0m
2022-08-17T12:54:02.937Z DEBUG modem << [07:22:10.615,112] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T12:54:02.947Z DEBUG modem << [07:22:10.615,142] [1;33m<wrn> cloud_module: New connection attempt in 32 seconds if not successful[0m
2022-08-17T12:54:02.953Z DEBUG modem << [07:22:10.615,264] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:54:02.960Z DEBUG modem << [07:22:10.743,133] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T12:54:02.968Z DEBUG modem << [07:22:10.743,225] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:54:07.443Z DEBUG modem << +CSCON: 0
2022-08-17T12:54:27.444Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T12:54:34.012Z DEBUG modem << %CESQ: 68,3,18,2
2022-08-17T12:54:34.018Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T12:54:34.525Z DEBUG modem << +CSCON: 1
2022-08-17T12:54:34.929Z DEBUG modem << [07:22:42.615,325] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T12:54:34.941Z DEBUG modem << [07:22:42.615,844] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T12:54:34.947Z DEBUG modem << [07:22:42.615,875] [1;33m<wrn> cloud_module: New connection attempt in 64 seconds if not successful[0m
2022-08-17T12:54:34.958Z DEBUG modem << [07:22:42.616,027] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:54:34.960Z DEBUG modem << [07:22:43.538,330] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T12:54:34.966Z DEBUG modem << [07:22:43.538,421] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:54:38.247Z DEBUG modem << %CESQ: 63,3,15,2
2022-08-17T12:54:40.794Z DEBUG modem << +CSCON: 0
2022-08-17T12:54:41.108Z DEBUG modem << %CESQ: 70,3,20,2
2022-08-17T12:55:00.795Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T12:55:38.012Z DEBUG modem << %CESQ: 68,3,16,2
2022-08-17T12:55:38.024Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T12:55:38.525Z DEBUG modem << +CSCON: 1
2022-08-17T12:55:38.927Z DEBUG modem << [07:23:46.616,027] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T12:55:38.936Z DEBUG modem << [07:23:46.616,546] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T12:55:38.945Z DEBUG modem << [07:23:46.616,577] [1;33m<wrn> cloud_module: New connection attempt in 128 seconds if not successful[0m
2022-08-17T12:55:38.952Z DEBUG modem << [07:23:46.616,729] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:55:38.958Z DEBUG modem << [07:23:47.550,872] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T12:55:38.969Z DEBUG modem << [07:23:47.550,964] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:55:44.795Z DEBUG modem << +CSCON: 0
2022-08-17T12:55:45.109Z DEBUG modem << %CESQ: 71,3,23,3
2022-08-17T12:55:50.229Z DEBUG modem << %CESQ: 71,3,18,2
2022-08-17T12:56:04.796Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T12:57:45.992Z DEBUG modem << %CESQ: 70,3,17,2
2022-08-17T12:57:45.996Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T12:57:46.505Z DEBUG modem << +CSCON: 1
2022-08-17T12:57:46.923Z DEBUG modem << [07:25:54.616,729] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T12:57:46.931Z DEBUG modem << [07:25:54.617,248] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T12:57:46.942Z DEBUG modem << [07:25:54.617,279] [1;33m<wrn> cloud_module: New connection attempt in 256 seconds if not successful[0m
2022-08-17T12:57:46.947Z DEBUG modem << [07:25:54.617,431] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:57:46.960Z DEBUG modem << [07:25:55.467,803] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T12:57:46.962Z DEBUG modem << [07:25:55.467,895] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T12:57:52.774Z DEBUG modem << +CSCON: 0
2022-08-17T12:58:12.775Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T13:02:01.992Z DEBUG modem << %CESQ: 69,3,16,2
2022-08-17T13:02:01.997Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T13:02:02.506Z DEBUG modem << +CSCON: 1
2022-08-17T13:02:02.915Z DEBUG modem << [07:30:10.617,431] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T13:02:02.923Z DEBUG modem << [07:30:10.617,950] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T13:02:02.932Z DEBUG modem << [07:30:10.617,980] [1;33m<wrn> cloud_module: New connection attempt in 512 seconds if not successful[0m
2022-08-17T13:02:02.941Z DEBUG modem << [07:30:10.618,133] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T13:02:02.945Z DEBUG modem << [07:30:11.548,706] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T13:02:02.958Z DEBUG modem << [07:30:11.548,797] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T13:02:08.941Z DEBUG modem << +CSCON: 0
2022-08-17T13:02:11.669Z DEBUG modem << %CESQ: 72,3,21,3
2022-08-17T13:02:28.942Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T13:03:26.652Z DEBUG modem << %CESQ: 68,3,16,2
2022-08-17T13:03:27.214Z DEBUG modem << %NCELLMEAS: 0,"01261B01","24201","7AA9",65535,6400,169,71,20,158161652,0
2022-08-17T13:03:27.216Z DEBUG modem << %CESQ: 255,0,255,0
2022-08-17T13:03:27.498Z DEBUG modem << [07:31:35.204,650] [0m<inf> app_event_manager: APP_EVT_DATA_GET_ALL[0m
2022-08-17T13:03:27.510Z DEBUG modem << [07:31:35.205,322] [0m<inf> app_event_manager: APP_EVT_DATA_GET - Requested data types (MOD_DYN, BAT, ENV, NEIGHBOR_CELLS, GNSS)[0m
2022-08-17T13:03:27.516Z DEBUG modem << [07:31:35.220,703] [0m<inf> app_event_manager: GNSS_EVT_ACTIVE[0m
2022-08-17T13:03:27.527Z DEBUG modem << [07:31:35.221,191] [0m<inf> app_event_manager: GNSS_EVT_AGPS_NEEDED[0m
2022-08-17T13:03:27.530Z DEBUG modem << [07:31:35.221,862] [0m<inf> app_event_manager: SENSOR_EVT_ENVIRONMENTAL_DATA_READY[0m
2022-08-17T13:03:27.538Z DEBUG modem << [07:31:35.222,259] [1;33m<wrn> data_module: Failed to request A-GPS data, error: -13[0m
2022-08-17T13:03:27.548Z DEBUG modem << [07:31:35.222,259] [1;33m<wrn> data_module: This is expected to fail if we are not in a connected state[0m
2022-08-17T13:03:27.560Z DEBUG modem << [07:31:35.277,801] [0m<inf> app_event_manager: MODEM_EVT_MODEM_DYNAMIC_DATA_READY[0m
2022-08-17T13:03:27.563Z DEBUG modem << [07:31:35.290,954] [0m<inf> app_event_manager: MODEM_EVT_BATTERY_DATA_READY[0m
2022-08-17T13:03:27.571Z DEBUG modem << [07:31:35.929,077] [0m<inf> app_event_manager: MODEM_EVT_NEIGHBOR_CELLS_DATA_READY[0m
2022-08-17T13:04:28.145Z DEBUG modem << [07:32:35.853,820] [0m<inf> app_event_manager: GNSS_EVT_TIMEOUT[0m
2022-08-17T13:04:28.151Z DEBUG modem << [07:32:35.854,309] [0m<inf> app_event_manager: GNSS_EVT_INACTIVE[0m
2022-08-17T13:04:28.157Z DEBUG modem << [07:32:35.854,766] [0m<inf> app_event_manager: DATA_EVT_DATA_READY[0m
2022-08-17T13:07:23.619Z DEBUG modem << [07:35:31.333,709] [0m<inf> ext_sensors_bsec: Storing state to flash[0m
2022-08-17T13:10:33.993Z DEBUG modem << %CESQ: 65,3,13,1
2022-08-17T13:10:34.003Z DEBUG modem << %MDMEV: SEARCH STATUS 2
2022-08-17T13:10:34.507Z DEBUG modem << +CSCON: 1
2022-08-17T13:10:34.897Z DEBUG modem << [07:38:42.618,133] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTION_TIMEOUT[0m
2022-08-17T13:10:34.905Z DEBUG modem << [07:38:42.618,652] [1;33m<wrn> cloud_module: Cloud connection establishment in progress[0m
2022-08-17T13:10:34.915Z DEBUG modem << [07:38:42.618,682] [1;33m<wrn> cloud_module: New connection attempt in 2048 seconds if not successful[0m
2022-08-17T13:10:34.921Z DEBUG modem << [07:38:42.618,835] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T13:10:34.932Z DEBUG modem << [07:38:43.509,490] [1;31m<err> nrf_cloud: nRF cloud connect failed -23[0m
2022-08-17T13:10:34.934Z DEBUG modem << [07:38:43.509,582] [0m<inf> app_event_manager: CLOUD_EVT_CONNECTING[0m
2022-08-17T13:10:36.889Z DEBUG modem << %CESQ: 67,3,18,2
2022-08-17T13:10:41.023Z DEBUG modem << +CSCON: 0
2022-08-17T13:11:01.023Z DEBUG modem << %CESQ: 255,0,255,0

Regards,
Per

helsing · Accepted Answer

Are all your devices using SIM cards from ibasis? 
 Thank you for the trace. 
 The trace indicates NAT/firewall issues. This explains why this only occurs in passive mode, and not active mode. 
 In passive mode, the device will in general send data upon movement. In addition, frequent pings are sent to nRF Cloud every 20 minutes, which is clearly not enough. 
 Attached is a firmware build (taken from latest main) where the keepalive is decreased to 2 minutes. Please try this out and see if this resolves the issue. If so, you would need to tweak the keepalive to suit your needs. Alternatively, you could try a SIM card from a different provider who might have longer NAT/firewall timeouts. Telenoer and Telia are good alternatives for testing in Norway. 
 It is also recommended to set a lower accelerometer threshold if you would like to trigger on movement. The default value of 10 has proven to be a bit to high to easily trigger data transmission on movement. 
 4520.merged.hex

Per · Answer

Thats correct. We are using the SIMs from ibasis. We have decided to got for a BLE-advertisment solution for the verifcation now so I'll have to wait with the Asset Tracker V2 cloud solution, but if we return to it, we will decrease the keepalive as you propose. Thank you very much.

Thingy 91: Asset Tracker V2: Connection to nRF Cloud lost for days after switching to "Passive Mode"

Top Replies