Hello,
I'm developing a NB-IOT application on the nRF9160-DK based on the 2.6.1-rc1 SDK. I'm experiencing a bug where the firmware gets stuck while serving a send() request. In particular the issue seems to happen when there's a timeout and the http_timeout function is called (part of the http_client library). This function seems to never return.
The system does not recover from there. Any suggestion?
Hello Marco,
Any suggestion?
Could you share a full application log starting from device boot, that shows the issue you are facing?
Thanks & regards,
Markus
Hello Marco,
Any suggestion?
Could you share a full application log starting from device boot, that shows the issue you are facing?
Thanks & regards,
Markus
Hi Albrecht Markus Schellenberger , thank you for your prompt reply. Please find it enclosed
SEGGER J-Link V7.68a - Real time terminal output
J-Link OB-K22-NordicSemi compiled Dec 3 2021 15:46:55 V1.0, SN=960038941
Process: JLinkGDBServerCLExe
*** Booting Zephyr OS build v2.6.0-rc1-ncs1 ***
[00:00:12.275,848] <inf> main_firmware: New backend API firmware started - compiled at Thu Jul 28 14:26:09 2022
[00:00:12.276,000] <dbg> main_firmware.main_backend_thread: Main backend thread, starting main loop...
[00:00:12.276,000] <dbg> main_firmware.main_backend_thread: Setting up NB-IoT things...
[00:00:12.515,899] <inf> AT: Test command AT%XMAGPIO?
[00:00:12.525,482] <inf> 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
[00:00:12.525,482] <dbg> nbiot.setup_nbiot: Deleting all certificates stored in
the modem, install new ones
[00:00:19.029,724] <inf> AT: Modem firmware version:
[00:00:19.037,841] <inf> AT: mfw_nrf9160_1.3.0
[00:00:19.046,447] <inf> main_firmware: IMEI nr: xxxx
[00:00:19.046,447] <dbg> main_firmware.main_backend_thread: NB-IoT setup is done, ready for usage!
[00:00:19.046,508] <inf> AT: Test command AT%XMAGPIO?
[00:00:19.055,725] <inf> 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
[00:00:19.055,725] <dbg> main_firmware.main_backend_thread: Connecting to the backend...
[00:00:19.055,755] <inf> nbiot: Waiting for network...
[00:00:19.064,208] <dbg> lte_lc.init_and_config: System mode (6) and preference (2) are already configured
[00:00:19.089,202] <dbg> lte_lc.lte_lc_system_mode_set: Sending AT command to set system mode: AT%XSYSTEMMODE=1,1,0,2
[00:00:24.850,067] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 2,"2D5D","019FFC67",9
[00:00:24.850,311] <dbg> lte_lc_helpers.parse_cereg: Network registration status: 2
[00:00:24.850,341] <dbg> lte_lc_helpers.parse_cereg: LTE mode: 9
[00:00:25.167,175] <dbg> lte_lc.at_handler: +CSCON notification
[00:00:25.167,297] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode connected
[00:00:26.621,337] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"2D5D","019FFC67",9,,,"11100000","00101000"
[00:00:26.621,643] <dbg> lte_lc_helpers.parse_cereg: Network registration status: 5
[00:00:26.621,673] <dbg> lte_lc_helpers.parse_cereg: LTE mode: 9
[00:00:26.621,734] <dbg> lte_lc_helpers.parse_psm: TAU: 28800 sec, active time: -1 sec
[00:00:26.621,826] <inf> nbiot: NB-IoT network connection established
[00:00:26.623,687] <wrn> AT: Failed to execute AT command (AT%CCLK?)!
[00:00:26.634,307] <wrn> AT: Failed to execute AT command (AT%CCLK?)!
[00:00:26.642,303] <inf> AT: LTE signal conditions:
[00:00:26.661,041] <inf> AT: %CONEVAL: 0,1,8,58,26,36,"019FFC67","20404",404,6254,20,0,0,15,1,1,107
[00:00:26.661,102] <inf> AT: Test command AT%XMAGPIO?
[00:00:26.664,215] <inf> 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
[00:00:26.666,351] <inf> main_firmware: Current network datetime (GMT): %CCLK: "22/07/29,11:53:37+01",1
[00:00:26.666,381] <dbg> main_firmware.connect_to_backend: Connecting to the backend server...
[00:00:27.122,222] <inf> transport: Connecting to api-dev.anything-connected.com
[00:00:29.965,362] <inf> main_firmware: Current time_measured (GMT): 2022-07-29 11:53:40.000000
[00:00:29.970,306] <inf> backend: Sending 313 bytes of payload for bulk upload
[00:00:29.970,397] <dbg> backend.send_upload_data: Payload (313 bytes): [{"time_measured":"2022-07-29 11:53:40.000000", "asset_id":1, "data_node_configuration":1, "data":{"ax":12345.123047 ,"ay":12345.123047 ,"az":12345.123047 ,"gx":12345.123047 ,"gy":12345.123047 ,"gz":12345.123047 ,"mx":12345.123047 ,"my":12345.123047 ,"mz":12345.123047 ,"temp":12345.123047 }}]
[00:00:29.970,581] <dbg> net_http: Data to send
50 4f 53 54 20 2f 6d 65 61 73 75 72 65 6d 65 6e |POST /me asuremen
74 73 2f 20 48 54 54 50 2f 31 2e 31 0d 0a 48 6f |ts/ HTTP /1.1..Ho
73 74 3a 20 61 70 69 2d 64 65 76 2e 61 6e 79 74 |st: api- dev.anyt
68 69 6e 67 2d 63 6f 6e 6e 65 63 74 65 64 2e 63 |hing-con nected.c
6f 6d 0d 0a 43 6f 6e 74 65 6e 74 2d 54 79 70 65 |om..Cont ent-Type
3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 6a 73 |: applic ation/js
6f 6e 0d 0a 41 75 74 68 6f 72 69 7a 61 74 69 6f |on..Auth orizatio
6e 3a 20 54 6f xx xx xx xx xx xx xx xx xx xx xx |n: Token xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx 0a 43 6f 6e 74 65 6e 74 2d 4c 65 6e 67 74 |x..Conte nt-Lengt
68 3a 20 33 31 33 0d 0a 0d 0a |h: 313.. ..
[00:00:29.972,351] <dbg> net_http.http_client_req: (backend thread): Sent 860 bytes
[00:00:31.013,122] <dbg> net_http.on_message_begin: (backend thread): -- HTTP POST response (headers) --
[00:00:31.013,275] <dbg> net_http.on_status: (backend thread): HTTP response status 201 Created
[00:00:31.048,553] <dbg> net_http.print_header_field: (backend thread): [4] Date
[00:00:31.049,438] <dbg> net_http.print_header_field: (backend thread): [29] Fri, 29 Jul 2022 11:53:41 GMT
[00:00:31.050,323] <dbg> net_http.print_header_field: (backend thread): [14] Content-Length
[00:00:31.052,886] <dbg> net_http.print_header_field: (backend thread): [1] 0
[00:00:31.054,992] <dbg> net_http.print_header_field: (backend thread): [10] Connection
[00:00:31.056,152] <dbg> net_http.print_header_field: (backend thread): [10] keep-alive
[00:00:31.056,945] <dbg> net_http.print_header_field: (backend thread): [4] vary
[00:00:31.057,769] <dbg> net_http.print_header_field: (backend thread): [22] Accept, Origin, Cookie
[00:00:31.058,593] <dbg> net_http.print_header_field: (backend thread): [5] allow
[00:00:31.059,356] <dbg> net_http.print_header_field: (backend thread): [24] GET, POST, HEAD, OPTIONS
[00:00:31.060,882] <dbg> net_http.print_header_field: (backend thread): [15] x-frame-options
[00:00:31.061,645] <dbg> net_http.print_header_field: (backend thread): [4] DENY
[00:00:31.062,408] <dbg> net_http.print_header_field: (backend thread): [22] x-content-type-options
[00:00:31.063,110] <dbg> net_http.print_header_field: (backend thread): [7] nosniff
[00:00:31.063,842] <dbg> net_http.print_header_field: (backend thread): [15] referrer-policy
[00:00:31.072,906] <dbg> net_http.print_header_field: (backend thread): [11] same-origin
[00:00:31.073,608] <dbg> net_http.print_header_field: (backend thread): [15] CF-Cache-Status
[00:00:31.074,279] <dbg> net_http.print_header_field: (backend thread): [7] DYNAMIC
[00:00:31.076,934] <dbg> net_http.print_header_field: (backend thread): [9] Expect-CT
[00:00:31.079,193] <dbg> net_http.print_header_field: (backend thread): [87] max-age=604800, report-uri="https://report-uri.cloudflare.com/cdn-cgi/beacon/expect-ct"
[00:00:31.082,092] <dbg> net_http.print_header_field: (backend thread): [9] Report-To
[00:00:31.082,763] <dbg> net_http.print_header_field: (backend thread): [127] {"endpoints":[{"url":"https:\/\/a.nel.cloudflare.com\/report\/v3?s=lYoOhYVW7Zj7cCwohId7kpf7JWAU%2F5yR7DX9ra95002bUIOe1Hdrm%2Bwb
[00:00:31.083,099] <dbg> net_http.print_header_field: (backend thread): [3] NEL
[00:00:31.083,404] <dbg> net_http.print_header_field: (backend thread): [60] <log_strdup alloc failed>
[00:00:31.083,587] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [25] <log_strdup alloc failed>
[00:00:31.083,770] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [24] <log_strdup alloc failed>
[00:00:31.083,892] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [6] <log_strdup alloc failed>
[00:00:31.084,014] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [10] <log_strdup alloc failed>
[00:00:31.084,136] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [6] <log_strdup alloc failed>
[00:00:31.084,259] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [20] <log_strdup alloc failed>
[00:00:31.084,350] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [7] <log_strdup alloc failed>
[00:00:31.084,472] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [43] <log_strdup alloc failed>
[00:00:31.084,472] <dbg> net_http.on_headers_complete: (<log_strdup alloc failed>): Headers complete
[00:00:31.084,503] <dbg> net_http.on_message_complete: (<log_strdup alloc failed>): -- HTTP POST response (complete) --
[00:00:31.084,625] <dbg> net_http.http_client_req: (<log_strdup alloc failed>): Received 887 bytes
[00:00:31.084,655] <dbg> backend.send_upload_data: Http client bytes sent: 0
[00:00:31.084,655] <inf> backend: Murt, POST response:
[00:00:31.084,655] <inf> backend: <log_strdup alloc failed>
[00:00:31.084,686] <dbg> main_firmware.main_backend_thread: Finished uploading data
[00:00:31.086,730] <inf> main_firmware: Current time_measured (GMT): <log_strdup alloc failed>
[00:00:31.089,752] <inf> backend: Sending 313 bytes of payload for bulk upload
[00:00:31.089,782] <dbg> backend.send_upload_data: Payload (313 bytes): <log_strdup alloc failed>
[00:00:31.089,965] <dbg> net_http: Data to send
50 4f 53 54 20 2f 6d 65 61 73 75 72 65 6d 65 6e |POST /me asuremen
74 73 2f 20 48 54 54 50 2f 31 2e 31 0d 0a 48 6f |ts/ HTTP /1.1..Ho
73 74 3a 20 61 70 69 2d 64 65 76 2e 61 6e 79 74 |st: api- dev.anyt
68 69 6e 67 2d 63 6f 6e 6e 65 63 74 65 64 2e 63 |hing-con nected.c
6f 6d 0d 0a 43 6f 6e 74 65 6e 74 2d 54 79 70 65 |om..Cont ent-Type
3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 6a 73 |: applic ation/js
6f 6e 0d 0a 41 75 74 68 6f 72 69 7a 61 74 69 6f |on..Auth orizatio
6e 3a 20 54 6f xx xx xx xx xx xx xx xx xx xx xx |n: Token xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx 0a 43 6f 6e 74 65 6e 74 2d 4c 65 6e 67 74 |x..Conte nt-Lengt
68 3a 20 33 31 33 0d 0a 0d 0a |h: 313.. ..
[00:00:31.091,827] <dbg> net_http.http_client_req: (backend thread): Sent 860 bytes
[00:00:32.543,182] <dbg> net_http.on_message_begin: (backend thread): -- HTTP POST response (headers) --
[00:00:32.543,365] <dbg> net_http.on_status: (backend thread): HTTP response status 201 Created
[00:00:32.577,026] <dbg> net_http.print_header_field: (backend thread): [4] Date
[00:00:32.577,880] <dbg> net_http.print_header_field: (backend thread): [29] Fri, 29 Jul 2022 11:53:43 GMT
[00:00:32.578,765] <dbg> net_http.print_header_field: (backend thread): [14] Content-Length
[00:00:32.579,589] <dbg> net_http.print_header_field: (backend thread): [1] 0
[00:00:32.582,275] <dbg> net_http.print_header_field: (backend thread): [10] Connection
[00:00:32.583,099] <dbg> net_http.print_header_field: (backend thread): [10] keep-alive
[00:00:32.583,892] <dbg> net_http.print_header_field: (backend thread): [4] vary
[00:00:32.584,716] <dbg> net_http.print_header_field: (backend thread): [22] Accept, Origin, Cookie
[00:00:32.587,219] <dbg> net_http.print_header_field: (backend thread): [5] allow
[00:00:32.588,012] <dbg> net_http.print_header_field: (backend thread): [24] GET, POST, HEAD, OPTIONS
[00:00:32.590,057] <dbg> net_http.print_header_field: (backend thread): [15] x-frame-options
[00:00:32.591,094] <dbg> net_http.print_header_field: (backend thread): [4] DENY
[00:00:32.591,857] <dbg> net_http.print_header_field: (backend thread): [22] x-content-type-options
[00:00:32.592,559] <dbg> net_http.print_header_field: (backend thread): [7] nosniff
[00:00:32.593,292] <dbg> net_http.print_header_field: (backend thread): [15] referrer-policy
[00:00:32.593,963] <dbg> net_http.print_header_field: (backend thread): [11] same-origin
[00:00:32.594,665] <dbg> net_http.print_header_field: (backend thread): [15] CF-Cache-Status
[00:00:32.595,336] <dbg> net_http.print_header_field: (backend thread): [7] DYNAMIC
[00:00:32.596,374] <dbg> net_http.print_header_field: (backend thread): [9] Expect-CT
[00:00:32.597,412] <dbg> net_http.print_header_field: (backend thread): [87] max-age=604800, report-uri="https://report-uri.cloudflare.com/cdn-cgi/beacon/expect-ct"
[00:00:32.598,022] <dbg> net_http.print_header_field: (backend thread): [9] Report-To
[00:00:32.598,632] <dbg> net_http.print_header_field: (backend thread): [127] {"endpoints":[{"url":"https:\/\/a.nel.cloudflare.com\/report\/v3?s=yzdjqQJPI5M9WDlwy3TMY7QIdIJGZjsoYUvS5HjVMLKMiAeYx9n3tN7rMPa7
[00:00:32.598,999] <dbg> net_http.print_header_field: (backend thread): [3] NEL
[00:00:32.599,304] <dbg> net_http.print_header_field: (backend thread): [60] <log_strdup alloc failed>
[00:00:32.599,487] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [25] <log_strdup alloc failed>
[00:00:32.599,670] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [24] <log_strdup alloc failed>
[00:00:32.599,792] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [6] <log_strdup alloc failed>
[00:00:32.599,914] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [10] <log_strdup alloc failed>
[00:00:32.600,036] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [6] <log_strdup alloc failed>
[00:00:32.600,158] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [20] <log_strdup alloc failed>
[00:00:32.600,250] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [7] <log_strdup alloc failed>
[00:00:32.600,921] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [43] <log_strdup alloc failed>
[00:00:32.600,921] <dbg> net_http.on_headers_complete: (<log_strdup alloc failed>): Headers complete
[00:00:32.600,952] <dbg> net_http.on_message_complete: (<log_strdup alloc failed>): -- HTTP POST response (complete) --
[00:00:32.608,886] <dbg> net_http.http_client_req: (<log_strdup alloc failed>): Received 877 bytes
[00:00:32.608,886] <dbg> backend.send_upload_data: Http client bytes sent: 0
[00:00:32.608,886] <inf> backend: Murt, POST response:
[00:00:32.608,917] <inf> backend: <log_strdup alloc failed>
[00:00:32.608,917] <dbg> main_firmware.main_backend_thread: Finished uploading data
<snip>
[00:03:23.984,985] <inf> main_firmware: Current time_measured (GMT): 2022-07-29 11:56:34.000000
[00:03:23.988,037] <inf> backend: Sending 313 bytes of payload for bulk upload
[00:03:23.988,067] <dbg> backend.send_upload_data: Payload (313 bytes): <log_strdup alloc failed>
[00:03:23.988,220] <dbg> net_http: Data to send
50 4f 53 54 20 2f 6d 65 61 73 75 72 65 6d 65 6e |POST /me asuremen
74 73 2f 20 48 54 54 50 2f 31 2e 31 0d 0a 48 6f |ts/ HTTP /1.1..Ho
73 74 3a 20 61 70 69 2d 64 65 76 2e 61 6e 79 74 |st: api- dev.anyt
68 69 6e 67 2d 63 6f 6e 6e 65 63 74 65 64 2e 63 |hing-con nected.c
6f 6d 0d 0a 43 6f 6e 74 65 6e 74 2d 54 79 70 65 |om..Cont ent-Type
3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 6a 73 |: applic ation/js
6f 6e 0d 0a 41 75 74 68 6f 72 69 7a 61 74 69 6f |on..Auth orizatio
6e 3a 20 54 6f xx xx xx xx xx xx xx xx xx xx xx |n: Token xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx 0a 43 6f 6e 74 65 6e 74 2d 4c 65 6e 67 74 |x..Conte nt-Lengt
68 3a 20 33 31 33 0d 0a 0d 0a |h: 313.. ..
[00:03:23.990,112] <dbg> net_http.http_client_req: (backend thread): Sent 860 bytes
[00:03:25.242,370] <dbg> net_http.on_message_begin: (backend thread): -- HTTP POST response (headers) --
[00:03:25.242,553] <dbg> net_http.on_status: (backend thread): HTTP response status 201 Created
[00:03:25.246,307] <dbg> net_http.print_header_field: (backend thread): [4] Date
[00:03:25.247,192] <dbg> net_http.print_header_field: (backend thread): [29] Fri, 29 Jul 2022 11:56:35 GMT
[00:03:25.248,077] <dbg> net_http.print_header_field: (backend thread): [14] Content-Length
[00:03:25.248,901] <dbg> net_http.print_header_field: (backend thread): [1] 0
[00:03:25.249,755] <dbg> net_http.print_header_field: (backend thread): [10] Connection
[00:03:25.250,579] <dbg> net_http.print_header_field: (backend thread): [10] keep-alive
[00:03:25.251,373] <dbg> net_http.print_header_field: (backend thread): [4] vary
[00:03:25.254,028] <dbg> net_http.print_header_field: (backend thread): [22] Accept, Origin, Cookie
[00:03:25.254,852] <dbg> net_http.print_header_field: (backend thread): [5] allow
[00:03:25.255,615] <dbg> net_http.print_header_field: (backend thread): [24] GET, POST, HEAD, OPTIONS
[00:03:25.256,408] <dbg> net_http.print_header_field: (backend thread): [15] x-frame-options
[00:03:25.257,141] <dbg> net_http.print_header_field: (backend thread): [4] DENY
[00:03:25.259,613] <dbg> net_http.print_header_field: (backend thread): [22] x-content-type-options
[00:03:25.260,345] <dbg> net_http.print_header_field: (backend thread): [7] nosniff
[00:03:25.261,444] <dbg> net_http.print_header_field: (backend thread): [15] referrer-policy
[00:03:25.263,336] <dbg> net_http.print_header_field: (backend thread): [11] same-origin
[00:03:25.264,038] <dbg> net_http.print_header_field: (backend thread): [15] CF-Cache-Status
[00:03:25.264,709] <dbg> net_http.print_header_field: (backend thread): [7] DYNAMIC
[00:03:25.265,380] <dbg> net_http.print_header_field: (backend thread): [9] Expect-CT
[00:03:25.266,052] <dbg> net_http.print_header_field: (backend thread): [87] max-age=604800, report-uri="https://report-uri.cloudflare.com/cdn-cgi/beacon/expect-ct"
[00:03:25.266,632] <dbg> net_http.print_header_field: (backend thread): [9] Report-To
[00:03:25.267,639] <dbg> net_http.print_header_field: (backend thread): [127] {"endpoints":[{"url":"https:\/\/a.nel.cloudflare.com\/report\/v3?s=6Kk4l1LclpdR%2BezhZuWPyMl91E0AFid9GkEyRD09e6j8LwF30IjvKlTD2T
[00:03:25.268,371] <dbg> net_http.print_header_field: (backend thread): [3] NEL
[00:03:25.268,676] <dbg> net_http.print_header_field: (backend thread): [60] <log_strdup alloc failed>
[00:03:25.268,890] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [25] <log_strdup alloc failed>
[00:03:25.269,042] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [24] <log_strdup alloc failed>
[00:03:25.269,165] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [6] <log_strdup alloc failed>
[00:03:25.269,317] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [10] <log_strdup alloc failed>
[00:03:25.269,439] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [6] <log_strdup alloc failed>
[00:03:25.269,531] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [20] <log_strdup alloc failed>
[00:03:25.269,653] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [7] <log_strdup alloc failed>
[00:03:25.269,744] <dbg> net_http.print_header_field: (<log_strdup alloc failed>): [43] <log_strdup alloc failed>
[00:03:25.269,744] <dbg> net_http.on_headers_complete: (<log_strdup alloc failed>): Headers complete
[00:03:25.269,775] <dbg> net_http.on_message_complete: (<log_strdup alloc failed>): -- HTTP POST response (complete) --
[00:03:25.269,897] <dbg> net_http.http_client_req: (<log_strdup alloc failed>): Received 881 bytes
[00:03:25.269,927] <dbg> backend.send_upload_data: Http client bytes sent: 0
[00:03:25.269,927] <inf> backend: Murt, POST response:
[00:03:25.269,958] <inf> backend: <log_strdup alloc failed>
[00:03:25.269,958] <dbg> main_firmware.main_backend_thread: Finished uploading data
[00:03:25.287,078] <inf> main_firmware: Current time_measured (GMT): <log_strdup alloc failed>
[00:03:25.290,100] <inf> backend: Sending 313 bytes of payload for bulk upload
[00:03:25.290,161] <dbg> backend.send_upload_data: Payload (313 bytes): <log_strdup alloc failed>
[00:03:25.290,313] <dbg> net_http: Data to send
50 4f 53 54 20 2f 6d 65 61 73 75 72 65 6d 65 6e |POST /me asuremen
74 73 2f 20 48 54 54 50 2f 31 2e 31 0d 0a 48 6f |ts/ HTTP /1.1..Ho
73 74 3a 20 61 70 69 2d 64 65 76 2e 61 6e 79 74 |st: api- dev.anyt
68 69 6e 67 2d 63 6f 6e 6e 65 63 74 65 64 2e 63 |hing-con nected.c
6f 6d 0d 0a 43 6f 6e 74 65 6e 74 2d 54 79 70 65 |om..Cont ent-Type
3a 20 61 70 70 6c 69 63 61 74 69 6f 6e 2f 6a 73 |: applic ation/js
6f 6e 0d 0a 41 75 74 68 6f 72 69 7a 61 74 69 6f |on..Auth orizatio
6e 3a 20 54 6f xx xx xx xx xx xx xx xx xx xx xx |n: Token xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx xx |xxxxxxxx xxxxxxxx
xx xx 0a 43 6f 6e 74 65 6e 74 2d 4c 65 6e 67 74 |x..Conte nt-Lengt
68 3a 20 33 31 33 0d 0a 0d 0a |h: 313.. ..
[00:03:25.292,266] <dbg> net_http.http_client_req: (backend thread): Sent 860 bytes
[00:03:35.292,419] <dbg> net_sock.z_impl_zsock_close: (sysworkq): close: ctx=0x2001970c, fd=1
[00:03:50.469,299] <dbg> lte_lc.at_handler: +CSCON notification
[00:03:50.469,451] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode idle
[00:03:52.859,954] <dbg> lte_lc.at_handler: +CSCON notification
[00:03:52.860,107] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode connected
[00:04:03.221,069] <dbg> lte_lc.at_handler: +CSCON notification
[00:04:03.221,221] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode idle
[00:04:05.661,529] <dbg> lte_lc.at_handler: +CSCON notification
[00:04:05.661,682] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode connected
[00:04:15.919,281] <dbg> lte_lc.at_handler: +CSCON notification
[00:04:15.919,433] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode idle
[00:04:28.066,467] <dbg> lte_lc.at_handler: +CSCON notification
[00:04:28.066,619] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode connected
[00:04:38.335,205] <dbg> lte_lc.at_handler: +CSCON notification
[00:04:38.335,357] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode idle
[00:05:10.628,326] <dbg> lte_lc.at_handler: +CSCON notification
[00:05:10.628,479] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode connected
[00:05:21.048,400] <dbg> lte_lc.at_handler: +CSCON notification
[00:05:21.048,553] <dbg> lte_lc.at_handler: +CSCON notification: RRC mode idle
RTT connection on TCP port 19021 ended. Waiting for next connection...