lte_ble_gateway sample application gets a kernel panic

[Steven Grunza]

I have reprogrammed the nRF52840 board controller.  When I run the lte_ble_gateway application I get the following on the 2nd dev kit VCP port:

SPM: NS image at 0xc000
SPM: NS MSP at 0x2002bc90
SPM: NS reset vector at 0x14b7d
SPM: prepare to jump to Non-Secure image.
***** Booting Zephyr OS v1.14.99-ncs2 *****
Application started
Initializing Bluetooth..
Establishing LTE link (this may take some time) ...
ASSERTION FAIL [err == 0] @ ../src/main.c:515
LTE linBluetooth ready
k could noScanning...
be established.
***** Kernel Panic! *****
Current thread ID = 0x20023e34
Faulting instruction address = 0x32372

The board controller VCP (3rd port) gives:

***** Booting Zephyr OS v1.14.99-ncs2 *****

It looks like the problem is in lte_lc_init_and_connect(),  Any ideas on what is wrong?

    512                 printk("Establishing LTE link (this may take some time) ...\n");
    513                 display_state = LEDS_CONNECTING;
    514                 err = lte_lc_init_and_connect();
    515                 __ASSERT(err == 0, "LTE link could not be established.");
    516         }
    517 }

[Sigurd]

Steven Grunza said:

It looks like the problem is in lte_lc_init_and_connect(),

Were you able to get a LTE link with the at_client sample, together with the LTE link monitor ?

[Steven Grunza]

No.  UART, MODEL, UICC, and PDN are all green.  The LTE switches from Red to Yellow then back to Red.  The AT+CSEQ command reports the Reference Signal Received Power (RSRP) as 66.

AT+CESQ

+CESQ: 99,99,255,255,255,66

OK

[Carl Richard]

It can take some time before connection is established, especially with the iBasis SIM. Can you try to run AT+CFUN? and give it some time? (it has worked some times for me at least).

It could also be worth checking out the LTE-M/NB-IoT coverage in your area if you haven't already :)  

[Steven Grunza]

The solution was to upgrade from pre_alpha firmware to 1.0.1 in the mfwnrf9160101.zip file.

Now the LTE link comes up, gets a socket error, and shutdown; followed be a reboot.

2019-10-22T14:06:18.602Z DEBUG modem << ***** Booting Zephyr OS v1.14.99-ncs2 *****\x0D\x0A
2019-10-22T14:06:18.605Z DEBUG modem << Flash region\x09\x09Domain\x09\x09Permissions\x0D\x0A
2019-10-22T14:06:18.608Z DEBUG modem << 00 0x00000 0x08000 \x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.611Z DEBUG modem << 01 0x08000 0x10000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.614Z DEBUG modem << 02 0x10000 0x18000 \x09Non-Secure\x09rwxl\x0D
2019-10-22T14:06:18.618Z DEBUG modem << 03 0x18000 0x20000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.620Z DEBUG modem << 04 0x20000 0x28000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.624Z DEBUG modem << 05 0x28000 0x30000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.627Z DEBUG modem << 06 0x30000 0x38000 \x09Non-Secure\x09rwxl\x0D
2019-10-22T14:06:18.631Z DEBUG modem << 07 0x38000 0x40000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.634Z DEBUG modem << 08 0x40000 0x48000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.637Z DEBUG modem << 09 0x48000 0x50000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.641Z DEBUG modem << 10 0x50000 0x58000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.644Z DEBUG modem << 11 0x58000 0x60000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.647Z DEBUG modem << 12 0x60000 0x68000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.650Z DEBUG modem << 13 0x68000 0x70000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.653Z DEBUG modem << 14 0x70000 0x78000 \x09Non-Secure\x09rwxl\x0D
2019-10-22T14:06:18.656Z DEBUG modem << 15 0x78000 0x80000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.660Z DEBUG modem << 16 0x80000 0x88000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.663Z DEBUG modem << 17 0x88000 0x90000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.666Z DEBUG modem << 18 0x90000 0x98000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.670Z DEBUG modem << 19 0x98000 0xa0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.673Z DEBUG modem << 20 0xa0000 0xa8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.676Z DEBUG modem << 21 0xa8000 0xb0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.679Z DEBUG modem << 22 0xb0000 0xb8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.683Z DEBUG modem << 23 0xb8000 0xc0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.686Z DEBUG modem << 24 0xc0000 0xc8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.690Z DEBUG modem << 25 0xc8000 0xd0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.692Z DEBUG modem << 26 0xd0000 0xd8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.696Z DEBUG modem << 27 0xd8000 0xe0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.699Z DEBUG modem << 28 0xe0000 0xe8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.702Z DEBUG modem << 29 0xe8000 0xf0000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.706Z DEBUG modem << 30 0xf0000 0xf8000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.709Z DEBUG modem << 31 0xf8000 0x100000 \x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.714Z DEBUG modem << Non-secure callable region 0 placed in flash region 0 with size 32.\x0D\x0A
2019-10-22T14:06:18.718Z DEBUG modem << SRAM region\x09\x09Domain\x09\x09Permissions\x0D\x0A
2019-10-22T14:06:18.721Z DEBUG modem << 00 0x00000 0x02000\x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.725Z DEBUG modem << 01 0x02000 0x04000\x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.728Z DEBUG modem << 02 0x04000 0x06000\x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.730Z DEBUG modem << 03 0x06000 0x08000\x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.732Z DEBUG modem << 04 0x08000 0x0a000\x09Secure\x09\x09rwxl\x0D
2019-10-22T14:06:18.735Z DEBUG modem << 05 0x0a000 0x0c000\x09Secure\x09\x09rwxl\x0D
2019-10-22T14:06:18.739Z DEBUG modem << 06 0x0c000 0x0e000\x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.741Z DEBUG modem << 07 0x0e000 0x10000\x09Secure\x09\x09rwxl\x0D\x0A
2019-10-22T14:06:18.744Z DEBUG modem << 08 0x10000 0x12000\x09Non-Secure\x09rwxl\x0D
2019-10-22T14:06:18.748Z DEBUG modem << 09 0x12000 0x14000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.763Z DEBUG modem << 10 0x14000 0x16000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.764Z DEBUG modem << 11 0x16000 0x18000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.765Z DEBUG modem << 12 0x18000 0x1a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.765Z DEBUG modem << 13 0x1a000 0x1c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.766Z DEBUG modem << 14 0x1c000 0x1e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.767Z DEBUG modem << 15 0x1e000 0x20000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.770Z DEBUG modem << 16 0x20000 0x22000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.775Z DEBUG modem << 17 0x22000 0x24000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.776Z DEBUG modem << 18 0x24000 0x26000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.779Z DEBUG modem << 19 0x26000 0x28000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.782Z DEBUG modem << 20 0x28000 0x2a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.785Z DEBUG modem << 21 0x2a000 0x2c000\x09Non-Secure\x09rwxl\x0D
2019-10-22T14:06:18.790Z DEBUG modem << 22 0x2c000 0x2e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.793Z DEBUG modem << 23 0x2e000 0x30000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.795Z DEBUG modem << 24 0x30000 0x32000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.798Z DEBUG modem << 25 0x32000 0x34000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.801Z DEBUG modem << 26 0x34000 0x36000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.806Z DEBUG modem << 27 0x36000 0x38000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.808Z DEBUG modem << 28 0x38000 0x3a000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.811Z DEBUG modem << 29 0x3a000 0x3c000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.814Z DEBUG modem << 30 0x3c000 0x3e000\x09Non-Secure\x09rwxl\x0D\x0A
2019-10-22T14:06:18.817Z DEBUG modem << 31 0x3e000 0x40000\x09Non-Secure\x09rwxl\x0D\x0A\x0D\x0A
2019-10-22T14:06:18.823Z DEBUG modem << Peripheral\x09\x09Domain\x09\x09Status\x0D\x0A
2019-10-22T14:06:18.824Z DEBUG modem << 00 NRF_P0\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.825Z DEBUG modem << 01 NRF_CLOCK\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.827Z DEBUG modem << 02 NRF_RTC1\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.829Z DEBUG modem << 03 NRF_NVMC\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.832Z DEBUG modem << 04 NRF_UARTE1\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.835Z DEBUG modem << 05 NRF_UARTE2\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.839Z DEBUG modem << 06 NRF_TWIM2\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.840Z DEBUG modem << 07 NRF_SPIM3\x09\x09Non-Secure\x09OK\x0D
2019-10-22T14:06:18.842Z DEBUG modem << 08 NRF_TIMER0\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.845Z DEBUG modem << 09 NRF_TIMER1\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.848Z DEBUG modem << 10 NRF_TIMER2\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.850Z DEBUG modem << 11 NRF_SAADC\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.853Z DEBUG modem << 12 NRF_PWM0\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.858Z DEBUG modem << 13 NRF_PWM1\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.859Z DEBUG modem << 14 NRF_PWM2\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.860Z DEBUG modem << 15 NRF_PWM3\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.862Z DEBUG modem << 16 NRF_IPC\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.865Z DEBUG modem << 17 NRF_VMC\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.867Z DEBUG modem << 18 NRF_FPU\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.869Z DEBUG modem << 19 NRF_EGU1\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.874Z DEBUG modem << 20 NRF_EGU2\x09\x09Non-Secure\x09OK\x0D\x0A
2019-10-22T14:06:18.875Z DEBUG modem << 21 NRF_GPIOTE1\x09\x09Non-Secure\x09OK\x0D\x0A\x0D\x0A
2019-10-22T14:06:18.877Z DEBUG modem << SPM: NS image at 0xc000\x0D\x0A
2019-10-22T14:06:18.879Z DEBUG modem << SPM: NS MSP at 0x2002bc90\x0D
2019-10-22T14:06:18.882Z DEBUG modem << SPM: NS reset vector at 0x14b7d\x0D\x0A
2019-10-22T14:06:18.886Z DEBUG modem << SPM: prepare to jump to Non-Secure image.\x0D\x0A
2019-10-22T14:06:19.226Z DEBUG modem << ***** Booting Zephyr OS v1.14.99-ncs2 *****\x0D\x0A
2019-10-22T14:06:19.230Z DEBUG modem << Application started\x0D\x0A
2019-10-22T14:06:19.231Z DEBUG modem << Initializing Bluetooth..\x0D\x0A
2019-10-22T14:06:19.253Z DEBUG modem << Establishing LTE link (this may take some time) ...\x0D\x0A
2019-10-22T14:06:19.270Z DEBUG modem << Bluetooth ready\x0D\x0A
2019-10-22T14:06:19.274Z DEBUG modem << Scanning...\x0D\x0A
2019-10-22T14:06:31.146Z DEBUG modem << NRF_CLOUD_EVT_TRANSPORT_CONNECTED\x0D\x0A
2019-10-22T14:06:31.894Z DEBUG modem << NRF_CLOUD_EVT_USER_ASSOCIATED\x0D
2019-10-22T14:07:00.670Z DEBUG modem >> AT+CFUN?\x0D\x0A
2019-10-22T14:07:00.683Z DEBUG modem << +CFUN: 1\x0D\x0A
2019-10-22T14:07:00.698Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.702Z DEBUG modem >> AT+CGSN=1\x0D\x0A
2019-10-22T14:07:00.718Z DEBUG modem << +CGSN: "352656100163248"\x0D\x0A
2019-10-22T14:07:00.719Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.724Z DEBUG modem >> AT+CGMI\x0D\x0A
2019-10-22T14:07:00.741Z DEBUG modem << Nordic Semiconductor ASA\x0D\x0A
2019-10-22T14:07:00.741Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.744Z DEBUG modem >> AT+CGMM\x0D\x0A
2019-10-22T14:07:00.758Z DEBUG modem << nRF9160-SICA\x0D\x0A
2019-10-22T14:07:00.759Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.761Z DEBUG modem >> AT+CGMR\x0D\x0A
2019-10-22T14:07:00.775Z DEBUG modem << mfw_nrf9160_1.0.1\x0D\x0A
2019-10-22T14:07:00.775Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.776Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.0.1] SerNr: 352656100163248
2019-10-22T14:07:00.777Z DEBUG modem >> AT+CEMODE?\x0D\x0A
2019-10-22T14:07:00.790Z DEBUG modem << +CEMODE: 2\x0D\x0A
2019-10-22T14:07:00.792Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.798Z DEBUG modem >> AT%XCBAND=?\x0D\x0A
2019-10-22T14:07:00.818Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,14,17,18,19,20,25,26,28,66)\x0D\x0A
2019-10-22T14:07:00.819Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.827Z DEBUG modem >> AT+CMEE?\x0D\x0A
2019-10-22T14:07:00.841Z DEBUG modem << +CMEE: 0\x0D\x0A
2019-10-22T14:07:00.842Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.845Z DEBUG modem >> AT+CMEE=1\x0D\x0A
2019-10-22T14:07:00.858Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.860Z DEBUG modem >> AT+CNEC?\x0D\x0A
2019-10-22T14:07:00.874Z DEBUG modem << +CNEC: 0\x0D\x0A
2019-10-22T14:07:00.875Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.878Z DEBUG modem >> AT+CNEC=24\x0D\x0A
2019-10-22T14:07:00.893Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.896Z DEBUG modem >> AT+CGEREP?\x0D\x0A
2019-10-22T14:07:00.909Z DEBUG modem << +CGEREP: 0,0\x0D\x0A
2019-10-22T14:07:00.910Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.914Z DEBUG modem >> AT+CGDCONT?\x0D\x0A
2019-10-22T14:07:00.930Z DEBUG modem << +CGDCONT: 0,"IP","ibasis.iot","10.160.187.141",0,0\x0D
2019-10-22T14:07:00.931Z DEBUG modem << \x0A
2019-10-22T14:07:00.931Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.935Z DEBUG modem >> AT+CGACT?\x0D\x0A
2019-10-22T14:07:00.947Z DEBUG modem << +CGACT: 0,1\x0D\x0A
2019-10-22T14:07:00.949Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.953Z DEBUG modem >> AT+CGEREP=1\x0D\x0A
2019-10-22T14:07:00.964Z DEBUG modem << OK\x0D
2019-10-22T14:07:00.967Z DEBUG modem >> AT+CIND=1,1,1\x0D\x0A
2019-10-22T14:07:00.980Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.984Z DEBUG modem >> AT+CEREG=2\x0D\x0A
2019-10-22T14:07:00.993Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:00.996Z DEBUG modem >> AT+CEREG?\x0D\x0A
2019-10-22T14:07:01.011Z DEBUG modem << +CEREG: 2,5,"6603","018F8B03",7\x0D\x0A
2019-10-22T14:07:01.012Z DEBUG modem << OK\x0D\x0A
2019-10-22T14:07:01.017Z DEBUG modem >> AT+COPS=3,2\x0D\x0A
2019-10-22T14:07:01.029Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.032Z DEBUG modem >> AT+COPS?\x0D\x0A
2019-10-22T14:07:01.046Z DEBUG modem << +COPS: 0,2,"311480",7\x0D\x0A
2019-10-22T14:07:01.047Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.050Z DEBUG modem >> AT%XCBAND\x0D\x0A
2019-10-22T14:07:01.063Z DEBUG modem << %XCBAND: 13\x0D
2019-10-22T14:07:01.064Z DEBUG modem << \x0A
2019-10-22T14:07:01.064Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.068Z DEBUG modem >> AT+CGDCONT?\x0D\x0A
2019-10-22T14:07:01.084Z DEBUG modem << +CGDCONT: 0,"IP","ibasis.iot","10.160.187.141",0,0\x0D\x0A
2019-10-22T14:07:01.085Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.093Z DEBUG modem >> AT+CGACT?\x0D\x0A
2019-10-22T14:07:01.108Z DEBUG modem << +CGACT: 0,1\x0D\x0A
2019-10-22T14:07:01.109Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.112Z DEBUG modem >> AT%CESQ=1\x0D\x0A
2019-10-22T14:07:01.124Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.128Z DEBUG modem >> AT+CESQ\x0D\x0A
2019-10-22T14:07:01.143Z DEBUG modem << +CESQ: 99,99,255,255,255,50\x0D\x0A
2019-10-22T14:07:01.144Z DEBUG modem << OK\x0D\x0A
2019-10-22T14:07:01.147Z DEBUG modem >> AT%XSIM=1\x0D\x0A
2019-10-22T14:07:01.159Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.162Z DEBUG modem >> AT%XSIM?\x0D\x0A
2019-10-22T14:07:01.174Z DEBUG modem << %XSIM: 1\x0D\x0A
2019-10-22T14:07:01.175Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.180Z DEBUG modem >> AT+CPIN?\x0D\x0A
2019-10-22T14:07:01.192Z DEBUG modem << +CPIN: READY\x0D\x0A
2019-10-22T14:07:01.193Z DEBUG modem << OK\x0D
2019-10-22T14:07:01.196Z DEBUG modem >> AT+CPINR="SIM PIN"\x0D\x0A
2019-10-22T14:07:01.216Z DEBUG modem << +CPINR: "SIM PIN",3\x0D\x0A
2019-10-22T14:07:01.217Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.224Z DEBUG modem >> AT+CIMI\x0D\x0A
2019-10-22T14:07:01.237Z DEBUG modem << 204080813511783\x0D\x0A
2019-10-22T14:07:01.242Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:07:01.243Z INFO IMSIdentity: 204080813511783
2019-10-22T14:07:32.513Z DEBUG modem << NRF_CLOUD_EVT_TRANSPORT_DISCONNECTED\x0D
2019-10-22T14:07:37.828Z DEBUG modem << NRF_CLOUD_EVT_TRANSPORT_CONNECTED\x0D\x0A
2019-10-22T14:07:38.517Z DEBUG modem << NRF_CLOUD_EVT_USER_ASSOCIATED\x0D\x0A
2019-10-22T14:08:39.146Z DEBUG modem << NRF_CLOUD_EVT_TRANSPORT_DISCONNECTED\x0D
2019-10-22T14:13:36.753Z INFO Modem port is opened
2019-10-22T14:13:36.756Z DEBUG modem >> AT+CFUN?\x0D\x0A
2019-10-22T14:13:36.761Z DEBUG modem << +CFUN: 1\x0D
2019-10-22T14:13:36.764Z DEBUG modem << \x0A
2019-10-22T14:13:36.765Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.768Z DEBUG modem >> AT+CGSN=1\x0D\x0A
2019-10-22T14:13:36.778Z DEBUG modem << +CGSN: "352656100163248"\x0D\x0A
2019-10-22T14:13:36.779Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.782Z DEBUG modem >> AT+CGMI\x0D\x0A
2019-10-22T14:13:36.787Z DEBUG modem << Nordic Semiconductor ASA\x0D\x0A
2019-10-22T14:13:36.788Z DEBUG modem << OK\x0D
2019-10-22T14:13:36.794Z DEBUG modem >> AT+CGMM\x0D\x0A
2019-10-22T14:13:36.799Z DEBUG modem << nRF9160-SICA\x0D\x0A
2019-10-22T14:13:36.800Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.803Z DEBUG modem >> AT+CGMR\x0D\x0A
2019-10-22T14:13:36.815Z DEBUG modem << mfw_nrf9160_1.0.1\x0D\x0A
2019-10-22T14:13:36.817Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.818Z INFO Nordic Semiconductor ASA nRF9160-SICA [mfw_nrf9160_1.0.1] SerNr: 352656100163248
2019-10-22T14:13:36.820Z DEBUG modem >> AT+CEMODE?\x0D\x0A
2019-10-22T14:13:36.828Z DEBUG modem << +CEMODE: 2\x0D\x0A
2019-10-22T14:13:36.830Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.833Z DEBUG modem >> AT%XCBAND=?\x0D\x0A
2019-10-22T14:13:36.846Z DEBUG modem << %XCBAND: (1,2,3,4,5,8,12,13,14,17,18,19,20,25,26,28,66)\x0D\x0A
2019-10-22T14:13:36.847Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.849Z DEBUG modem >> AT+CMEE?\x0D\x0A
2019-10-22T14:13:36.853Z DEBUG modem << +CMEE: 0\x0D\x0A
2019-10-22T14:13:36.854Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.862Z DEBUG modem >> AT+CMEE=1\x0D\x0A
2019-10-22T14:13:36.865Z DEBUG modem << OK\x0D
2019-10-22T14:13:36.867Z DEBUG modem >> AT+CNEC?\x0D\x0A
2019-10-22T14:13:36.871Z DEBUG modem << +CNEC: 0\x0D\x0A
2019-10-22T14:13:36.872Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.878Z DEBUG modem >> AT+CNEC=24\x0D\x0A
2019-10-22T14:13:36.882Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.884Z DEBUG modem >> AT+CGEREP?\x0D\x0A
2019-10-22T14:13:36.888Z DEBUG modem << +CGEREP: 0,0\x0D\x0A
2019-10-22T14:13:36.889Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.895Z DEBUG modem >> AT+CGDCONT?\x0D\x0A
2019-10-22T14:13:36.899Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.901Z DEBUG modem >> AT+CGACT?\x0D\x0A
2019-10-22T14:13:36.904Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.911Z DEBUG modem >> AT+CGEREP=1\x0D\x0A
2019-10-22T14:13:36.915Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.917Z DEBUG modem >> AT+CIND=1,1,1\x0D\x0A
2019-10-22T14:13:36.921Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.927Z DEBUG modem >> AT+CEREG=2\x0D\x0A
2019-10-22T14:13:36.931Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.933Z DEBUG modem >> AT+CEREG?\x0D\x0A
2019-10-22T14:13:36.939Z DEBUG modem << +CEREG: 2,4,"FFFE","FFFFFFFF",7\x0D\x0A
2019-10-22T14:13:36.946Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.949Z DEBUG modem >> AT%CESQ=1\x0D\x0A
2019-10-22T14:13:36.953Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.955Z DEBUG modem >> AT+CESQ\x0D\x0A
2019-10-22T14:13:36.963Z DEBUG modem << +CESQ: 99,99,255,255,255,255\x0D\x0A
2019-10-22T14:13:36.964Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.967Z DEBUG modem >> AT%XSIM=1\x0D\x0A
2019-10-22T14:13:36.970Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.972Z DEBUG modem >> AT%XSIM?\x0D\x0A
2019-10-22T14:13:36.980Z DEBUG modem << %XSIM: 1\x0D\x0A
2019-10-22T14:13:36.980Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:36.983Z DEBUG modem >> AT+CPIN?\x0D\x0A
2019-10-22T14:13:36.987Z DEBUG modem << +CPIN: READY\x0D\x0A
2019-10-22T14:13:36.988Z DEBUG modem << OK\x0D
2019-10-22T14:13:36.996Z DEBUG modem >> AT+CPINR="SIM PIN"\x0D\x0A
2019-10-22T14:13:37.013Z DEBUG modem << +CPINR: "SIM PIN",3\x0D\x0A
2019-10-22T14:13:37.013Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:37.016Z DEBUG modem >> AT+CIMI\x0D\x0A
2019-10-22T14:13:37.020Z DEBUG modem << 204080813511783\x0D\x0A
2019-10-22T14:13:37.021Z DEBUG modem << OK\x0D\x0A\x00
2019-10-22T14:13:37.022Z INFO IMSIdentity: 204080813511783
2019-10-22T14:13:38.034Z DEBUG modem << [00:00:03.612,304] \x1B[0m<dbg> lte_lc.at_handler: recv: %CESQ: 56,2\x0D\x0A
2019-10-22T14:13:38.051Z DEBUG modem << \x1B[0m\x0D\x0A
2019-10-22T14:13:38.052Z DEBUG modem << [00:00:03.618,988] \x1B[0m<dbg> lte_lc.at_handler: recv: +CEREG: 2,"6603","018F8B03",7\x0D\x0A
2019-10-22T14:13:38.053Z DEBUG modem << \x1B[0m\x0D\x0A
2019-10-22T14:13:41.230Z DEBUG modem << [00:00:06.807,647] \x1B[0m<dbg> lte_lc.at_handler: recv: +CGEV: ME PDN ACT 0,0\x0D\x0A
2019-10-22T14:13:41.236Z DEBUG modem << \x1B[0m\x0D\x0A
2019-10-22T14:13:41.237Z DEBUG modem << [00:00:06.815,216] \x1B[0m<dbg> lte_lc.at_handler: recv: +CNEC_ESM: 50,0\x0D\x0A
2019-10-22T14:13:41.237Z DEBUG modem << \x1B[0m\x0D\x0A
2019-10-22T14:13:41.245Z DEBUG modem << [00:00:06.822,235] \x1B[0m<dbg> lte_lc.at_handler: recv: +CEREG: 5,"6603","018F8B03",7\x0D\x0A
2019-10-22T14:13:41.247Z DEBUG modem << \x1B[0m\x0D\x0A
2019-10-22T14:13:41.248Z DEBUG modem << Connected to LTE network\x0D\x0A
2019-10-22T14:13:41.485Z DEBUG modem << [00:00:07.061,859] \x1B[0m<dbg> nrf_cloud_transport.nct_connect: IPv4 Address 0x6cea5634\x1B[0m\x0D\x0A
2019-10-22T14:13:46.317Z DEBUG modem << [00:00:11.894,195] \x1B[0m<dbg> nrf_cloud_transport.nct_mqtt_evt_handler: MQTT_EVT_CONNACK\x1B[0m\x0D\x0A
2019-10-22T14:13:46.326Z DEBUG modem << [00:00:11.902,404] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 2\x1B[0m\x0D\x0A
2019-10-22T14:13:46.333Z DEBUG modem << [00:00:11.910,156] \x1B[0m<dbg> nrf_cloud.event_handler: NRF_CLOUD_EVT_TRANSPORT_CONNECTED\x1B[0m\x0D\x0A
2019-10-22T14:13:46.335Z DEBUG modem << CLOUD_EVT_CONNECTED\x0D\x0A
2019-10-22T14:13:46.347Z DEBUG modem << [00:00:11.920,288] \x1B[0m<dbg> nrf_cloud_transport.nct_cc_connect: nct_cc_connect\x1B[0m\x0D\x0A
2019-10-22T14:13:46.352Z DEBUG modem << [00:00:11.929,107] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 3\x1B[0m\x0D\x0A
2019-10-22T14:13:46.688Z DEBUG modem << [00:00:12.263,214] \x1B[0m<dbg> nrf_cloud_transport.nct_mqtt_evt_handler: MQTT_EVT_SUBACK: id=1234 result=0\x1B[0m\x0D\x0A
2019-10-22T14:13:46.695Z DEBUG modem << [00:00:12.272,888] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 4\x1B[0m\x0D\x0A
2019-10-22T14:13:46.705Z DEBUG modem << [00:00:12.280,639] \x1B[0m<dbg> nrf_cloud_transport.nct_cc_send: mqtt_publish: id=5678 opcode=0 len=0\x1B[0m\x0D\x0A
2019-10-22T14:13:46.714Z DEBUG modem << [00:00:12.290,954] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 5\x1B[0m\x0D\x0A
2019-10-22T14:13:46.966Z DEBUG modem << [00:00:12.541,320] \x1B[0m<dbg> nrf_cloud_transport.nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id=5678 result=0\x1B[0m\x0D\x0A
2019-10-22T14:13:46.974Z DEBUG modem << [00:00:12.550,994] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 5\x1B[0m\x0D\x0A
2019-10-22T14:13:47.075Z DEBUG modem << [00:00:12.650,512] \x1B[0m<dbg> nrf_cloud_transport.nct_mqtt_evt_handler: MQTT_EVT_PUBLISH: id=1 len=992 \x1B[0m\x0D\x0A
2019-10-22T14:13:47.090Z DEBUG modem << [00:00:12.666,290] \x1B[0m<dbg> nrf_cloud_transport.nct_dc_endpoint_set: nct_dc_endpoint_set\x1B[0m\x0D
2019-10-22T14:13:47.098Z DEBUG modem << [00:00:12.674,804] \x1B[0m<dbg> nrf_cloud_transport.nct_dc_endpoint_get: nct_dc_endpoint_get\x1B[0m\x0D\x0A
2019-10-22T14:13:47.114Z DEBUG modem << [00:00:12.684,509] \x1B[0m<dbg> nrf_cloud_transport.nct_cc_send: mqtt_publish: id=7890 opcode=1 len=255\x1B[0m\x0D\x0A
2019-10-22T14:13:47.119Z DEBUG modem << [00:00:12.695,129] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 12\x1B[0m\x0D\x0A
2019-10-22T14:13:47.130Z DEBUG modem << [00:00:12.702,972] \x1B[0m<dbg> nrf_cloud.event_handler: NRF_CLOUD_EVT_USER_ASSOCIATED\x1B[0m\x0D\x0A
2019-10-22T14:13:47.131Z DEBUG modem << CLOUD_EVT_PAIR_DONE\x0D\x0A
2019-10-22T14:13:47.703Z DEBUG modem << [00:00:13.278,228] \x1B[0m<dbg> nrf_cloud_transport.nct_mqtt_evt_handler: MQTT_EVT_PUBACK: id=7890 result=0\x1B[0m\x0D\x0A
2019-10-22T14:13:47.711Z DEBUG modem << [00:00:13.287,902] \x1B[0m<dbg> nrf_cloud_transport.nct_dc_connect: nct_dc_connect\x1B[0m\x0D\x0A
2019-10-22T14:13:47.720Z DEBUG modem << [00:00:13.296,661] \x1B[0m<dbg> nrf_cloud.nfsm_set_current_state_and_notify: state: 13\x1B[0m\x0D\x0A
2019-10-22T14:13:48.008Z DEBUG modem << Socket error: POLLHUP\x0D\x0A
2019-10-22T14:13:48.016Z DEBUG modem << LTE link disconnect\x0D\x0A
2019-10-22T14:13:49.635Z DEBUG modem << Shutdown modem\x0D\x0A
2019-10-22T14:13:49.648Z DEBUG modem << ***** Booting Zephyr OS build v1.14.99-ncs3-snapshot2-2647-gd6e67554cfeb *****\x0D\x0A

[Sigurd]

Are you using LTE-M or NB-IOT ?

Did you register the device at nrfcloud.com ? If no, please do that, and test again.

If yes, you might need to update the certificates. See the guide at this link.

[Steven Grunza]

It took three tries to get the certificate to work.  The key was to use the HWID on the back of the dev kit and not the PIN on the iBasis SIM card.

Also re-compile the app used to enter the certificate to remove the global ASSERT().  The app gets too slow with global ASSERT() and the LTE Link Monitor times out the command before the OK gets returned.

The asset_tracker application can connect and report the flip-switch state.  The GPS address is wrong since I'm on the v1.0.0 which doesn't do real GPS data.  I'm on the master branch now but those problems will be a different ticket.