*** Booting Zephyr OS build v3.2.99-ncs2 *** I: Starting bootloader I: Primary image: magic=unset, swap_type=0x1, copy_done=0x3, image_ok=0x3 I: Secondary image: magic=unset, swap_type=0x1, copy_done=0x3, image_ok=0x3 I: Boot source: none I: Swap type: none I: Bootloader chainload address offset: 0x10000 [00:00:00.252,899] nrf_modem: Initializing libmodem 2.3.1-8622ee1c632e [00:00:00.252,960] nrf_modem: Control region at 0x20008000 (0x4e8 bytes) [00:00:00.252,990] nrf_modem: TX region at 0x200084e8 (0x2000 bytes) [00:00:00.253,021] nrf_modem: RX region at 0x2000a568 (0x2000 bytes) [00:00:00.253,021] nrf_modem: Trace region at 0x2000c568 (0x4000 bytes) [00:00:00.430,541] nrf_modem: IPC trace ready [00:00:00.476,898] nrf_modem: Handshake completed [00:00:00.476,928] nrf_modem: RPC ready [00:00:00.477,325] nrf_modem: _nrf_modem_lib_init: Modem library has initialized, ret 0 [00:00:00.477,325] nrf_modem: _nrf_modem_lib_init: Modem init callback: 0x3688d [00:00:00.477,355] nrf_modem: _nrf_modem_lib_init: Modem init callback: 0x2676d [00:00:00.477,447] nrf_modem: AT+CGDCONT=0,IPV4V6,pelion [00:00:00.484,130] nrf_modem: AT+CGAUTH=0,2,default,void [00:00:00.484,588] nrf_modem: _nrf_modem_lib_init: Modem init callback: 0x364e3 [00:00:00.484,680] nrf_modem_lib_trace: Trace thread ready [00:00:00.484,771] nrf_modem: AT%XMODEMTRACE=1,2 [00:00:00.486,053] nrf_modem_lib_trace: Trace level override: 2 *** Booting Zephyr OS build v3.2.99-ncs2 *** [00:00:00.486,236] app: main() started [00:00:01.486,297] app: CustomBoardInit() started [00:00:01.496,429] app: CustomBoardInit() finished [00:00:01.496,459] app: NetworkInit() started [00:00:01.496,520] nrf_modem: AT+CMEE? [00:00:01.505,371] nrf_modem: AT+CMEE=1 [00:00:01.505,828] nrf_modem: AT%CMNG=2,100,0 [00:00:01.509,582] nrf_modem: AT+CMEE=0 [00:00:01.510,101] nrf_modem: AT%XSYSTEMMODE? [00:00:01.510,559] lte_lc: init_and_config: System mode (1) and preference (0) are already configured [00:00:01.510,650] nrf_modem: AT+CEREG=5 [00:00:01.510,986] nrf_modem: AT+CSCON=1 [00:00:01.511,352] nrf_modem: AT+CEREG? [00:00:01.511,779] nrf_modem: AT+CFUN? [00:00:01.512,207] nrf_modem: AT%XSYSTEMMODE=1,0,0,0 [00:00:01.514,282] nrf_modem: AT+CEREG=5 [00:00:01.514,617] nrf_modem: AT+CSCON=1 [00:00:01.514,984] nrf_modem: AT+CFUN=1 [00:00:01.544,799] lte_lc: lte_lc_func_mode_set: CFUN monitor callback: 0x3689f [00:00:01.544,830] pdn: on_cfun: Subscribing to +CNEC=16 and +CGEREP=1 [00:00:01.544,891] nrf_modem: AT+CNEC=16 [00:00:01.545,318] nrf_modem: AT+CGEREP=1 [00:00:04.847,747] lte_lc: at_handler_cereg: +CEREG notification: +CEREG: 2,"B481","01D05B0D",7 [00:00:04.847,961] lte_lc_helpers: parse_cereg: Network registration status: 2 [00:00:04.847,991] lte_lc_helpers: parse_cereg: LTE mode: 7 [00:00:04.848,052] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:04.848,052] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:04.848,144] nrf_modem: AT%XTIME=1 [00:00:04.848,510] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:04.848,541] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:04.848,541] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:04.848,571] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:04.848,571] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:04.848,602] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:04.848,602] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:04.938,201] lte_lc: at_handler_cscon: +CSCON notification [00:00:04.938,323] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:04.938,323] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:04.938,354] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:07.587,036] lte_lc: at_handler_cereg: +CEREG notification: +CEREG: 5,"B481","01D05B0D",7,,,"11100000","11100000" [00:00:07.587,310] lte_lc_helpers: parse_cereg: Network registration status: 5 [00:00:07.587,341] lte_lc_helpers: parse_cereg: LTE mode: 7 [00:00:07.587,402] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:07.587,432] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:07.587,432] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:07.587,524] nrf_modem: AT%XMONITOR [00:00:07.588,195] lte_lc_helpers: parse_psm: TAU: 3240 sec, active time: -1 sec [00:00:07.588,195] lte_lc: lte_lc_psm_get: TAU: 3240 sec, active time: -1 sec [00:00:07.588,226] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:07.588,226] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:07.588,256] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:07.616,790] date_time: date_time_core_current_check: Date time never set [00:00:07.616,821] date_time: date_time_at_xtime_handler: Time obtained from cellular network (XTIME notification) [00:00:07.616,851] date_time: date_time_core_schedule_update: New periodic date time update in: 14400 seconds [00:00:07.616,882] date_time: date_time_core_store: System time updated: 2023-03-10 23:08:59 [00:00:07.687,652] app: current time: 1678489739070 [00:00:13.912,170] lte_lc: at_handler_cscon: +CSCON notification [00:00:13.912,322] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:13.912,322] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:13.912,322] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:17.687,744] app: NetworkInit() finished [00:00:17.687,896] nrf_modem: AT%XCBAND [00:00:17.696,929] nrf_modem: AT%XCBAND=? [00:00:17.697,418] nrf_modem: AT+CGDCONT? [00:00:17.698,425] nrf_modem: AT+CEMODE? [00:00:17.705,108] nrf_modem: AT+COPS? [00:00:17.705,657] nrf_modem: AT+CEREG? [00:00:17.706,390] nrf_modem: AT+CEREG? [00:00:17.707,153] nrf_modem: AT%XSYSTEMMODE? [00:00:17.707,794] nrf_modem: AT%XSYSTEMMODE? [00:00:17.708,557] nrf_modem: AT%XSYSTEMMODE? [00:00:17.709,320] nrf_modem: AT+CGDCONT? [00:00:17.710,113] nrf_modem: AT+CESQ [00:00:17.716,796] nrf_modem: AT+CGMR [00:00:17.717,254] nrf_modem: AT%XVBAT [00:00:17.717,803] nrf_modem: AT+CGSN [00:00:17.718,231] app: signal strength (RSRP): -66 dBm [00:00:17.718,261] app: battery level: 3292 mV [00:00:17.718,292] app: modem firmware: mfw_nrf9160_1.3.3 [00:00:17.718,353] nrf_modem: Service has port: 443 [00:00:17.718,383] nrf_modem: getaddrinfo() fam: 0x1, type 0x1, proto 0x6 [00:00:17.840,759] lte_lc: at_handler_cscon: +CSCON notification [00:00:17.840,881] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:17.840,911] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:17.840,911] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:18.146,514] nrf_modem: RPC_IP_GETADDRINFO_RES, result RPC_IP_ERR_OK [00:00:18.146,606] nrf_modem: ai_length 0x4d, ai_family 0x1, ai_addrlen 0x4, ai_namelen 0x31 [00:00:18.146,697] nrf_modem: Resolved 216.239.36.54 [00:00:18.146,850] nrf_modem: socket() fam 0x1, type 0x1, proto 0x102 [00:00:18.147,277] nrf_modem: RPC_IP_OPEN_RES, result RPC_IP_ERR_OK [00:00:18.147,369] nrf_modem: TLS peer verification 2, fd 0x0 [00:00:18.147,430] nrf_modem: TLS tags: 1, fd 0x0 [00:00:18.147,460] nrf_modem: connect() fd 0x0 [00:00:18.147,491] nrf_modem: sa_family 0x1, destaddr_len 0x4, destport 443 [00:00:18.361,450] nrf_modem: RPC_IP_CONNECT_RES fd 0x0, result RPC_IP_ERR_OK [00:00:18.361,480] nrf_modem: Waiting for handshake semaphore [00:00:18.361,541] nrf_modem: Attaching sock fd 0x0 [00:00:18.361,541] nrf_modem: Hostname: none [00:00:18.361,602] nrf_modem: role 0x2, verify 0x2, cache 0x0, tags count 1 [00:00:18.361,602] nrf_modem: tag[0]: 100 [00:00:18.371,185] nrf_modem: RPC_IP_TLS_ATTACH_RES fd 0x0, result RPC_IP_ERR_OK [00:00:20.389,404] nrf_modem: RPC_IP_TLS_HANDSHAKE_COMPLETE_NTF fd 0x0, result RPC_IP_ERR_OK [00:00:20.389,495] app: SocketTCPConnect() created socket 0 [00:00:20.389,587] net_http: http_flush_data: Data to send 47 45 54 20 2f 74 65 73 74 5f 6c 6f 6e 67 5f 68 |GET /tes t_long_h 74 74 70 5f 67 65 74 3f 64 65 6c 61 79 3d 32 26 |ttp_get? delay=2& 73 69 7a 65 3d 31 30 30 20 48 54 54 50 2f 31 2e |size=100 HTTP/1. 31 0d 0a 48 6f 73 74 3a 20 75 73 2d 63 65 6e 74 |1..Host: us-cent 72 61 6c 31 2d 64 65 76 65 6c 6f 70 6d 65 6e 74 |ral1-dev elopment 2d 32 32 32 36 32 33 2e 63 6c 6f 75 64 66 75 6e |-222623. cloudfun 63 74 69 6f 6e 73 2e 6e 65 74 0d 0a 0d 0a |ctions.n et.... [00:00:20.389,617] nrf_modem: send() fd 0x0, len 110, blocking [00:00:20.390,258] nrf_modem: RPC_IP_SEND_RES fd 0x0, result RPC_IP_ERR_OK [00:00:20.390,350] nrf_modem: send() fd 0x0, 110 bytes sent [00:00:20.390,380] net_http: http_client_req: (0x20015e28): Sent 110 bytes [00:00:20.390,441] nrf_modem: recv() fd 0x0, buf 0x2001a8a8, len 256, flags 0 (blocking) [00:00:22.689,331] nrf_modem: RPC_IP_RECVFROM_NTF, fd 0x0 (513 bytes) [00:00:22.689,392] nrf_modem: No sender address [00:00:22.689,422] nrf_modem: Incoming pkt, data 0x2000a718, len 513 [00:00:22.689,514] net_http: on_message_begin: (0x20015e28): -- HTTP GET response (headers) -- [00:00:22.689,575] net_http: on_status: (0x20015e28): HTTP response status 200 OK [00:00:22.689,758] net_http: print_header_field: (0x20015e28): [12] Content-Type [00:00:22.689,941] net_http: print_header_field: (0x20015e28): [24] text/html; charset=utf-8 [00:00:22.690,093] net_http: print_header_field: (0x20015e28): [21] Function-Execution-Id [00:00:22.690,246] net_http: print_header_field: (0x20015e28): [12] cxkra63w4sfl [00:00:22.690,399] net_http: print_header_field: (0x20015e28): [21] X-Cloud-Trace-Context [00:00:22.690,551] net_http: print_header_field: (0x20015e28): [36] e5d68e597d45fadef0feefd31aedb491;o=1 [00:00:22.690,673] net_http: print_header_field: (0x20015e28): [4] Date [00:00:22.690,795] net_http: print_header_field: (0x20015e28): [29] Fri, 10 Mar 2023 23:09:14 GMT [00:00:22.690,887] net_http: print_header_field: (0x20015e28): [6] Server [00:00:22.690,979] net_http: print_header_field: (0x20015e28): [15] Google Frontend [00:00:22.691,101] net_http: print_header_field: (0x20015e28): [14] Content-Length [00:00:22.691,162] net_http: print_header_field: (0x20015e28): [3] 100 [00:00:22.691,253] net_http: print_header_field: (0x20015e28): [7] Alt-Svc [00:00:22.691,314] net_http: print_header_field: (0x20015e28): [9] h3=":443" [00:00:22.691,345] net_http: http_wait_data: (0x20015e28): Calling callback for partitioned 256 len data [00:00:22.691,345] app: HttpResponseCallback() called [00:00:22.691,375] app: got http status code update: 200 [00:00:22.691,436] nrf_modem: recv() fd 0x0, buf 0x2001a8a8, len 256, flags 0 (blocking) [00:00:22.691,650] net_http: print_header_field: (0x20015e28): [127] ; ma=2592000,h3-29=":443"; ma=2592000,h3-Q050=":443"; ma=2592000,h3-Q046=":443"; ma=2592000,h3-Q043=":443"; ma=2592000,quic=":4 [00:00:22.691,680] net_http: on_headers_complete: (0x20015e28): Headers complete [00:00:22.691,680] net_http: on_body: (0x20015e28): Processed 99 length 99 [00:00:22.691,711] net_http: http_wait_data: (0x20015e28): Calling callback for partitioned 256 len data [00:00:22.691,711] app: HttpResponseCallback() called [00:00:22.691,741] app: got a body fragment of length 99 [00:00:22.691,772] nrf_modem: recv() fd 0x0, buf 0x2001a8a8, len 256, flags 0 (blocking) [00:00:22.691,833] nrf_modem: Freeing pkt 0x200168ec, pkt.data 0x2000a718 [00:00:22.691,864] net_http: on_body: (0x20015e28): Processed 100 length 1 [00:00:22.691,894] net_http: on_message_complete: (0x20015e28): -- HTTP GET response (complete) -- [00:00:22.691,925] net_http: http_wait_data: (0x20015e28): Calling callback for 1 len data [00:00:22.691,925] app: HttpResponseCallback() called [00:00:22.691,955] app: got a body fragment of length 1 [00:00:22.691,955] net_http: http_client_req: (0x20015e28): Received 513 bytes [00:00:22.691,986] nrf_modem: close() fd 0x0 [00:00:22.693,634] nrf_modem: RPC_IP_CLOSE_RES fd 0x0, result RPC_IP_ERR_OK [00:00:22.693,695] app: HttpGet() was successful!!! [00:00:23.942,474] lte_lc: at_handler_cscon: +CSCON notification [00:00:23.942,596] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:23.942,626] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:23.942,626] lte_lc_helpers: event_handler_list_dispatch: Done [00:00:24.016,815] lte_lc: at_handler_cscon: +CSCON notification [00:00:24.016,937] lte_lc_helpers: event_handler_list_dispatch: Dispatching events: [00:00:24.016,937] lte_lc_helpers: event_handler_list_dispatch: - handler=0x00026AF9 [00:00:24.016,937] lte_lc_helpers: event_handler_list_dispatch: Done