HTTPS connection using AT#XHTTPCCON fails to connect to server

[EmbeddedDan]

I'm using the serial_lte_modem application from Tag v1.5.0-rc1, on the nrf9160dk evaluation board to make a secure connection to google.com but it returns #XHTTPCCON:0.

The local service provider uses NB-Iot and I'm able to connect to a server using a none secure connection.

Could you please look at the evidence and remark on what I'm doing wrong?

The CA certificate is loaded in the modem certificate storage. No other certificates are loaded.

AT%CMNG=2,42,0
%CMNG: 42,0,"0000000000000000000000000000000000000000000000000000000000000000","-----BEGIN CERTIFICATE-----
MIIDujCCAqKgAwIBAgILBAAAAAABD4Ym5g0wDQYJKoZIhvcNAQEFBQAwTDEgMB4G
A1UECxMXR2xvYmFsU2lnbiBSb290IENBIC0gUjIxEzARBgNVBAoTCkdsb2JhbFNp
Z24xEzARBgNVBAMTCkdsb2JhbFNpZ24wHhcNMDYxMjE1MDgwMDAwWhcNMjExMjE1
MDgwMDAwWjBMMSAwHgYDVQQLExdHbG9iYWxTaWduIFJvb3QgQ0EgLSBSMjETMBEG
A1UEChMKR2xvYmFsU2lnbjETMBEGA1UEAxMKR2xvYmFsU2lnbjCCASIwDQYJKoZI
hvcNAQEBBQADggEPADCCAQoCggEBAKbPJA6+Lm8omUVCxKs+IVSbC9N/hHD6ErPL
v4dfxn+G07IwXNb9rfF73OX4YJYJkhD10FPe+3t+c4isUoh7SqbKSaZeqKeMWhG8
eoLrvozps6yWJQeXSpkqBy+0Hne/ig+1AnwblrjFuTosvNYSuetZfeLQBoZfXklq
tTleiDTsvHgMCJiEbKjNS7SgfQx5TfC4LcshytVsW33hoCmEofnTlEnLJGKRILzd
C9XZzPnqJworc5HGnRusyMvo4KD0L5CLTfuwNhv2GXqF4G3yYROIXJ/gkwpRl4pa
zq+r1feqCapgvdzZX99yqWATXgAByUr6P6TqBwMhAo6CygPCm48CAwEAAaOBnDCB
mTAOBgNVHQ8BAf8EBAMCAQYwDwYDVR0TAQH/BAUwAwEB/zAdBgNVHQ4EFgQUm+IH
V2ccHsBqBt5ZtJot39wZhi4wNgYDVR0fBC8wLTAroCmgJ4YlaHR0cDovL2NybC5n
bG9iYWxzaWduLm5ldC9yb290LXIyLmNybDAfBgNVHSMEGDAWgBSb4gdXZxwewGoG
3lm0mi3f3BmGLjANBgkqhkiG9w0BAQUFAAOCAQEAmYFThxxol4aR7OBKuEQLq4Gs
J0/WwbgcQ3izDJr86iw8bmEbTUsp9Z8FHSbBuOmDAGJFtqkIk7mpM0sYmsL4h4hO
291xNBrBVNpGP+DTKqttVCL1OmLNIG+6KYnX3ZHu01yiPqFbQfXf5WRDLenVOavS
ot+3i9DAgBkcRcAtjOj4LaR0VknFBbVPFd5uRHg5h6h+u/N5GJG79G+dwfCMNYxd
AfvDbbnvRG15RjF+Cv6pgsH/76tuIMRQyV+dTZsXjAzlAcmgQWpzU/qlULRuJQ/7
TBj0/VLZjmmx6BEP3ojY+x1J96relc8geMJgEtslQIxq/H5COEBkEveegeGTLg==
-----END CERTIFICATE-----"

This is the AT commands I send to the modem using a script:

****************
Nordic AT Client
****************
COM /dev/ttyACM0 Open
AT
OK
AT#XSLMUART?
#XSLMUART: 115200
OK
AT+CFUN=0
OK
AT%XSYSTEMMODE=0,1,0,2
OK
AT%XBANDLOCK=1,"10000000"
OK
AT%XDEEPSEARCH=1
OK
AT+CGDCONT=0,"IP","nbiot.vodacom.za"
OK
AT+COPS=0
OK
AT+CEREG=5
OK
AT+CFUN=21
OK
AT%XICCID
ERROR
Check SIM
AT+CPIN="3349"
OK
+CEREG: 2,"5209","0A19790D",9,0,0,"11100000","11100000"
+CEREG: 1,"5209","0A19790D",9,,,"11100000","00000110"
AT%XICCID
%XICCID: 89330000000020549718
OK
Waiting 1 sec
AT#XHTTPCCON=1,"google.com",443,42
#XHTTPCCON:0
ERROR

Attached is a modem trace for your perusal

trace-2021-04-22T10-26-22.978Z.bin

[Heidi]

Hi!

First question, did you add CONFIG_SLM_HTTPC=y to the prj.conf file, to enable the HTTP module in the SLM application? 

In the meantime, I will take a look at the trace.

Best regards,

Heidi

[EmbeddedDan]

The serial_lte_modem application does have the necessary configurations set:

#
# Copyright (c) 2020 Nordic Semiconductor ASA
#
# SPDX-License-Identifier: LicenseRef-Nordic-5-Clause
#
# General config
CONFIG_LOG=y
CONFIG_LOG_DEFAULT_LEVEL=3
CONFIG_STACK_SENTINEL=y
CONFIG_NEWLIB_LIBC=y
CONFIG_NEWLIB_LIBC_FLOAT_PRINTF=y
CONFIG_RING_BUFFER=y

# Segger RTT
CONFIG_USE_SEGGER_RTT=y
CONFIG_RTT_CONSOLE=y
CONFIG_UART_CONSOLE=n
CONFIG_LOG_BACKEND_RTT=y
CONFIG_LOG_BACKEND_UART=n

# Network
CONFIG_NETWORKING=y
CONFIG_NET_SOCKETS=y
CONFIG_NET_NATIVE=n

# Modem library
CONFIG_NRF_MODEM_LIB=y
# Align the max FD entry to NRF_MODEM_MAX_SOCKET_COUNT(8)
CONFIG_POSIX_MAX_FDS=8
# Enable below for modem trace
CONFIG_NRF_MODEM_LIB_TRACE_ENABLED=y

# Use GPIO
CONFIG_GPIO=y
CONFIG_GPIO_NRFX=y
CONFIG_GPIO_NRF_P0=y

# UART interface
CONFIG_SERIAL=y
CONFIG_UART_ASYNC_API=y
CONFIG_NRFX_TIMER2=y

# LTE link control
CONFIG_LTE_LINK_CONTROL=y
CONFIG_LTE_AUTO_INIT_AND_CONNECT=n

# Stacks and heaps
CONFIG_MAIN_STACK_SIZE=4096
CONFIG_HEAP_MEM_POOL_SIZE=16384
CONFIG_SYSTEM_WORKQUEUE_STACK_SIZE=4096

# AT_CMD
# Enable AT_CMD debug for details
#CONFIG_AT_CMD_LOG_LEVEL_DBG=y
CONFIG_AT_CMD_RESPONSE_MAX_LEN=4096

# Device power management
CONFIG_DEVICE_POWER_MANAGEMENT=y

# Enable SUPL client support
#CONFIG_SUPL_CLIENT_LIB=y

# FOTA
CONFIG_HTTP_PARSER_URL=y
CONFIG_FOTA_DOWNLOAD=y
CONFIG_FOTA_DOWNLOAD_PROGRESS_EVT=y
CONFIG_DFU_TARGET=y
CONFIG_DOWNLOAD_CLIENT=y
CONFIG_DOWNLOAD_CLIENT_STACK_SIZE=4096
CONFIG_BOOTLOADER_MCUBOOT=y
CONFIG_IMG_MANAGER=y
CONFIG_FLASH=y
CONFIG_IMG_ERASE_PROGRESSIVELY=y

#
# SLM-specific configurations
#
CONFIG_SLM_LOG_LEVEL_INF=y
# Configure external XTAL for UART
CONFIG_SLM_EXTERNAL_XTAL=n
# Enable GPIO wakeup if sleep is expected
#CONFIG_SLM_GPIO_WAKEUP=y
# Use UART_0 (when working with PC terminal)
CONFIG_UART_0_NRF_HW_ASYNC_TIMER=2
CONFIG_SLM_DATAMODE_HWFC=n
# Use UART_2 (when working with external MCU)
#CONFIG_SLM_CONNECT_UART_2=y
#CONFIG_UART_2_NRF_HW_ASYNC_TIMER=2

# Use optional GPS service
#CONFIG_SLM_GPS=y
# Use optional FTP client service
#CONFIG_SLM_FTPC=y
# Use optional MQTT client service
#CONFIG_SLM_MQTTC=y
# Use optional HTTP client service
CONFIG_SLM_HTTPC=y
# Enable Key Management Library
CONFIG_MODEM_KEY_MGMT=y

#Enable Debug symbols
CONFIG_DEBUG_OPTIMIZATIONS=y

[Heidi]

Thanks. What MFW version are you running?

[EmbeddedDan]

mfw_nrf9160_1.2.3

[Heidi]

Hi

It looks like you're not waiting for +CEREG to return status 5, Registered, roaming before running the #HTTPCCON command. Could that be right?

Also, could you change this to CONFIG_SLM_LOG_LEVEL_DBG=y to get all log messages and add CONFIG_AT_CMD_LOG_LEVEL_DBG=y as well. 

[EmbeddedDan]

I'm waiting for either +CEREG:1 or +CEREG: 5.  In this case I receive +CEREG: 1 which means 'Registered, home network'.

I have attached the logs with the new log levels enabled.

The first modem trace is with commands entered using the LTE Link Monitor.

trace-2021-04-30T06-22-27.472Z.bin

Fullscreen 2021-04-30T06_08_10.897Z-log.txt Download

2021-04-30T06:08:11.711Z INFO Application data folder: /home/danie/.config/nrfconnect/pc-nrfconnect-linkmonitor
2021-04-30T06:08:11.779Z VERBOSE Could not fetch serial number for serial port at /dev/ttyS0
2021-04-30T06:08:11.780Z VERBOSE Could not fetch serial number for serial port at /dev/ttyS1
2021-04-30T06:08:11.780Z VERBOSE Could not fetch serial number for serial port at /dev/ttyS2
2021-04-30T06:08:11.780Z VERBOSE Could not fetch serial number for serial port at /dev/ttyS3
2021-04-30T06:08:11.843Z DEBUG App pc-nrfconnect-linkmonitor v1.1.10 official
2021-04-30T06:08:11.843Z DEBUG App path: /home/danie/.nrfconnect-apps/node_modules/pc-nrfconnect-linkmonitor
2021-04-30T06:08:11.843Z DEBUG nRFConnect 3.6.1 is supported by the app (^3.6.0)
2021-04-30T06:08:11.843Z DEBUG nRFConnect path: /tmp/.mount_nrfcon3e0APO/resources/app.asar
2021-04-30T06:08:11.843Z DEBUG HomeDir: /home/danie
2021-04-30T06:08:11.843Z DEBUG TmpDir: /tmp
2021-04-30T06:08:31.184Z INFO Modem port is opened
2021-04-30T06:08:31.193Z DEBUG modem >> AT+CFUN?
2021-04-30T06:08:49.881Z DEBUG modem >> AT%XSYSTEMMODE=0,1,0,2
2021-04-30T06:08:50.882Z ERROR Error: 'AT%XSYSTEMMODE=0,1,0,2
' timed out
2021-04-30T06:09:02.801Z DEBUG modem >> AT
2021-04-30T06:09:03.801Z ERROR Error: 'AT
' timed out
2021-04-30T06:09:07.972Z DEBUG modem >> AT+CFUN?
2021-04-30T06:09:08.973Z ERROR Error: 'AT+CFUN?
' timed out
2021-04-30T06:09:13.417Z INFO Modem port is closed
2021-04-30T06:09:13.427Z INFO Modem port is opened
2021-04-30T06:09:13.431Z DEBUG modem >> AT+CFUN?
2021-04-30T06:09:28.039Z DEBUG modem << Ready
2021-04-30T06:09:52.281Z DEBUG modem >> AT+CFUN=0
2021-04-30T06:09:52.304Z DEBUG modem << OK
2021-04-30T06:09:58.833Z DEBUG modem >> AT%XSYSTEMMODE=0,1,0,2
2021-04-30T06:09:58.855Z DEBUG modem << OK
2021-04-30T06:10:05.198Z DEBUG modem >> AT%XBANDLOCK=1,"10000000"
2021-04-30T06:10:05.216Z DEBUG modem << OK
2021-04-30T06:10:11.760Z DEBUG modem >> AT%XDEEPSEARCH=1
2021-04-30T06:10:11.787Z DEBUG modem << OK
2021-04-30T06:10:18.341Z DEBUG modem >> AT+CGDCONT=0,"IP","nbiot.vodacom.za"
2021-04-30T06:10:18.365Z DEBUG modem << OK
2021-04-30T06:10:55.426Z DEBUG modem >> AT+CFUN=21
2021-04-30T06:10:55.531Z DEBUG modem << OK
2021-04-30T06:10:58.923Z DEBUG modem >> AT+CPIN="3349"
2021-04-30T06:10:58.954Z DEBUG modem << OK
2021-04-30T06:11:12.340Z DEBUG modem >> AT+CFUN?
2021-04-30T06:11:12.358Z DEBUG modem << +CFUN: 1
2021-04-30T06:11:12.382Z DEBUG modem << OK
2021-04-30T06:11:12.387Z DEBUG modem >> AT+CGSN=1
2021-04-30T06:11:12.415Z DEBUG modem << +CGSN: "352656100264236"
2021-04-30T06:11:12.419Z DEBUG modem << 
2021-04-30T06:11:12.422Z DEBUG modem << OK
2021-04-30T06:11:12.427Z DEBUG modem >> AT+CGMI
2021-04-30T06:11:12.458Z DEBUG modem << Nordic Semiconductor ASA
2021-04-30T06:11:12.460Z DEBUG modem << OK
2021-04-30T06:11:12.465Z DEBUG modem >> AT+CGMM
2021-04-30T06:11:12.491Z DEBUG modem << nRFFFFFFFFF-SICA
2021-04-30T06:11:12.493Z DEBUG modem << 
2021-04-30T06:11:12.496Z DEBUG modem << OK
2021-04-30T06:11:12.503Z DEBUG modem >> AT+CGMR
2021-04-30T06:11:12.524Z DEBUG modem << mfw_nrf9160_1.2.3
2021-04-30T06:11:12.536Z DEBUG modem << OK
2021-04-30T06:11:12.539Z INFO Nordic Semiconductor ASA nRFFFFFFFFF-SICA [mfw_nrf9160_1.2.3] SerNr: 352656100264236
2021-04-30T06:11:12.543Z DEBUG modem >> AT+CEMODE?
2021-04-30T06:11:12.564Z DEBUG modem << +CEMODE: 0
2021-04-30T06:11:12.569Z DEBUG modem << OK
2021-04-30T06:11:12.598Z DEBUG modem >> AT%XCBAND=?
2021-04-30T06:11:12.618Z DEBUG modem << %XCBAND: (8)
2021-04-30T06:11:12.620Z DEBUG modem << OK
2021-04-30T06:11:12.633Z DEBUG modem >> AT+CMEE?
2021-04-30T06:11:12.656Z DEBUG modem << +CMEE: 0
2021-04-30T06:11:12.658Z DEBUG modem << OK
2021-04-30T06:11:12.662Z DEBUG modem >> AT+CMEE=1
2021-04-30T06:11:12.679Z DEBUG modem << OK
2021-04-30T06:11:12.682Z DEBUG modem >> AT+CNEC?
2021-04-30T06:11:12.701Z DEBUG modem << +CNEC: 0
2021-04-30T06:11:12.703Z DEBUG modem << OK
2021-04-30T06:11:12.708Z DEBUG modem >> AT+CNEC=24
2021-04-30T06:11:12.727Z DEBUG modem << OK
2021-04-30T06:11:12.730Z DEBUG modem >> AT+CGEREP?
2021-04-30T06:11:12.749Z DEBUG modem << +CGEREP: 0,0
2021-04-30T06:11:12.750Z DEBUG modem << OK
2021-04-30T06:11:12.754Z DEBUG modem >> AT+CGDCONT?
2021-04-30T06:11:12.776Z DEBUG modem << +CGDCONT: 0,"IP","nbiot.vodacom.za","",0,0
2021-04-30T06:11:12.777Z DEBUG modem << 
2021-04-30T06:11:12.778Z DEBUG modem << OK
2021-04-30T06:11:12.786Z DEBUG modem >> AT+CGACT?
2021-04-30T06:11:12.808Z DEBUG modem << +CGACT: 0,0
2021-04-30T06:11:12.813Z DEBUG modem << OK
2021-04-30T06:11:12.825Z DEBUG modem >> AT+CGEREP=1
2021-04-30T06:11:12.844Z DEBUG modem << OK
2021-04-30T06:11:12.848Z DEBUG modem >> AT+CIND=1,1,1
2021-04-30T06:11:12.865Z DEBUG modem << OK
2021-04-30T06:11:12.867Z DEBUG modem >> AT+CEREG=5
2021-04-30T06:11:12.885Z DEBUG modem << OK
2021-04-30T06:11:12.891Z DEBUG modem >> AT+CEREG?
2021-04-30T06:11:12.920Z DEBUG modem << +CEREG: 5,2,"5209","0A19790D",9,0,0,"11100000","11100000"
2021-04-30T06:11:12.935Z DEBUG modem << OK
2021-04-30T06:11:12.953Z DEBUG modem >> AT%CESQ=1
2021-04-30T06:11:12.981Z DEBUG modem << OK
2021-04-30T06:11:12.985Z DEBUG modem >> AT+CESQ
2021-04-30T06:11:13.003Z DEBUG modem << +CESQ: 99,99,255,255,22,55
2021-04-30T06:11:13.006Z DEBUG modem << OK
2021-04-30T06:11:13.026Z DEBUG modem >> AT%XSIM=1
2021-04-30T06:11:13.045Z DEBUG modem << OK
2021-04-30T06:11:13.049Z DEBUG modem >> AT%XSIM?
2021-04-30T06:11:13.068Z DEBUG modem << %XSIM: 1
2021-04-30T06:11:13.069Z DEBUG modem << OK
2021-04-30T06:11:13.081Z DEBUG modem >> AT+CPIN?
2021-04-30T06:11:13.099Z DEBUG modem << +CPIN: READY
2021-04-30T06:11:13.100Z DEBUG modem << OK
2021-04-30T06:11:13.115Z DEBUG modem >> AT+CPINR="SIM PIN"
2021-04-30T06:11:13.141Z DEBUG modem << +CPINR: "SIM PIN",3
2021-04-30T06:11:13.142Z DEBUG modem << OK
2021-04-30T06:11:13.147Z DEBUG modem >> AT+CIMI
2021-04-30T06:11:13.164Z DEBUG modem << 655013302054671
2021-04-30T06:11:13.165Z DEBUG modem << OK
2021-04-30T06:11:13.166Z INFO IMSIdentity: 655013302054671
2021-04-30T06:11:15.063Z DEBUG modem << %CESQ: 53,2,27,3
2021-04-30T06:11:26.348Z DEBUG modem << +CGEV: ME PDN ACT 0
2021-04-30T06:11:26.358Z DEBUG modem >> AT+CGDCONT?
2021-04-30T06:11:26.362Z DEBUG modem << +CEREG: 1,"5209","0A19790D",9,,,"11100000","00000110"
2021-04-30T06:11:26.363Z DEBUG modem << +CIND: "service",1
2021-04-30T06:11:26.375Z DEBUG modem << 
2021-04-30T06:11:26.379Z DEBUG modem << +CGDCONT: 0,"IP","nbiot.vodacom.za","10.170.27.20",0,0
2021-04-30T06:11:26.381Z DEBUG modem << OK
2021-04-30T06:11:26.391Z DEBUG modem >> AT+CGACT?
2021-04-30T06:11:26.412Z DEBUG modem << +CGACT: 0,1
2021-04-30T06:11:26.413Z DEBUG modem << OK
2021-04-30T06:11:54.617Z DEBUG modem >> AT#XHTTPCCON=1,"google.com",443,42"
2021-04-30T06:11:55.618Z ERROR Error: 'AT#XHTTPCCON=1,"google.com",443,42"
' timed out
2021-04-30T06:12:27.210Z DEBUG modem << %CESQ: 53,2,20,2
2021-04-30T06:12:37.022Z DEBUG modem << %CESQ: 54,2,27,3
2021-04-30T06:13:13.968Z DEBUG modem >> AT#XHTTPCCON=1,"google.com",443,42"
2021-04-30T06:13:13.977Z DEBUG modem << ERROR
2021-04-30T06:13:13.978Z ERROR Error: AT#XHTTPCCON=1,"google.com",443,42"
 failed
2021-04-30T06:14:07.702Z DEBUG modem >> AT+CFUN=0
2021-04-30T06:14:08.703Z ERROR Error: 'AT+CFUN=0
' timed out
2021-04-30T06:14:09.814Z DEBUG modem << +CGEV: ME PDN DEACT 0
2021-04-30T06:14:09.820Z DEBUG modem << +CEREG: 0,"5209","0A19790D",9,0,0,"11100000","11100000"
2021-04-30T06:14:09.824Z DEBUG modem >> AT+CGDCONT?
2021-04-30T06:14:09.837Z DEBUG modem << +CIND: "service",0
2021-04-30T06:14:09.838Z DEBUG modem << +CGEV: ME DETACH
2021-04-30T06:14:10.193Z DEBUG modem << 
2021-04-30T06:14:10.197Z DEBUG modem << %CESQ: 255,0,255,0
2021-04-30T06:14:10.199Z DEBUG modem << 
2021-04-30T06:14:10.200Z DEBUG modem << %XSIM: 0
2021-04-30T06:14:10.201Z DEBUG modem << 
2021-04-30T06:14:10.214Z DEBUG modem << 
2021-04-30T06:14:10.216Z DEBUG modem << OK
2021-04-30T06:14:10.219Z DEBUG modem >> AT+CGACT?
2021-04-30T06:14:10.263Z DEBUG modem << OK
2021-04-30T06:14:10.271Z DEBUG modem << OK
2021-04-30T06:14:18.069Z DEBUG modem >> AT+CFUN?
2021-04-30T06:14:18.086Z DEBUG modem << +CFUN: 0
2021-04-30T06:14:18.092Z DEBUG modem << OK
2021-04-30T06:15:31.668Z DEBUG modem >> AT+CFUN=0
2021-04-30T06:15:31.688Z DEBUG modem << OK
2021-04-30T06:15:38.024Z DEBUG modem >> AT%CMNG=2,42,0
2021-04-30T06:15:38.165Z DEBUG modem << %CMNG: 42,0,"0000000000000000000000000000000000000000000000000000000000000000","-----BEGIN CERTIFICATE-----MIIDdzCCAl+gAwIBAgIEAgAAuTANBgkqhkiG9w0BAQUFADBaMQswCQYDVQQGEwJJRTESMBAGA1UEChMJQmFsdGltb3JlMRMwEQYDVQQLEwpDeWJlclRydXN0MSIwIAYDVQQDExlCYWx0aW1vcmUgQ3liZXJUcnVzdCBSb290MB4XDTAwMDUxMjE4NDYwMFoXDTI1MDUxMjIzNTkwMFowWjELMAkGA1UEBhMCSUUxEjAQBgNVBAoTCUJhbHRpbW9yZTETMBEGA1UECxMKQ3liZXJUcnVzdDEiMCAGA1UEAxMZQmFsdGltb3JlIEN5YmVyVHJ1c3QgUm9vdDCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBAKMEuyKrmD1X6CZymrV51Cni4eiVgLGw41uOKymaZN+hXe2wCQVt2yguzmKiYv60iNoS6zjrIZ3AQSsBUnuId9Mcj8e6uYi1agnnc+gRQKfRzMpijS3ljwumUNKoUMMo6vWrJYeKmpYcqWe4PwzV9/lSEy/CG9VwcPCPwBLKBsua4dnKM3p31vjsufFoREJIE9LAwqSuXmD+tqYF/LTdB1kC1FkYmGP1pWPgkAx9XbIGevOF6uvUA65ehD5f/xXtabz5OTZydc93Uk3zyZAsuT3lySNTPx8kmCFcB5kpvcY67Oduhjprl3RjM71oGDHweI12v/yejl0qhqdNkNwnGjkCAwEAAaNFMEMwHQYDVR0OBBYEFOWdWTCCR1jMrPoIVDaGezq1BE3wMBIGA1UdEwEB/wQIMAYBAf8CAQMwDgYDVR0PAQH/BAQDAgEGMA0GCSqGSIb3DQEBBQUAA4IBAQCFDF2O5G9RaEIFoN27TyclhAO992T9Ldcw46QQF+vaKSm2eT929hkTI7gQCvlYpNRhcL0EYWoSihfVCr3FvDB81ukMJY2GQE/szKN+OMY3EU/t3WgxjkzSswF07r51XgdIGn9w/xZchMB5hbgF/X++ZRGjD8ACtPhSNzkE1akxehi/oCr0Epn3o0WC4zxe9Z2etciefC7IpJ5OCBRLbf1wbWsaY71k5h+3zvDyny67G7fyUIhzksLi4xaNmjICq44Y3ekQEe5+NauQrz4wlHrQMz2nZQ/1/I6eYs9HRCwBXbsdtTLSR9I4LtD+gdwyah617jzV/OeBHRnDJELqYzmp-----END CERTIFICATE-----"
2021-04-30T06:15:38.170Z DEBUG modem << OK
2021-04-30T06:15:45.525Z DEBUG modem >> AT%XSYSTEMMODE=0,1,0,2
2021-04-30T06:15:45.546Z DEBUG modem << OK
2021-04-30T06:15:51.632Z DEBUG modem >> AT%XBANDLOCK=1,"10000000"
2021-04-30T06:15:51.655Z DEBUG modem << OK
2021-04-30T06:16:03.218Z DEBUG modem >> AT+CGDCONT=0,"IP","nbiot.vodacom.za"
2021-04-30T06:16:03.243Z DEBUG modem << OK
2021-04-30T06:16:09.774Z DEBUG modem >> AT+CEREG=5
2021-04-30T06:16:09.791Z DEBUG modem << OK
2021-04-30T06:16:14.632Z DEBUG modem >> AT+CFUN=21
2021-04-30T06:16:14.737Z DEBUG modem << OK
2021-04-30T06:16:19.676Z DEBUG modem >> AT+CPIN="3349"
2021-04-30T06:16:19.709Z DEBUG modem << OK
2021-04-30T06:16:20.968Z DEBUG modem << +CEREG: 2,"5209","0A19790D",9,0,0,"11100000","11100000"
2021-04-30T06:16:29.023Z DEBUG modem << +CEREG: 1,"5209","0A19790D",9,,,"11100000","00000110"
2021-04-30T06:16:29.030Z DEBUG modem >> AT+COPS=3,2
2021-04-30T06:16:29.045Z DEBUG modem << OK
2021-04-30T06:16:29.048Z DEBUG modem >> AT+COPS?
2021-04-30T06:16:29.061Z DEBUG modem << +COPS: 0,2,"65501",9
2021-04-30T06:16:29.063Z DEBUG modem << OK
2021-04-30T06:16:29.088Z DEBUG modem >> AT%XCBAND
2021-04-30T06:16:29.113Z DEBUG modem << %XCBAND: 8
2021-04-30T06:16:29.115Z DEBUG modem << OK
2021-04-30T06:16:29.128Z DEBUG modem >> AT+CGDCONT?
2021-04-30T06:16:29.150Z DEBUG modem << +CGDCONT: 0,"IP","nbiot.vodacom.za","10.170.27.21",0,0
2021-04-30T06:16:29.153Z DEBUG modem << OK
2021-04-30T06:16:29.165Z DEBUG modem >> AT+CGACT?
2021-04-30T06:16:29.178Z DEBUG modem << +CGACT: 0,1
2021-04-30T06:16:29.180Z DEBUG modem << 
2021-04-30T06:16:29.181Z DEBUG modem << OK
2021-04-30T06:16:34.077Z DEBUG modem >> AT#XHTTPCCON=1,"google.com",443,42"
2021-04-30T06:16:35.078Z ERROR Error: 'AT#XHTTPCCON=1,"google.com",443,42"
' timed out
2021-04-30T06:19:43.371Z DEBUG modem >> AT#XHTTPCCON=0
2021-04-30T06:19:44.372Z ERROR Error: 'AT#XHTTPCCON=0
' timed out
2021-04-30T06:19:51.264Z DEBUG modem >> AT+CFUN?
2021-04-30T06:19:52.264Z ERROR Error: 'AT+CFUN?
' timed out
2021-04-30T06:20:01.357Z DEBUG modem >> AT+CFUN=0
2021-04-30T06:20:02.357Z ERROR Error: 'AT+CFUN=0
' timed out
2021-04-30T06:20:13.015Z INFO Modem port is closed
2021-04-30T06:20:13.032Z INFO Modem port is opened
2021-04-30T06:20:13.037Z DEBUG modem >> AT+CFUN?
2021-04-30T06:20:20.148Z DEBUG modem << Ready
2021-04-30T06:20:24.873Z DEBUG modem << OK
2021-04-30T06:22:00.269Z INFO Modem port is closed
2021-04-30T06:22:00.278Z INFO Modem port is opened
2021-04-30T06:22:00.283Z DEBUG modem >> AT+CFUN?
2021-04-30T06:22:03.628Z INFO Modem port is closed
2021-04-30T06:22:05.819Z INFO Modem port is opened
2021-04-30T06:22:05.823Z DEBUG modem >> AT+CFUN?
2021-04-30T06:22:13.480Z DEBUG modem << Ready
2021-04-30T06:22:32.038Z DEBUG modem << Ready
2021-04-30T06:22:36.271Z DEBUG modem >> AT+CFUN=0
2021-04-30T06:22:37.271Z ERROR Error: 'AT+CFUN=0
' timed out
2021-04-30T06:22:37.625Z DEBUG modem << OK
2021-04-30T06:22:44.056Z DEBUG modem >> AT%CMNG=2,42,0
2021-04-30T06:22:44.199Z DEBUG modem << %CMNG: 42,0,"0000000000000000000000000000000000000000000000000000000000000000","-----BEGIN CERTIFICATE-----MIIDdzCCAl+gAwIBAgIEAgAAuTANBgkqhkiG9w0BAQUFADBaMQswCQYDVQQGEwJJRTESMBAGA1UEChMJQmFsdGltb3JlMRMwEQYDVQQLEwpDeWJlclRydXN0MSIwIAYDVQQDExlCYWx0aW1vcmUgQ3liZXJUcnVzdCBSb290MB4XDTAwMDUxMjE4NDYwMFoXDTI1MDUxMjIzNTkwMFowWjELMAkGA1UEBhMCSUUxEjAQBgNVBAoTCUJhbHRpbW9yZTETMBEGA1UECxMKQ3liZXJUcnVzdDEiMCAGA1UEAxMZQmFsdGltb3JlIEN5YmVyVHJ1c3QgUm9vdDCCASIwDQYJKoZIhvcNAQEBBQADggEPADCCAQoCggEBAKMEuyKrmD1X6CZymrV51Cni4eiVgLGw41uOKymaZN+hXe2wCQVt2yguzmKiYv60iNoS6zjrIZ3AQSsBUnuId9Mcj8e6uYi1agnnc+gRQKfRzMpijS3ljwumUNKoUMMo6vWrJYeKmpYcqWe4PwzV9/lSEy/CG9VwcPCPwBLKBsua4dnKM3p31vjsufFoREJIE9LAwqSuXmD+tqYF/LTdB1kC1FkYmGP1pWPgkAx9XbIGevOF6uvUA65ehD5f/xXtabz5OTZydc93Uk3zyZAsuT3lySNTPx8kmCFcB5kpvcY67Oduhjprl3RjM71oGDHweI12v/yejl0qhqdNkNwnGjkCAwEAAaNFMEMwHQYDVR0OBBYEFOWdWTCCR1jMrPoIVDaGezq1BE3wMBIGA1UdEwEB/wQIMAYBAf8CAQMwDgYDVR0PAQH/BAQDAgEGMA0GCSqGSIb3DQEBBQUAA4IBAQCFDF2O5G9RaEIFoN27TyclhAO992T9Ldcw46QQF+vaKSm2eT929hkTI7gQCvlYpNRhcL0EYWoSihfVCr3FvDB81ukMJY2GQE/szKN+OMY3EU/t3WgxjkzSswF07r51XgdIGn9w/xZchMB5hbgF/X++ZRGjD8ACtPhSNzkE1akxehi/oCr0Epn3o0WC4zxe9Z2etciefC7IpJ5OCBRLbf1wbWsaY71k5h+3zvDyny67G7fyUIhzksLi4xaNmjICq44Y3ekQEe5+NauQrz4wlHrQMz2nZQ/1/I6eYs9HRCwBXbsdtTLSR9I4LtD+gdwyah617jzV/OeBHRnDJELqYzmp-----END CERTIFICATE-----"
2021-04-30T06:22:44.204Z DEBUG modem << OK
2021-04-30T06:22:49.778Z DEBUG modem >> AT%XSYSTEMMODE=0,1,0,2
2021-04-30T06:22:49.800Z DEBUG modem << OK
2021-04-30T06:22:55.687Z DEBUG modem >> AT%XBANDLOCK=1,"10000000"
2021-04-30T06:22:55.711Z DEBUG modem << OK
2021-04-30T06:23:02.908Z DEBUG modem >> AT%XDEEPSEARCH=1
2021-04-30T06:23:02.929Z DEBUG modem << OK
2021-04-30T06:23:10.424Z DEBUG modem >> AT+CGDCONT=0,"IP","nbiot.vodacom.za"
2021-04-30T06:23:10.444Z DEBUG modem << OK
2021-04-30T06:23:25.879Z DEBUG modem >> AT+COPS=0
2021-04-30T06:23:25.902Z DEBUG modem << OK
2021-04-30T06:23:34.448Z DEBUG modem >> AT+CEREG=5
2021-04-30T06:23:34.465Z DEBUG modem << OK
2021-04-30T06:23:40.291Z DEBUG modem >> AT+CFUN=21
2021-04-30T06:23:40.373Z DEBUG modem << OK
2021-04-30T06:23:45.658Z DEBUG modem >> AT+CPIN="3349"
2021-04-30T06:23:45.686Z DEBUG modem << OK
2021-04-30T06:23:47.252Z DEBUG modem << +CEREG: 2,"5209","0A19790D",9,0,0,"11100000","11100000"
2021-04-30T06:24:13.342Z DEBUG modem << +CEREG: 1,"5209","0A19790D",9,,,"11100000","00000110"
2021-04-30T06:24:13.349Z DEBUG modem >> AT+COPS=3,2
2021-04-30T06:24:13.366Z DEBUG modem << OK
2021-04-30T06:24:13.369Z DEBUG modem >> AT+COPS?
2021-04-30T06:24:13.386Z DEBUG modem << +COPS: 0,2,"65501",9
2021-04-30T06:24:13.400Z DEBUG modem << 
2021-04-30T06:24:13.402Z DEBUG modem << OK
2021-04-30T06:24:13.415Z DEBUG modem >> AT%XCBAND
2021-04-30T06:24:13.433Z DEBUG modem << %XCBAND: 8
2021-04-30T06:24:13.436Z DEBUG modem << OK
2021-04-30T06:24:13.457Z DEBUG modem >> AT+CGDCONT?
2021-04-30T06:24:13.485Z DEBUG modem << +CGDCONT: 0,"IP","nbiot.vodacom.za","10.170.27.27",0,0
2021-04-30T06:24:13.489Z DEBUG modem << OK
2021-04-30T06:24:13.504Z DEBUG modem >> AT+CGACT?
2021-04-30T06:24:13.530Z DEBUG modem << +CGACT: 0,1
2021-04-30T06:24:13.533Z DEBUG modem << OK
2021-04-30T06:24:20.234Z DEBUG modem >> AT+CGDCONT?
2021-04-30T06:24:20.256Z DEBUG modem << +CGDCONT: 0,"IP","nbiot.vodacom.za","10.170.27.27",0,0
2021-04-30T06:24:20.260Z DEBUG modem << OK
2021-04-30T06:24:28.878Z DEBUG modem >> AT#XHTTPCCON=1,"google.com",443,42"
2021-04-30T06:24:29.878Z ERROR Error: 'AT#XHTTPCCON=1,"google.com",443,42"
' timed out
2021-04-30T06:27:49.783Z INFO Modem port is closed
2021-04-30T06:28:37.945Z INFO Restoring from /home/danie/.config/nrfconnect/pc-nrfconnect-linkmonitor/logs/2021-04-30T06_08_10.897Z-log.txt

The second trace is with the automated script.

trace-2021-04-30T06-31-49.640Z.bin

[Heidi]

Thank you. I'm going to have someone from the modem team take a look at this tomorrow and then I'll get back to you.

[Heidi]

Hi

The log is not complete. A lot of traces seem to be missing which makes it difficult to analyze.

However, the bandlock setting might be something to check. The permanent bandlock is set to band 8 and band 7 is the only band visible in the log. Is that intentional?

 

[EmbeddedDan]

No that is a mistake. The only band we have here is Band 7, The bandlock setting was recommended by the local Nordic supplier. I will change that. 

Any recommendation to fix the problem with the incomplete log?