Trying to get Azure IoT Hub sample working with MQTT over WebSockets

Question

Greetings! Our device will use WiFi network to send data to the Azure's IoT Hub, where it will be further forwarded to our cloud. As the used network won't be controlled by us, but by a third party, we can not assume that the usual MQTT ports will be opened. So, thus we must resort to using the MQTT over the Websockets. As a minimal working setup I am trying to get nrf/samples/net/azure_iot_hub sample running, but using the Websockets. The default setup which uses the MQTT works, but I have troubles getting the Websockets running. My suspicion is that something related to the MBedTLS or certificates fails and thus the connection is closed, however I lack additional knowledge to further pinpoint the problem. I am using nRF7002DK, running NCS v.2.7.0. I will first describe the steps I took in my debugging journey and then end with the current Mbedtls log output that I am trying to understand. Support for MQTT over WebSockets Although the IoT Hub supports MQTT over Websockets (see first note in the linked article) and Zephyr support the WebSockets, the mqtt_helper.c library in the NCS doesn't provide support for it. So to get the MQTT over WebSockets working I wrote a small patch for it. I took the inspiration from the Zephyr's mqtt_publisher example. diff --git a/subsys/net/lib/mqtt_helper/mqtt_helper.c b/subsys/net/lib/mqtt_helper/mqtt_helper.c index e9755309b0..001119ddaf 100644 --- a/subsys/net/lib/mqtt_helper/mqtt_helper.c +++ b/subsys/net/lib/mqtt_helper/mqtt_helper.c @@ -36,6 +36,15 @@ BUILD_ASSERT((CONFIG_MQTT_HELPER_SEC_TAG != -1), "Security tag must be configure #define MQTT_HELPER_STATIC static #endif +#if defined(CONFIG_MQTT_LIB_WEBSOCKET) +/* Making RX buffer large enough that the full IPv6 packet can fit into it */ +#define MQTT_LIB_WEBSOCKET_RECV_BUF_LEN 1280 + +/* Websocket needs temporary buffer to store partial packets */ +static uint8_t temp_ws_rx_buf[MQTT_LIB_WEBSOCKET_RECV_BUF_LEN]; +#endif + + MQTT_HELPER_STATIC struct mqtt_client mqtt_client; static struct sockaddr_storage broker; static char rx_buffer[CONFIG_MQTT_HELPER_RX_TX_BUFFER_SIZE]; @@ -494,10 +503,28 @@ static int client_connect(struct mqtt_helper_conn_params *conn_params) #endif #if defined(CONFIG_MQTT_LIB_TLS) - mqtt_client.transport.type = MQTT_TRANSPORT_SECURE; +#if defined(CONFIG_MQTT_LIB_WEBSOCKET) + mqtt_client.transport.type = MQTT_TRANSPORT_SECURE_WEBSOCKET; +#else + mqtt_client.transport.type = MQTT_TRANSPORT_SECURE; +#endif /* CONFIG_MQTT_LIB_WEBSOCKET */ #else - mqtt_client.transport.type = MQTT_TRANSPORT_NON_SECURE; +#if defined(CONFIG_MQTT_LIB_WEBSOCKET) + mqtt_client.transport.type = MQTT_TRANSPORT_NON_SECURE_WEBSOCKET; +#else + mqtt_client.transport.type = MQTT_TRANSPORT_NON_SECURE; +#endif /* CONFIG_MQTT_LIB_WEBSOCKET */ + #endif /* CONFIG_MQTT_LIB_TLS */ + +#if defined(CONFIG_MQTT_LIB_WEBSOCKET) + mqtt_client.transport.websocket.config.host = conn_params->hostname.ptr; + mqtt_client.transport.websocket.config.url = "/mqtt"; + mqtt_client.transport.websocket.config.tmp_buf = temp_ws_rx_buf; + mqtt_client.transport.websocket.config.tmp_buf_len = sizeof(temp_ws_rx_buf); + mqtt_client.transport.websocket.timeout = 20 * MSEC_PER_SEC; +#endif + mqtt_client.user_name = conn_params->user_name.size > 0 ? &user_name : NULL; mqtt_client.password = conn_params->password.size > 0 ? &password : NULL; To confirm that above patch was functioning as intended I used the nrf/samples/net/mqtt sample. I configured it connect to the Mosquitto's 8081 port (MQTT over WebSockets, encrypted, unauthenticated). As a different certificate for WebSockets was needed (compared to the provided one for the 8883 port - MQTT, encrypted, unauthenticated) I had to adjust some mbedtls-related KConfig options: CONFIG_MBEDTLS_MPI_MAX_SIZE=512 CONFIG_POSIX_MAX_FDS=32 With above setup I could successfully connect to the two public brokers: test.mosquitto.org and broker.emqx.io. Debugging azure_iot_hub sample As a starting point I had a working connection with the IoT hub, using the default setup. I am using the DPS to provision my device. I then added the below KConfigs to my prj.conf to enable the Websockets. I accumulated the configs as I was debugging. Keep in mind that if you want to have functional mbedtls logs on NCS v2.7.0 you need to apply the patch that fixes them . CONFIG_MQTT_LIB_WEBSOCKET=y CONFIG_WEBSOCKET_CLIENT=y CONFIG_MQTT_HELPER_PORT=443 # CONFIG_MQTT_HELPER_LOG_LEVEL_DBG=y # CONFIG_MQTT_LOG_LEVEL_DBG=y CONFIG_NET_LOG=y CONFIG_NET_WEBSOCKET_LOG_LEVEL_DBG=y CONFIG_NET_HTTP_LOG_LEVEL_DBG=y # Enable Mbed TLS logs CONFIG_MBEDTLS_DEBUG=y CONFIG_MBEDTLS_DEBUG_C=y CONFIG_MBEDTLS_DEBUG_LEVEL=3 CONFIG_NET_BUF_RX_COUNT=72 CONFIG_NET_BUF_TX_COUNT=72 CONFIG_MBEDTLS_SSL_MAX_CONTENT_LEN=4096 CONFIG_MBEDTLS_MPI_MAX_SIZE=512 CONFIG_POSIX_MAX_FDS=32 CONFIG_MBEDTLS_SSL_RENEGOTIATION=y CONFIG_MBEDTLS_DEBUG_EXTRACT_BASENAME_AT_RUNTIME=y CONFIG_MBEDTLS_SSL_SERVER_NAME_INDICATION=y The symbol that really made a difference was the CONFIG_MBEDTLS_SSL_RENEGOTIATION, as there was a clear warning when this wasn't enabled and then connection was closed by the server (as indicated by the the mbedtls_ssl_fetch_input() returned -29312 (-0x7280) line. Below is the captured log without the mentioned flag: *** Booting My Application v2.1.0-dev-daf2946a0f07 *** *** Using nRF Connect SDK v2.7.0-5cb85570ca43 *** *** Using Zephyr OS v3.6.99-100befc70c74 *** 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: Image index: 0, Swap type: none I: Bootloader chainload address offset: 0x10000 I: Jumping to the first image slot *** Booting nRF Connect SDK v2.7.0-5cb85570ca43 *** *** Using Zephyr OS v3.6.99-100befc70c74 *** [00:00:00.247,131] azure_iot_hub_sample: Azure IoT Hub sample started [00:00:00.256,622] azure_iot_hub_sample: Bringing network interface up and connecting to the network [00:00:01.832,275] wifi_mgmt_ext: Connection requested [00:00:01.842,651] azure_iot_hub_sample: Device ID: nrf7002dk_010 [00:00:05.912,261] net_dhcpv4: Received: 192.168.76.247 [00:00:05.920,867] azure_iot_hub_sample: Network connectivity established and IP address assigned [00:00:05.933,532] azure_iot_hub_sample: Connected to network [00:00:05.942,321] azure_iot_hub_sample: Starting DPS [00:00:05.952,209] azure_iot_hub_sample: DPS registration status: AZURE_IOT_HUB_DPS_REG_STATUS_NOT_STARTED [00:00:05.965,026] azure_iot_hub_sample: Already assigned to an IoT hub, skipping DPS [00:00:05.975,921] azure_iot_hub_sample: Device ID "nrf7002dk_010" assigned to IoT hub with hostname "bbc-grp-qst-sbx-iothub001.azure-devices.net" [00:00:05.993,469] azure_fota: Current firmware version: 0.0.0-dev [00:00:06.002,685] azure_iot_hub_sample: Azure IoT Hub library initialized [00:00:06.012,603] azure_iot_hub_sample: AZURE_IOT_HUB_EVT_CONNECTING [00:00:06.056,701] net_dns_resolve: DNS recv error (-103) [00:00:06.583,892] net_dns_resolve: DNS recv error (-4) [00:00:06.693,634] mbedtls: ssl_tls.c:3914: => handshake [00:00:06.702,270] mbedtls: ssl_msg.c:2358: => flush output [00:00:06.711,181] mbedtls: ssl_msg.c:2367: <= flush output [00:00:06.720,123] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_HELLO_REQUEST [00:00:06.731,109] mbedtls: ssl_msg.c:2358: => flush output [00:00:06.739,990] mbedtls: ssl_msg.c:2367: <= flush output [00:00:06.748,931] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_CLIENT_HELLO [00:00:06.759,826] mbedtls: ssl_client.c:0924: => write client hello [00:00:06.769,744] mbedtls: ssl_client.c:0486: dumping 'client hello, random bytes' (32 bytes) [00:00:06.781,646] mbedtls: ssl_client.c:0486: 0000: b9 35 34 cf 60 1a 89 93 07 f9 be 43 26 7a 5b 99 .54.`......C&z[. [00:00:06.795,745] mbedtls: ssl_client.c:0486: 0010: 91 93 38 61 43 ec 7a 57 7d 59 fc 73 18 ef 7d e6 ..8aC.zW}Y.s..}. [00:00:06.809,570] mbedtls: ssl_client.c:0511: dumping 'session id' (0 bytes) [00:00:06.819,854] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: cca8, TLS-ECDHE-RSA-WITH-CHACHA20-POLY1305-SHA256 [00:00:06.834,472] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: cca9, TLS-ECDHE-ECDSA-WITH-CHACHA20-POLY1305-SHA256 [00:00:06.849,243] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: ccaa, TLS-DHE-RSA-WITH-CHACHA20-POLY1305-SHA256 [00:00:06.863,647] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c02c, TLS-ECDHE-ECDSA-WITH-AES-256-GCM-SHA384 [00:00:06.877,899] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c030, TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384 [00:00:06.891,967] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 009f, TLS-DHE-RSA-WITH-AES-256-GCM-SHA384 [00:00:06.905,853] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0ad, TLS-ECDHE-ECDSA-WITH-AES-256-CCM [00:00:06.919,494] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c09f, TLS-DHE-RSA-WITH-AES-256-CCM [00:00:06.932,800] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c024, TLS-ECDHE-ECDSA-WITH-AES-256-CBC-SHA384 [00:00:06.947,052] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c028, TLS-ECDHE-RSA-WITH-AES-256-CBC-SHA384 [00:00:06.961,120] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 006b, TLS-DHE-RSA-WITH-AES-256-CBC-SHA256 [00:00:06.975,006] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c00a, TLS-ECDHE-ECDSA-WITH-AES-256-CBC-SHA [00:00:06.989,013] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c014, TLS-ECDHE-RSA-WITH-AES-256-CBC-SHA [00:00:07.002,807] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 0039, TLS-DHE-RSA-WITH-AES-256-CBC-SHA [00:00:07.016,448] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0af, TLS-ECDHE-ECDSA-WITH-AES-256-CCM-8 [00:00:07.030,273] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0a3, TLS-DHE-RSA-WITH-AES-256-CCM-8 [00:00:07.043,731] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c02b, TLS-ECDHE-ECDSA-WITH-AES-128-GCM-SHA256 [00:00:07.057,983] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c02f, TLS-ECDHE-RSA-WITH-AES-128-GCM-SHA256 [00:00:07.072,052] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 009e, TLS-DHE-RSA-WITH-AES-128-GCM-SHA256 [00:00:07.085,968] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0ac, TLS-ECDHE-ECDSA-WITH-AES-128-CCM [00:00:07.099,578] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c09e, TLS-DHE-RSA-WITH-AES-128-CCM [00:00:07.112,884] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c023, TLS-ECDHE-ECDSA-WITH-AES-128-CBC-SHA256 [00:00:07.127,136] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c027, TLS-ECDHE-RSA-WITH-AES-128-CBC-SHA256 [00:00:07.141,204] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 0067, TLS-DHE-RSA-WITH-AES-128-CBC-SHA256 [00:00:07.155,090] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c009, TLS-ECDHE-ECDSA-WITH-AES-128-CBC-SHA [00:00:07.169,067] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c013, TLS-ECDHE-RSA-WITH-AES-128-CBC-SHA [00:00:07.182,891] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 0033, TLS-DHE-RSA-WITH-AES-128-CBC-SHA [00:00:07.196,533] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0ae, TLS-ECDHE-ECDSA-WITH-AES-128-CCM-8 [00:00:07.210,327] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0a2, TLS-DHE-RSA-WITH-AES-128-CCM-8 [00:00:07.223,937] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0ff, TLS-ECJPAKE-WITH-AES-128-CCM-8 [00:00:07.237,426] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 009d, TLS-RSA-WITH-AES-256-GCM-SHA384 [00:00:07.250,976] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c09d, TLS-RSA-WITH-AES-256-CCM [00:00:07.263,916] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 003d, TLS-RSA-WITH-AES-256-CBC-SHA256 [00:00:07.277,465] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 0035, TLS-RSA-WITH-AES-256-CBC-SHA [00:00:07.290,771] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c032, TLS-ECDH-RSA-WITH-AES-256-GCM-SHA384 [00:00:07.304,748] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c02a, TLS-ECDH-RSA-WITH-AES-256-CBC-SHA384 [00:00:07.318,756] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c00f, TLS-ECDH-RSA-WITH-AES-256-CBC-SHA [00:00:07.332,489] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c02e, TLS-ECDH-ECDSA-WITH-AES-256-GCM-SHA384 [00:00:07.346,649] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c026, TLS-ECDH-ECDSA-WITH-AES-256-CBC-SHA384 [00:00:07.360,839] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c005, TLS-ECDH-ECDSA-WITH-AES-256-CBC-SHA [00:00:07.374,725] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0a1, TLS-RSA-WITH-AES-256-CCM-8 [00:00:07.387,847] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 009c, TLS-RSA-WITH-AES-128-GCM-SHA256 [00:00:07.401,397] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c09c, TLS-RSA-WITH-AES-128-CCM [00:00:07.414,337] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 003c, TLS-RSA-WITH-AES-128-CBC-SHA256 [00:00:07.427,886] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: 002f, TLS-RSA-WITH-AES-128-CBC-SHA [00:00:07.441,192] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c031, TLS-ECDH-RSA-WITH-AES-128-GCM-SHA256 [00:00:07.455,200] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c029, TLS-ECDH-RSA-WITH-AES-128-CBC-SHA256 [00:00:07.469,177] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c00e, TLS-ECDH-RSA-WITH-AES-128-CBC-SHA [00:00:07.482,910] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c02d, TLS-ECDH-ECDSA-WITH-AES-128-GCM-SHA256 [00:00:07.497,100] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c025, TLS-ECDH-ECDSA-WITH-AES-128-CBC-SHA256 [00:00:07.511,260] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c004, TLS-ECDH-ECDSA-WITH-AES-128-CBC-SHA [00:00:07.525,115] mbedtls: ssl_client.c:0369: client hello, add ciphersuite: c0a0, TLS-RSA-WITH-AES-128-CCM-8 [00:00:07.538,360] mbedtls: ssl_client.c:0387: adding EMPTY_RENEGOTIATION_INFO_SCSV [00:00:07.549,102] mbedtls: ssl_client.c:0396: client hello, got 53 cipher suites [00:00:07.559,722] mbedtls: ssl_client.c:0040: client hello, adding server name extension: bbc-grp-qst-sbx-iothub001.azure-devices.net [00:00:07.574,890] mbedtls: ssl_client.c:0230: client hello, adding supported_groups extension [00:00:07.586,578] mbedtls: ssl_client.c:0249: got supported group(0017) [00:00:07.596,343] mbedtls: ssl_client.c:0281: NamedGroup: secp256r1 ( 17 ) [00:00:07.606,414] mbedtls: ssl_client.c:0301: dumping 'Supported groups extension' (4 bytes) [00:00:07.618,194] mbedtls: ssl_client.c:0301: 0000: 00 02 00 17 .... [00:00:07.631,011] mbedtls: ssl_tls.c:9286: adding signature_algorithms extension [00:00:07.641,601] mbedtls: ssl_tls.c:9306: got signature scheme [603] ecdsa_secp521r1_sha512 [00:00:07.653,228] mbedtls: ssl_tls.c:9315: sent signature scheme [603] ecdsa_secp521r1_sha512 [00:00:07.664,916] mbedtls: ssl_tls.c:9306: got signature scheme [601] rsa_pkcs1_sha512 [00:00:07.676,025] mbedtls: ssl_tls.c:9315: sent signature scheme [601] rsa_pkcs1_sha512 [00:00:07.687,225] mbedtls: ssl_tls.c:9306: got signature scheme [503] ecdsa_secp384r1_sha384 [00:00:07.698,852] mbedtls: ssl_tls.c:9315: sent signature scheme [503] ecdsa_secp384r1_sha384 [00:00:07.710,540] mbedtls: ssl_tls.c:9306: got signature scheme [501] rsa_pkcs1_sha384 [00:00:07.721,649] mbedtls: ssl_tls.c:9315: sent signature scheme [501] rsa_pkcs1_sha384 [00:00:07.732,818] mbedtls: ssl_tls.c:9306: got signature scheme [403] ecdsa_secp256r1_sha256 [00:00:07.744,445] mbedtls: ssl_tls.c:9315: sent signature scheme [403] ecdsa_secp256r1_sha256 [00:00:07.756,164] mbedtls: ssl_tls.c:9306: got signature scheme [401] rsa_pkcs1_sha256 [00:00:07.767,272] mbedtls: ssl_tls.c:9315: sent signature scheme [401] rsa_pkcs1_sha256 [00:00:07.778,472] mbedtls: ssl_tls12_client.c:0105: client hello, adding supported_point_formats extension [00:00:07.791,290] mbedtls: ssl_tls12_client.c:0150: client hello, adding ecjpake_kkpp extension [00:00:07.803,131] mbedtls: ssl_tls12_client.c:0165: generating new ecjpake parameters [00:00:07.971,618] mbedtls: ssl_tls12_client.c:0272: client hello, adding max_fragment_length extension [00:00:07.984,100] mbedtls: ssl_tls12_client.c:0338: client hello, adding extended_master_secret extension [00:00:07.996,856] mbedtls: ssl_client.c:0688: client hello, total extension length: 427 [00:00:08.008,026] mbedtls: ssl_client.c:0690: dumping 'client hello extensions' (427 bytes) [00:00:08.019,775] mbedtls: ssl_client.c:0690: 0000: 01 ab 00 00 00 30 00 2e 00 00 2b 62 62 63 2d 67 .....0....+bbc-g [00:00:08.033,874] mbedtls: ssl_client.c:0690: 0010: 72 70 2d 71 73 74 2d 73 62 78 2d 69 6f 74 68 75 rp-qst-sbx-iothu [00:00:08.047,943] mbedtls: ssl_client.c:0690: 0020: 62 30 30 31 2e 61 7a 75 72 65 2d 64 65 76 69 63 b001.azure-devic [00:00:08.062,011] mbedtls: ssl_client.c:0690: 0030: 65 73 2e 6e 65 74 00 0a 00 04 00 02 00 17 00 0d es.net.......... [00:00:08.076,080] mbedtls: ssl_client.c:0690: 0040: 00 0e 00 0c 06 03 06 01 05 03 05 01 04 03 04 01 ................ [00:00:08.090,148] mbedtls: ssl_client.c:0690: 0050: 00 0b 00 02 01 00 01 00 01 4a 41 04 92 bb 5f d7 .........JA..._. [00:00:08.104,248] mbedtls: ssl_client.c:0690: 0060: 7b 6a a2 50 0d 51 97 51 d3 b2 ac eb 59 3c dd 21 {j.P.Q.Q....Y<.! [00:00:08.118,316] mbedtls: ssl_client.c:0690: 0070: c1 0e 0e a1 df 60 e3 c2 1e 5c 64 66 a1 3b 71 af .....`...\df.;q. [00:00:08.132,415] mbedtls: ssl_client.c:0690: 0080: 8a a2 d4 5f 16 5b eb f1 ba 49 1d 76 3b 5d 4a 78 ..._.[...I.v;]Jx [00:00:08.146,484] mbedtls: ssl_client.c:0690: 0090: 67 b1 fd 20 27 d9 98 1c 97 16 c7 09 41 04 cd d5 g.. '.......A... [00:00:08.160,583] mbedtls: ssl_client.c:0690: 00a0: 09 04 f6 b6 3e 73 be bb 4a c2 bb 5d ea 2c 43 e1 ....>s..J..].,C. [00:00:08.174,652] mbedtls: ssl_client.c:0690: 00b0: 90 8c b3 1d 70 eb 9b f9 f8 66 27 f5 c4 1e 86 fb ....p....f'..... [00:00:08.188,751] mbedtls: ssl_client.c:0690: 00c0: 8c 3e 83 ba 10 50 8d 7f 5d 8e c4 b9 a9 b8 72 a6 .>...P..].....r. [00:00:08.202,819] mbedtls: ssl_client.c:0690: 00d0: c1 ac 3d 38 7c a7 73 2b 0d b0 60 09 ed 4f 20 f1 ..=8|.s+..`..O . [00:00:08.216,918] mbedtls: ssl_client.c:0690: 00e0: 00 bd 36 c1 c0 98 68 46 be 4a a4 5a 65 3b a6 d5 ..6...hF.J.Ze;.. [00:00:08.230,987] mbedtls: ssl_client.c:0690: 00f0: 55 8a 06 9c 57 d4 9f 5e 8e 01 52 13 7f 82 27 41 U...W..^..R...'A [00:00:08.245,056] mbedtls: ssl_client.c:0690: 0100: 04 21 e2 8a d4 a0 2a 32 9e 2a 3a 47 ff 61 06 68 .!....*2.*:G.a.h [00:00:08.259,155] mbedtls: ssl_client.c:0690: 0110: 64 5d 9c 8a 91 38 33 38 74 ff b4 4c 62 d3 1e 24 d]...838t..Lb..$ [00:00:08.273,223] mbedtls: ssl_client.c:0690: 0120: b6 e8 81 78 bf a1 07 d3 38 c2 da 4c 3f bb aa ab ...x....8..L?... [00:00:08.287,322] mbedtls: ssl_client.c:0690: 0130: 77 5b 01 a9 56 a1 dd ff f3 aa 0b a6 d2 29 4e ff w[..V........)N. [00:00:08.301,391] mbedtls: ssl_client.c:0690: 0140: 6b 41 04 e4 c8 db 4a f8 99 be 7c 1b e9 37 f0 66 kA....J...|..7.f [00:00:08.315,460] mbedtls: ssl_client.c:0690: 0150: de 04 11 da 2c b2 e5 b9 42 9f ec ea 19 61 d2 62 ....,...B....a.b [00:00:08.329,559] mbedtls: ssl_client.c:0690: 0160: 46 d7 42 18 fb c4 d0 a2 99 4e 8e 6a 02 28 ed 4b F.B......N.j.(.K [00:00:08.343,627] mbedtls: ssl_client.c:0690: 0170: 8b f6 9b a3 20 15 b7 00 1a 65 00 68 74 2a 67 ed .... ....e.ht*g. [00:00:08.357,696] mbedtls: ssl_client.c:0690: 0180: 94 fe 79 20 82 82 3d 29 b8 59 e6 41 56 2c f1 e3 ..y ..=).Y.AV,.. [00:00:08.371,795] mbedtls: ssl_client.c:0690: 0190: 26 3b d8 e6 e3 81 fd fe 25 51 2a a8 0b 99 d7 32 &;......%Q*....2 [00:00:08.385,833] mbedtls: ssl_client.c:0690: 01a0: 52 00 2f a1 00 01 00 01 04 00 17 R./........ [00:00:08.401,184] mbedtls: ssl_msg.c:2788: => write handshake message [00:00:08.411,041] mbedtls: ssl_msg.c:2948: => write record [00:00:08.420,013] mbedtls: ssl_msg.c:3032: output record: msgtype = 22, version = [3:3], msglen = 578 [00:00:08.432,373] mbedtls: ssl_msg.c:3085: <= write record [00:00:08.441,284] mbedtls: ssl_msg.c:2909: <= write handshake message [00:00:08.451,141] mbedtls: ssl_client.c:1012: <= write client hello [00:00:08.460,845] mbedtls: ssl_msg.c:2358: => flush output [00:00:08.469,787] mbedtls: ssl_msg.c:2372: message length: 583, out_left: 583 [00:00:08.481,414] mbedtls: ssl_msg.c:2379: ssl->f_send() returned 583 (-0xfffffdb9) [00:00:08.492,492] mbedtls: ssl_msg.c:2406: <= flush output [00:00:08.501,434] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_HELLO [00:00:08.512,329] mbedtls: ssl_tls12_client.c:1194: => parse server hello [00:00:08.522,521] mbedtls: ssl_msg.c:4122: => read record [00:00:08.531,341] mbedtls: ssl_msg.c:2160: => fetch input [00:00:08.540,191] mbedtls: ssl_msg.c:2300: in_left: 0, nb_want: 5 [00:00:08.549,743] mbedtls: ssl_msg.c:2320: in_left: 0, nb_want: 5 [00:00:08.559,265] mbedtls: ssl_tls.c:3925: <= handshake [00:00:08.628,173] mbedtls: ssl_tls.c:3914: => handshake [00:00:08.636,840] mbedtls: ssl_msg.c:2358: => flush output [00:00:08.645,721] mbedtls: ssl_msg.c:2367: <= flush output [00:00:08.654,663] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_HELLO [00:00:08.665,557] mbedtls: ssl_tls12_client.c:1194: => parse server hello [00:00:08.675,781] mbedtls: ssl_msg.c:4122: => read record [00:00:08.684,570] mbedtls: ssl_msg.c:2160: => fetch input [00:00:08.693,420] mbedtls: ssl_msg.c:2300: in_left: 0, nb_want: 5 [00:00:08.703,002] mbedtls: ssl_msg.c:2320: in_left: 0, nb_want: 5 [00:00:08.712,554] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 5 (-0xfffffffb) [00:00:08.724,304] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:08.733,184] mbedtls: ssl_msg.c:3869: input record: msgtype = 22, version = [0x303], msglen = 98 [00:00:08.745,544] mbedtls: ssl_msg.c:2160: => fetch input [00:00:08.754,394] mbedtls: ssl_msg.c:2300: in_left: 5, nb_want: 103 [00:00:08.764,160] mbedtls: ssl_msg.c:2320: in_left: 5, nb_want: 103 [00:00:08.773,895] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 98 (-0xffffff9e) [00:00:08.785,736] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:08.794,616] mbedtls: ssl_msg.c:3242: handshake message: msglen = 98, type = 2, hslen = 98 [00:00:08.806,884] mbedtls: ssl_msg.c:4194: <= read record [00:00:08.815,765] mbedtls: ssl_tls12_client.c:1268: dumping 'server hello, version' (2 bytes) [00:00:08.827,606] mbedtls: ssl_tls12_client.c:1268: 0000: 03 03 .. [00:00:08.840,759] mbedtls: ssl_tls12_client.c:1288: server hello, current time: 975624526 [00:00:08.852,142] mbedtls: ssl_tls12_client.c:1298: dumping 'server hello, random bytes' (32 bytes) [00:00:08.864,593] mbedtls: ssl_tls12_client.c:1298: 0000: 3a 26 d9 4e c5 2c 5c cd c2 74 b6 f0 7a 0d 2b f1 :&.N.,\..t..z.+. [00:00:08.879,180] mbedtls: ssl_tls12_client.c:1298: 0010: cf 48 ec 21 17 7b b1 20 f0 6c a5 f0 2b 43 91 8d .H.!.{. .l..+C.. [00:00:08.893,554] mbedtls: ssl_tls12_client.c:1361: server hello, session id len.: 32 [00:00:08.904,571] mbedtls: ssl_tls12_client.c:1362: dumping 'server hello, session id' (32 bytes) [00:00:08.916,839] mbedtls: ssl_tls12_client.c:1362: 0000: b3 97 28 83 fe c3 92 a7 70 4f 09 2d 4b 7a 40 94 ..(.....pO.-Kz@. [00:00:08.931,427] mbedtls: ssl_tls12_client.c:1362: 0010: 98 8d c8 b2 14 48 22 9f d0 42 5a c8 77 44 8e e8 .....H"..BZ.wD.. [00:00:08.945,800] mbedtls: ssl_tls12_client.c:1386: no session has been resumed [00:00:08.956,268] mbedtls: ssl_tls12_client.c:1389: server hello, chosen ciphersuite: c02f [00:00:08.967,712] mbedtls: ssl_tls12_client.c:1390: server hello, compress alg.: 0 [00:00:08.978,485] mbedtls: ssl_tls12_client.c:1425: server hello, chosen ciphersuite: TLS-ECDHE-RSA-WITH-AES-128-GCM-SHA256 [00:00:08.992,767] mbedtls: ssl_tls12_client.c:1446: server hello, total extension length: 22 [00:00:09.004,638] mbedtls: ssl_tls12_client.c:1466: found renegotiation extension [00:00:09.015,289] mbedtls: ssl_tls12_client.c:1480: found max_fragment_length extension [00:00:09.026,489] mbedtls: ssl_tls12_client.c:1545: found supported_point_formats extension [00:00:09.037,994] mbedtls: ssl_tls12_client.c:1518: found extended_master_secret extension [00:00:09.049,407] mbedtls: ssl_tls12_client.c:1662: <= parse server hello [00:00:09.059,600] mbedtls: ssl_msg.c:2358: => flush output [00:00:09.068,511] mbedtls: ssl_msg.c:2367: <= flush output [00:00:09.077,453] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_CERTIFICATE [00:00:09.088,867] mbedtls: ssl_tls.c:7516: => parse certificate [00:00:09.098,205] mbedtls: ssl_msg.c:4122: => read record [00:00:09.107,025] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.115,844] mbedtls: ssl_msg.c:2300: in_left: 0, nb_want: 5 [00:00:09.125,427] mbedtls: ssl_msg.c:2320: in_left: 0, nb_want: 5 [00:00:09.134,979] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 5 (-0xfffffffb) [00:00:09.146,728] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:09.155,609] mbedtls: ssl_msg.c:3869: input record: msgtype = 22, version = [0x303], msglen = 3066 [00:00:09.168,151] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.177,001] mbedtls: ssl_msg.c:2300: in_left: 5, nb_want: 3071 [00:00:09.187,103] mbedtls: ssl_msg.c:2320: in_left: 5, nb_want: 3071 [00:00:09.196,929] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 1344 (-0xfffffac0) [00:00:09.208,984] mbedtls: ssl_msg.c:2320: in_left: 1349, nb_want: 3071 [00:00:09.219,024] mbedtls: ssl_tls.c:3925: <= handshake [00:00:09.232,543] mbedtls: ssl_tls.c:3914: => handshake [00:00:09.241,241] mbedtls: ssl_msg.c:2358: => flush output [00:00:09.250,152] mbedtls: ssl_msg.c:2367: <= flush output [00:00:09.259,094] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_CERTIFICATE [00:00:09.270,507] mbedtls: ssl_tls.c:7516: => parse certificate [00:00:09.279,846] mbedtls: ssl_msg.c:4122: => read record [00:00:09.288,665] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.297,515] mbedtls: ssl_msg.c:2300: in_left: 1349, nb_want: 5 [00:00:09.307,281] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:09.316,162] mbedtls: ssl_msg.c:3869: input record: msgtype = 22, version = [0x303], msglen = 3066 [00:00:09.328,704] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.337,554] mbedtls: ssl_msg.c:2300: in_left: 1349, nb_want: 3071 [00:00:09.347,930] mbedtls: ssl_msg.c:2320: in_left: 1349, nb_want: 3071 [00:00:09.358,032] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 1452 (-0xfffffa54) [00:00:09.370,086] mbedtls: ssl_msg.c:2320: in_left: 2801, nb_want: 3071 [00:00:09.380,126] mbedtls: ssl_tls.c:3925: <= handshake [00:00:09.409,210] mbedtls: ssl_tls.c:3914: => handshake [00:00:09.417,877] mbedtls: ssl_msg.c:2358: => flush output [00:00:09.426,788] mbedtls: ssl_msg.c:2367: <= flush output [00:00:09.435,729] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_CERTIFICATE [00:00:09.447,143] mbedtls: ssl_tls.c:7516: => parse certificate [00:00:09.456,481] mbedtls: ssl_msg.c:4122: => read record [00:00:09.465,301] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.474,151] mbedtls: ssl_msg.c:2300: in_left: 2801, nb_want: 5 [00:00:09.483,917] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:09.492,797] mbedtls: ssl_msg.c:3869: input record: msgtype = 22, version = [0x303], msglen = 3066 [00:00:09.505,310] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.514,160] mbedtls: ssl_msg.c:2300: in_left: 2801, nb_want: 3071 [00:00:09.524,322] mbedtls: ssl_msg.c:2320: in_left: 2801, nb_want: 3071 [00:00:09.534,393] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 270 (-0xfffffef2) [00:00:09.546,325] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:09.555,206] mbedtls: ssl_msg.c:3242: handshake message: msglen = 3066, type = 11, hslen = 3066 [00:00:09.569,702] mbedtls: ssl_msg.c:4194: <= read record [00:00:09.580,505] mbedtls: ssl_tls.c:7283: Use configuration-specific verification callback [00:00:09.692,687] mbedtls: ssl_tls.c:7443: Certificate verification flags clear [00:00:09.703,125] mbedtls: ssl_tls.c:7629: <= parse certificate [00:00:09.712,463] mbedtls: ssl_msg.c:2358: => flush output [00:00:09.721,374] mbedtls: ssl_msg.c:2367: <= flush output [00:00:09.730,316] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_KEY_EXCHANGE [00:00:09.741,821] mbedtls: ssl_tls12_client.c:2091: => parse server key exchange [00:00:09.752,624] mbedtls: ssl_msg.c:4122: => read record [00:00:09.761,444] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.770,263] mbedtls: ssl_msg.c:2300: in_left: 0, nb_want: 5 [00:00:09.779,846] mbedtls: ssl_msg.c:2320: in_left: 0, nb_want: 5 [00:00:09.789,398] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 5 (-0xfffffffb) [00:00:09.801,116] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:09.809,997] mbedtls: ssl_msg.c:3869: input record: msgtype = 22, version = [0x303], msglen = 333 [00:00:09.822,448] mbedtls: ssl_msg.c:2160: => fetch input [00:00:09.831,268] mbedtls: ssl_msg.c:2300: in_left: 5, nb_want: 338 [00:00:09.841,064] mbedtls: ssl_msg.c:2320: in_left: 5, nb_want: 338 [00:00:09.850,799] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 333 (-0xfffffeb3) [00:00:09.862,701] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:09.871,582] mbedtls: ssl_msg.c:3242: handshake message: msglen = 333, type = 12, hslen = 333 [00:00:09.883,911] mbedtls: ssl_msg.c:4194: <= read record [00:00:09.892,761] mbedtls: ssl_tls12_client.c:2178: dumping 'server key exchange' (329 bytes) [00:00:09.904,693] mbedtls: ssl_tls12_client.c:2178: 0000: 03 00 17 41 04 55 44 d7 15 46 c7 b3 49 0e 54 e8 ...A.UD..F..I.T. [00:00:09.919,281] mbedtls: ssl_tls12_client.c:2178: 0010: fe f4 53 aa 2d 58 ef 40 2e b1 51 61 93 e4 fb 9c ..S.-X.@..Qa.... [00:00:09.933,868] mbedtls: ssl_tls12_client.c:2178: 0020: 46 66 0c 75 7a 4d d8 a5 21 88 dc a5 51 08 32 8b Ff.uzM..!...Q.2. [00:00:09.948,455] mbedtls: ssl_tls12_client.c:2178: 0030: 9e 60 73 3f df 0e 79 52 4b 52 90 20 cf 71 ba a2 .`s?..yRKR. .q.. [00:00:09.963,043] mbedtls: ssl_tls12_client.c:2178: 0040: dd 26 8e f0 ef 04 01 01 00 8d 42 91 29 3f b9 a4 .&........B.)?.. [00:00:09.977,630] mbedtls: ssl_tls12_client.c:2178: 0050: 13 bf 86 a8 bb 9c 69 5c c1 84 02 b9 63 16 04 b4 ......i\....c... [00:00:09.992,218] mbedtls: ssl_tls12_client.c:2178: 0060: ba 68 32 75 8a b6 43 69 5f 0b 24 1c 7b 49 15 97 .h2u..Ci_.$.{I.. [00:00:10.006,805] mbedtls: ssl_tls12_client.c:2178: 0070: ab bd cc 9f f2 9d a9 d4 5e a7 d9 31 08 9b 28 a2 ........^..1..(. [00:00:10.021,392] mbedtls: ssl_tls12_client.c:2178: 0080: d6 7e 82 62 8f 10 e5 29 56 ac d2 84 8a 56 21 46 .~.b...)V....V!F [00:00:10.035,980] mbedtls: ssl_tls12_client.c:2178: 0090: a8 bb 15 44 6d 32 2e a7 52 19 2d bd 56 1d c6 96 ...Dm2..R.-.V... [00:00:10.050,567] mbedtls: ssl_tls12_client.c:2178: 00a0: dd cd e8 7f 4d a0 c8 17 6d 48 28 d0 57 79 8a aa ....M...mH(.Wy.. [00:00:10.065,155] mbedtls: ssl_tls12_client.c:2178: 00b0: fe 52 56 b6 ae c3 c7 ef 1f 61 60 4e 68 c7 d2 1c .RV......a`Nh... [00:00:10.079,742] mbedtls: ssl_tls12_client.c:2178: 00c0: 3d 84 85 64 c3 3f 6e b9 fd 8f 3e 8f 9e e1 4a fc =..d.?n...>...J. [00:00:10.094,329] mbedtls: ssl_tls12_client.c:2178: 00d0: ec 4c a8 0a ad d2 fa 01 2d bf 0c ac a9 fb 51 dd .L......-.....Q. [00:00:10.108,917] mbedtls: ssl_tls12_client.c:2178: 00e0: 43 d2 49 08 a1 cc a1 49 ab ba 8c 0d db 20 44 aa C.I....I..... D. [00:00:10.123,535] mbedtls: ssl_tls12_client.c:2178: 00f0: bc 6c 8f e8 a9 47 6b 08 b5 dc 5c a5 6e b5 e6 9c .l...Gk...\.n... [00:00:10.138,122] mbedtls: ssl_tls12_client.c:2178: 0100: ab ec 4e 1e 85 83 89 36 bb f1 d7 ba cb 6d 4c 6f ..N....6.....mLo [00:00:10.152,709] mbedtls: ssl_tls12_client.c:2178: 0110: 5b f5 45 80 9a 50 ca 1d dd 4b 81 84 ed 64 82 69 [.E..P...K...d.i [00:00:10.167,327] mbedtls: ssl_tls12_client.c:2178: 0120: 93 4a 4b fc b5 f0 d5 e4 af 99 1a ae 94 95 3b 28 .JK...........;( [00:00:10.181,915] mbedtls: ssl_tls12_client.c:2178: 0130: 63 d1 5c 52 94 ab 0d 8f ff ad 22 34 79 c3 b1 6c c.\R......"4y..l [00:00:10.196,472] mbedtls: ssl_tls12_client.c:2178: 0140: 00 83 5e bd 12 db 1e 40 61 ..^....@a [00:00:10.210,266] mbedtls: ssl_tls12_client.c:1807: ECDH curve: secp256r1 [00:00:10.220,520] mbedtls: ssl_tls12_client.c:1814: value of 'ECDH: Qp(X)' (255 bits) is: [00:00:10.232,055] mbedtls: ssl_tls12_client.c:1814: 55 44 d7 15 46 c7 b3 49 0e 54 e8 fe f4 53 aa 2d [00:00:10.244,537] mbedtls: ssl_tls12_client.c:1814: 58 ef 40 2e b1 51 61 93 e4 fb 9c 46 66 0c 75 7a [00:00:10.256,835] mbedtls: ssl_tls12_client.c:1814: value of 'ECDH: Qp(Y)' (255 bits) is: [00:00:10.268,371] mbedtls: ssl_tls12_client.c:1814: 4d d8 a5 21 88 dc a5 51 08 32 8b 9e 60 73 3f df [00:00:10.280,853] mbedtls: ssl_tls12_client.c:1814: 0e 79 52 4b 52 90 20 cf 71 ba a2 dd 26 8e f0 ef [00:00:10.293,151] mbedtls: ssl_tls12_client.c:2373: dumping 'signature' (256 bytes) [00:00:10.304,199] mbedtls: ssl_tls12_client.c:2373: 0000: 8d 42 91 29 3f b9 a4 13 bf 86 a8 bb 9c 69 5c c1 .B.)?........i\. [00:00:10.318,786] mbedtls: ssl_tls12_client.c:2373: 0010: 84 02 b9 63 16 04 b4 ba 68 32 75 8a b6 43 69 5f ...c....h2u..Ci_ [00:00:10.333,374] mbedtls: ssl_tls12_client.c:2373: 0020: 0b 24 1c 7b 49 15 97 ab bd cc 9f f2 9d a9 d4 5e .$.{I..........^ [00:00:10.347,991] mbedtls: ssl_tls12_client.c:2373: 0030: a7 d9 31 08 9b 28 a2 d6 7e 82 62 8f 10 e5 29 56 ..1..(..~.b...)V [00:00:10.362,579] mbedtls: ssl_tls12_client.c:2373: 0040: ac d2 84 8a 56 21 46 a8 bb 15 44 6d 32 2e a7 52 ....V!F...Dm2..R [00:00:10.377,166] mbedtls: ssl_tls12_client.c:2373: 0050: 19 2d bd 56 1d c6 96 dd cd e8 7f 4d a0 c8 17 6d .-.V.......M...m [00:00:10.391,784] mbedtls: ssl_tls12_client.c:2373: 0060: 48 28 d0 57 79 8a aa fe 52 56 b6 ae c3 c7 ef 1f H(.Wy...RV...... [00:00:10.406,372] mbedtls: ssl_tls12_client.c:2373: 0070: 61 60 4e 68 c7 d2 1c 3d 84 85 64 c3 3f 6e b9 fd a`Nh...=..d.?n.. [00:00:10.420,959] mbedtls: ssl_tls12_client.c:2373: 0080: 8f 3e 8f 9e e1 4a fc ec 4c a8 0a ad d2 fa 01 2d .>...J..L......- [00:00:10.435,577] mbedtls: ssl_tls12_client.c:2373: 0090: bf 0c ac a9 fb 51 dd 43 d2 49 08 a1 cc a1 49 ab .....Q.C.I....I. [00:00:10.450,164] mbedtls: ssl_tls12_client.c:2373: 00a0: ba 8c 0d db 20 44 aa bc 6c 8f e8 a9 47 6b 08 b5 .... D..l...Gk.. [00:00:10.464,752] mbedtls: ssl_tls12_client.c:2373: 00b0: dc 5c a5 6e b5 e6 9c ab ec 4e 1e 85 83 89 36 bb .\.n.....N....6. [00:00:10.479,339] mbedtls: ssl_tls12_client.c:2373: 00c0: f1 d7 ba cb 6d 4c 6f 5b f5 45 80 9a 50 ca 1d dd ....mLo[.E..P... [00:00:10.493,927] mbedtls: ssl_tls12_client.c:2373: 00d0: 4b 81 84 ed 64 82 69 93 4a 4b fc b5 f0 d5 e4 af K...d.i.JK...... [00:00:10.508,544] mbedtls: ssl_tls12_client.c:2373: 00e0: 99 1a ae 94 95 3b 28 63 d1 5c 52 94 ab 0d 8f ff .....;(c.\R..... [00:00:10.523,132] mbedtls: ssl_tls12_client.c:2373: 00f0: ad 22 34 79 c3 b1 6c 00 83 5e bd 12 db 1e 40 61 ."4y..l..^....@a [00:00:10.537,536] mbedtls: ssl_tls.c:8732: Perform mbedtls-based computation of digest of ServerKeyExchange [00:00:10.550,628] mbedtls: ssl_tls12_client.c:2390: dumping 'parameters hash' (32 bytes) [00:00:10.562,133] mbedtls: ssl_tls12_client.c:2390: 0000: 7f 66 a2 98 05 4b 4e b1 14 80 84 59 1e 53 ff 07 .f...KN....Y.S.. [00:00:10.576,751] mbedtls: ssl_tls12_client.c:2390: 0010: 06 8a 23 1f 04 f0 fe 3e 7c a6 f4 12 64 4f e1 16 ..#....>|...dO.. [00:00:10.643,676] mbedtls: ssl_tls12_client.c:2461: <= parse server key exchange [00:00:10.654,479] mbedtls: ssl_msg.c:2358: => flush output [00:00:10.663,391] mbedtls: ssl_msg.c:2367: <= flush output [00:00:10.672,332] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_CERTIFICATE_REQUEST [00:00:10.683,837] mbedtls: ssl_tls12_client.c:2500: => parse certificate request [00:00:10.694,641] mbedtls: ssl_msg.c:4122: => read record [00:00:10.703,491] mbedtls: ssl_msg.c:2160: => fetch input [00:00:10.712,310] mbedtls: ssl_msg.c:2300: in_left: 0, nb_want: 5 [00:00:10.721,893] mbedtls: ssl_msg.c:2320: in_left: 0, nb_want: 5 [00:00:10.731,445] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 5 (-0xfffffffb) [00:00:10.743,194] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:10.752,075] mbedtls: ssl_msg.c:3869: input record: msgtype = 22, version = [0x303], msglen = 4 [00:00:10.764,343] mbedtls: ssl_msg.c:2160: => fetch input [00:00:10.773,193] mbedtls: ssl_msg.c:2300: in_left: 5, nb_want: 9 [00:00:10.782,806] mbedtls: ssl_msg.c:2320: in_left: 5, nb_want: 9 [00:00:10.792,358] mbedtls: ssl_msg.c:2323: ssl->f_recv(_timeout)() returned 4 (-0xfffffffc) [00:00:10.804,107] mbedtls: ssl_msg.c:2345: <= fetch input [00:00:10.812,988] mbedtls: ssl_msg.c:3242: handshake message: msglen = 4, type = 14, hslen = 4 [00:00:10.824,737] mbedtls: ssl_msg.c:4194: <= read record [00:00:10.833,587] mbedtls: ssl_tls12_client.c:2526: got no certificate request [00:00:10.843,963] mbedtls: ssl_tls12_client.c:2664: <= parse certificate request [00:00:10.854,766] mbedtls: ssl_msg.c:2358: => flush output [00:00:10.863,677] mbedtls: ssl_msg.c:2367: <= flush output [00:00:10.872,589] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_SERVER_HELLO_DONE [00:00:10.883,941] mbedtls: ssl_tls12_client.c:2675: => parse server hello done [00:00:10.894,561] mbedtls: ssl_msg.c:4122: => read record [00:00:10.903,381] mbedtls: ssl_msg.c:4190: reuse previously read message [00:00:10.913,482] mbedtls: ssl_msg.c:4194: <= read record [00:00:10.922,332] mbedtls: ssl_tls12_client.c:2703: <= parse server hello done [00:00:10.932,952] mbedtls: ssl_msg.c:2358: => flush output [00:00:10.941,864] mbedtls: ssl_msg.c:2367: <= flush output [00:00:10.950,805] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_CLIENT_CERTIFICATE [00:00:10.962,219] mbedtls: ssl_tls.c:6921: => write certificate [00:00:10.971,557] mbedtls: ssl_tls.c:6932: <= skip write certificate [00:00:10.981,323] mbedtls: ssl_msg.c:2358: => flush output [00:00:10.990,234] mbedtls: ssl_msg.c:2367: <= flush output [00:00:10.999,176] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_CLIENT_KEY_EXCHANGE [00:00:11.010,681] mbedtls: ssl_tls12_client.c:2718: => write client key exchange [00:00:11.060,791] mbedtls: ssl_tls12_client.c:2864: value of 'ECDH: Q(X)' (254 bits) is: [00:00:11.072,235] mbedtls: ssl_tls12_client.c:2864: 25 89 68 30 53 9c b4 80 50 52 b3 90 c0 25 c0 24 [00:00:11.084,716] mbedtls: ssl_tls12_client.c:2864: 9c 16 34 08 d6 f5 9f 5c 5a 24 02 56 31 89 be 01 [00:00:11.097,045] mbedtls: ssl_tls12_client.c:2864: value of 'ECDH: Q(Y)' (256 bits) is: [00:00:11.108,459] mbedtls: ssl_tls12_client.c:2864: 8f d6 bb a3 80 81 87 54 87 bc ac 40 b3 ec ac 58 [00:00:11.120,941] mbedtls: ssl_tls12_client.c:2864: 7b 26 a9 aa 3a fe 0b b1 a0 b5 26 50 b2 40 fb 90 [00:00:11.172,332] mbedtls: ssl_tls12_client.c:2892: value of 'ECDH: z' (256 bits) is: [00:00:11.183,502] mbedtls: ssl_tls12_client.c:2892: db c6 f3 67 7c ee df 88 28 3d 6d 12 35 79 ed 74 [00:00:11.195,983] mbedtls: ssl_tls12_client.c:2892: c2 73 99 a6 70 56 6f 45 4a 64 82 c3 80 95 0e 21 [00:00:11.208,282] mbedtls: ssl_msg.c:2788: => write handshake message [00:00:11.218,200] mbedtls: ssl_msg.c:2948: => write record [00:00:11.227,172] mbedtls: ssl_msg.c:3032: output record: msgtype = 22, version = [3:3], msglen = 70 [00:00:11.239,440] mbedtls: ssl_msg.c:2358: => flush output [00:00:11.248,382] mbedtls: ssl_msg.c:2372: message length: 75, out_left: 75 [00:00:11.259,368] mbedtls: ssl_msg.c:2379: ssl->f_send() returned 75 (-0xffffffb5) [00:00:11.270,324] mbedtls: ssl_msg.c:2406: <= flush output [00:00:11.279,235] mbedtls: ssl_msg.c:3085: <= write record [00:00:11.288,177] mbedtls: ssl_msg.c:2909: <= write handshake message [00:00:11.298,034] mbedtls: ssl_tls12_client.c:3213: <= write client key exchange [00:00:11.308,837] mbedtls: ssl_msg.c:2358: => flush output [00:00:11.317,749] mbedtls: ssl_msg.c:2367: <= flush output [00:00:11.326,690] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_CERTIFICATE_VERIFY [00:00:11.338,104] mbedtls: ssl_tls12_client.c:3261: => write certificate verify [00:00:11.348,815] mbedtls: ssl_tls.c:6535: => derive keys [00:00:11.357,635] mbedtls: ssl_tls.c:6659: => calc verify [00:00:11.366,607] mbedtls: ssl_tls.c:6677: dumping 'calculated verify result' (32 bytes) [00:00:11.378,082] mbedtls: ssl_tls.c:6677: 0000: f8 00 a8 2b 30 62 f5 7c f9 23 51 be 1c 75 e8 37 ...+0b.|.#Q..u.7 [00:00:11.391,906] mbedtls: ssl_tls.c:6677: 0010: ae b6 25 72 af 00 be 5d e7 d7 12 01 49 d0 7f a1 ..%r...]....I... [00:00:11.405,456] mbedtls: ssl_tls.c:6678: <= calc verify [00:00:11.414,337] mbedtls: ssl_tls.c:6378: dumping 'session hash for extended master secret' (32 bytes) [00:00:11.427,124] mbedtls: ssl_tls.c:6378: 0000: f8 00 a8 2b 30 62 f5 7c f9 23 51 be 1c 75 e8 37 ...+0b.|.#Q..u.7 [00:00:11.440,948] mbedtls: ssl_tls.c:6378: 0010: ae b6 25 72 af 00 be 5d e7 d7 12 01 49 d0 7f a1 ..%r...]....I... [00:00:11.455,810] mbedtls: ssl_tls.c:6518: dumping 'premaster secret' (32 bytes) [00:00:11.466,613] mbedtls: ssl_tls.c:6518: 0000: db c6 f3 67 7c ee df 88 28 3d 6d 12 35 79 ed 74 ...g|...(=m.5y.t [00:00:11.480,438] mbedtls: ssl_tls.c:6518: 0010: c2 73 99 a6 70 56 6f 45 4a 64 82 c3 80 95 0e 21 .s..pVoEJd.....! [00:00:11.498,199] mbedtls: ssl_tls.c:8267: ciphersuite = TLS-ECDHE-RSA-WITH-AES-128-GCM-SHA256 [00:00:11.509,979] mbedtls: ssl_tls.c:8269: dumping 'master secret' (48 bytes) [00:00:11.520,538] mbedtls: ssl_tls.c:8269: 0000: 3f 99 ba f2 e8 e9 54 55 be 01 00 7b 11 07 87 87 ?.....TU...{.... [00:00:11.534,332] mbedtls: ssl_tls.c:8269: 0010: 78 a3 3c cf 35 4b 94 4f cc 8e f2 ad 6c 53 28 3c x.<.5K.O....lS(< [00:00:11.548,156] mbedtls: ssl_tls.c:8269: 0020: 79 f9 6f d1 48 ca 31 ab ca 1c 24 b9 bc ce c8 7b y.o.H.1...$....{ [00:00:11.562,194] mbedtls: ssl_tls.c:8391: keylen: 16, minlen: 24, ivlen: 12, maclen: 0 [00:00:11.573,944] mbedtls: ssl_tls.c:6586: <= derive keys [00:00:11.582,794] mbedtls: ssl_tls12_client.c:3283: <= skip write certificate verify [00:00:11.593,933] mbedtls: ssl_msg.c:2358: => flush output [00:00:11.602,844] mbedtls: ssl_msg.c:2367: <= flush output [00:00:11.611,785] mbedtls: ssl_tls.c:3833: client state: MBEDTLS_SSL_CLIENT_CHANGE_CIPHER_SPEC [00:00:11.623,809] mbedtls: ssl_msg.c:5124: => write change cipher spec [00:00:11.633,758] mbedtls: ssl_msg.c:2788: => write handshake message [00:00:11.643,615] mbedtls: ssl_msg.c:2948: => write record [00:00:11.652,587] mbedtls: ssl_msg.c:3032: output record: msgtype = 20, version = [3:3], msglen = 1 [00:00:11.664,764]

MarkoSagadin · Answer

Hello Maria! I spent Thursday and Friday testing and debugging the Azure_iot_hub sample. I was able to get the MQTT to work over Websockets; however, I have uncovered some possible incorrect behaviour related to the DNS that I already mentioned. WebSockets So, the following setup was needed on my side to get the WebSockets working: - The small mqtt_helper.c patch that I included in the first post needs to be applied. - I needed to use NCS 2.9.0 - I had to regenerate the certificates as per the Nordic's IoT Hub library documentation - Mbedtls logs had to be disabled. The last one seemed to be the most critical. I was using the CONFIG_LOG_MODE_IMMEDIATE=y since I wanted to see all of the mbedtls logs (otherwise they were dropped). Naturally, this slowed the connection process quite significantly, and it seemed to result in a failed connection. For some reason, this was not an issue on the NCS 2.7.0., even though that amount of logs seems to be about the same (on both versions mbedtls prints quite many lines when setting up a TLS connection). But then again, if the baud rate was changed from 115200 to 921600 the sample started to work. DNS recv error As mentioned in a previous post, after moving to the NCS v2.9.0 the device would spam a long line of DNS recv error (-3) errors when trying to connect to the DPS server. It would spam them in quick intervals, for about the duration of the CONFIG_NET_SOCKETS_DNS_TIMEOUT. This symbol was set to 30 seconds in the sample, increasing it to 60 seconds would also increase the duration of the errors. From a development and production perspective, it is quite annoying that the device requires that much time to connect to the network. But Initially I thought that this is due to the my home network, however when I tested the sample in the office I observed the same issue. Also, the same behaviour was confirmed by a coworker who works in a different office as I do. *** Booting My Application v2.1.0-dev-12e5ee106034 *** *** Using nRF Connect SDK v2.9.0-7787b2649840 *** *** Using Zephyr OS v3.7.99-1f8f3dc29142 *** 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: Image index: 0, Swap type: none I: Bootloader chainload address offset: 0x10000 I: Jumping to the first image slot *** Booting nRF Connect SDK v2.9.0-7787b2649840 *** *** Using Zephyr OS v3.7.99-1f8f3dc29142 *** [00:00:00.245,941] azure_iot_hub_sample: Azure IoT Hub sample started [00:00:00.255,432] azure_iot_hub_sample: Bringing network interface up and connecting to the network [00:00:08.359,161] wifi_mgmt_ext: Connection requested [00:00:08.367,492] azure_iot_hub_sample: Device ID: nrf7002dk_irnas [00:00:12.542,602] net_dhcpv4: Received: 192.168.76.247 [00:00:12.551,330] azure_iot_hub_sample: Network connectivity established and IP address assigned [00:00:12.563,720] azure_iot_hub_sample: Connected to network [00:00:12.572,540] azure_iot_hub_sample: Starting DPS [00:00:12.582,275] azure_iot_hub_sample: DPS registration status: AZURE_IOT_HUB_DPS_REG_STATUS_NOT_STARTED [00:00:12.595,123] azure_iot_hub_sample: Already assigned to an IoT hub, skipping DPS [00:00:12.605,987] azure_iot_hub_sample: Device ID "nrf7002dk_irnas" assigned to IoT hub with hostname "ioth" [00:00:12.622,985] azure_fota: Current firmware version: 0.0.0-dev [00:00:12.632,202] azure_iot_hub_sample: Azure IoT Hub library initialized [00:00:12.642,120] azure_iot_hub_sample: AZURE_IOT_HUB_EVT_CONNECTING [00:00:12.717,712] net_dns_dispatcher: DNS recv error (-3) [00:00:12.730,957] net_dns_dispatcher: DNS recv error (-3) [00:00:12.743,713] net_dns_dispatcher: DNS recv error (-3) [00:00:12.756,958] net_dns_dispatcher: DNS recv error (-3) [00:00:12.770,080] net_dns_dispatcher: DNS recv error (-3) [00:00:12.783,264] net_dns_dispatcher: DNS recv error (-3) [00:00:12.796,478] net_dns_dispatcher: DNS recv error (-3) [00:00:12.809,600] net_dns_dispatcher: DNS recv error (-3) [00:00:12.831,970] net_dns_dispatcher: DNS recv error (-3) [00:00:12.845,275] net_dns_dispatcher: DNS recv error (-3) [00:00:12.858,612] net_dns_dispatcher: DNS recv error (-3) [00:00:12.871,856] net_dns_dispatcher: DNS recv error (-3) [00:00:12.885,101] net_dns_dispatcher: DNS recv error (-3) [00:00:12.898,223] net_dns_dispatcher: DNS recv error (-3) [00:00:12.911,254] net_dns_dispatcher: DNS recv error (-3) [00:00:12.924,285] net_dns_dispatcher: DNS recv error (-3) [00:00:12.937,500] net_dns_dispatcher: DNS recv error (-3) [00:00:12.950,805] net_dns_dispatcher: DNS recv error (-3) [00:00:12.964,019] net_dns_dispatcher: DNS recv error (-3) [00:00:12.977,264] net_dns_dispatcher: DNS recv error (-3) [00:00:12.990,417] net_dns_dispatcher: DNS recv error (-3) [00:00:13.003,540] net_dns_dispatcher: DNS recv error (-3) [00:00:13.016,784] net_dns_dispatcher: DNS recv error (-3) [00:00:13.029,968] net_dns_dispatcher: DNS recv error (-3) [00:00:13.043,182] net_dns_dispatcher: DNS recv error (-3) [00:00:13.056,335] net_dns_dispatcher: DNS recv error (-3) [00:00:13.069,458] net_dns_dispatcher: DNS recv error (-3) [00:00:13.082,794] net_dns_dispatcher: DNS recv error (-3) [00:00:13.095,947] net_dns_dispatcher: DNS recv error (-3) [00:00:13.109,222] net_dns_dispatcher: DNS recv error (-3) [00:00:13.122,436] net_dns_dispatcher: DNS recv error (-3) [00:00:13.135,589] net_dns_dispatcher: DNS recv error (-3) [00:00:13.148,864] net_dns_dispatcher: DNS recv error (-3) [00:00:13.161,956] net_dns_dispatcher: DNS recv error (-3) [00:00:13.175,079] net_dns_dispatcher: DNS recv error (-3) [00:00:13.188,293] net_dns_dispatcher: DNS recv error (-3) [00:00:13.201,354] net_dns_dispatcher: DNS recv error (-3) [00:00:13.214,508] net_dns_dispatcher: DNS recv error (-3) [00:00:13.227,478] net_dns_dispatcher: DNS recv error (-3) [00:00:13.240,631] net_dns_dispatcher: DNS recv error (-3) [00:00:13.253,936] net_dns_dispatcher: DNS recv error (-3) [00:00:13.267,181] net_dns_dispatcher: DNS recv error (-3) [00:00:13.280,334] net_dns_dispatcher: DNS recv error (-3) [00:00:13.293,579] net_dns_dispatcher: DNS recv error (-3) [00:00:13.306,793] net_dns_dispatcher: DNS recv error (-3) [00:00:13.320,037] net_dns_dispatcher: DNS recv error (-3) [00:00:13.333,068] net_dns_dispatcher: DNS recv error (-3) [00:00:13.349,578] net_dns_dispatcher: DNS recv error (-3) [00:00:13.362,213] net_dns_dispatcher: DNS recv error (-3) [00:00:13.375,457] net_dns_dispatcher: DNS recv error (-3) [00:00:13.388,763] net_dns_dispatcher: DNS recv error (-3) [00:00:13.401,916] net_dns_dispatcher: DNS recv error (-3) [00:00:13.415,069] net_dns_dispatcher: DNS recv error (-3) [00:00:13.430,389] net_dns_dispatcher: DNS recv error (-3) [00:00:13.443,084] net_dns_dispatcher: DNS recv error (-3) [00:00:13.456,207] net_dns_dispatcher: DNS recv error (-3) [00:00:13.469,421] net_dns_dispatcher: DNS recv error (-3) [00:00:13.482,543] net_dns_dispatcher: DNS recv error (-3) [00:00:13.497,314] net_dns_dispatcher: DNS recv error (-3) [00:00:13.510,467] net_dns_dispatcher: DNS recv error (-3) [00:00:13.523,681] net_dns_dispatcher: DNS recv error (-3) [00:00:13.536,834] net_dns_dispatcher: DNS recv error (-3) [00:00:13.549,896] net_dns_dispatcher: DNS recv error (-3) [00:00:13.562,927] net_dns_dispatcher: DNS recv error (-3) [00:00:13.576,232] net_dns_dispatcher: DNS recv error (-3) [00:00:13.589,385] net_dns_dispatcher: DNS recv error (-3) [00:00:13.602,691] net_dns_dispatcher: DNS recv error (-3) [00:00:13.615,844] net_dns_dispatcher: DNS recv error (-3) [00:00:13.628,906] net_dns_dispatcher: DNS recv error (-3) [00:00:13.641,967] net_dns_dispatcher: DNS recv error (-3) [00:00:13.655,181] net_dns_dispatcher: DNS recv error (-3) [00:00:13.668,395] net_dns_dispatcher: DNS recv error (-3) [00:00:13.681,732] net_dns_dispatcher: DNS recv error (-3) [00:00:13.694,976] net_dns_dispatcher: DNS recv error (-3) [00:00:13.708,190] net_dns_dispatcher: DNS recv error (-3) [00:00:13.721,313] net_dns_dispatcher: DNS recv error (-3) [00:00:13.741,058] net_dns_dispatcher: DNS recv error (-3) [00:00:13.754,547] net_dns_dispatcher: DNS recv error (-3) [00:00:13.768,005] net_dns_dispatcher: DNS recv error (-3) [00:00:13.781,036] net_dns_dispatcher: DNS recv error (-3) [00:00:13.794,189] net_dns_dispatcher: DNS recv error (-3) [00:00:13.807,342] net_dns_dispatcher: DNS recv error (-3) [00:00:13.820,495] net_dns_dispatcher: DNS recv error (-3) [00:00:13.833,557] net_dns_dispatcher: DNS recv error (-3) [00:00:13.846,618] net_dns_dispatcher: DNS recv error (-3) [00:00:13.859,924] net_dns_dispatcher: DNS recv error (-3) [00:00:13.873,016] net_dns_dispatcher: DNS recv error (-3) [00:00:13.886,230] net_dns_dispatcher: DNS recv error (-3) [00:00:13.899,291] net_dns_dispatcher: DNS recv error (-3) [00:00:13.912,536] net_dns_dispatcher: DNS recv error (-3) [00:00:13.925,506] net_dns_dispatcher: DNS recv error (-3) [00:00:13.938,720] net_dns_dispatcher: DNS recv error (-3) [00:00:13.951,782] net_dns_dispatcher: DNS recv error (-3) [00:00:13.964,904] net_dns_dispatcher: DNS recv error (-3) [00:00:13.978,027] net_dns_dispatcher: DNS recv error (-3) [00:00:13.991,149] net_dns_dispatcher: DNS recv error (-3) [00:00:14.004,394] net_dns_dispatcher: DNS recv error (-3) [00:00:14.017,608] net_dns_dispatcher: DNS recv error (-3) [00:00:14.030,700] net_dns_dispatcher: DNS recv error (-3) [00:00:14.045,471] net_dns_dispatcher: DNS recv error (-3) [00:00:14.058,807] net_dns_dispatcher: DNS recv error (-3) [00:00:14.071,990] net_dns_dispatcher: DNS recv error (-3) [00:00:14.085,205] net_dns_dispatcher: DNS recv error (-3) [00:00:14.098,297] net_dns_dispatcher: DNS recv error (-3) [00:00:14.111,511] net_dns_dispatcher: DNS recv error (-3) [00:00:14.124,725] net_dns_dispatcher: DNS recv error (-3) [00:00:14.137,908] net_dns_dispatcher: DNS recv error (-3) [00:00:14.150,970] net_dns_dispatcher: DNS recv error (-3) [00:00:14.164,154] net_dns_dispatcher: DNS recv error (-3) [00:00:14.177,337] net_dns_dispatcher: DNS recv error (-3) [00:00:14.190,521] net_dns_dispatcher: DNS recv error (-3) [00:00:14.203,765] net_dns_dispatcher: DNS recv error (-3) [00:00:14.216,827] net_dns_dispatcher: DNS recv error (-3) [00:00:14.230,133] net_dns_dispatcher: DNS recv error (-3) [00:00:14.243,286] net_dns_dispatcher: DNS recv error (-3) [00:00:14.256,530] net_dns_dispatcher: DNS recv error (-3) [00:00:14.269,775] net_dns_dispatcher: DNS recv error (-3) [00:00:14.282,989] net_dns_dispatcher: DNS recv error (-3) [00:00:14.296,142] net_dns_dispatcher: DNS recv error (-3) [00:00:14.309,478] net_dns_dispatcher: DNS recv error (-3) [00:00:14.322,814] net_dns_dispatcher: DNS recv error (-3) [00:00:14.335,906] net_dns_dispatcher: DNS recv error (-3) [00:00:14.350,585] net_dns_dispatcher: DNS recv error (-3) [00:00:14.363,800] net_dns_dispatcher: DNS recv error (-3) [00:00:14.377,044] net_dns_dispatcher: DNS recv error (-3) [00:00:14.390,258] net_dns_dispatcher: DNS recv error (-3) [00:00:14.403,472] net_dns_dispatcher: DNS recv error (-3) [00:00:14.416,717] net_dns_dispatcher: DNS recv error (-3) [00:00:14.429,779] net_dns_dispatcher: DNS recv error (-3) [00:00:14.442,901] net_dns_dispatcher: DNS recv error (-3) [00:00:14.456,237] net_dns_dispatcher: DNS recv error (-3) [00:00:14.469,360] net_dns_dispatcher: DNS recv error (-3) [00:00:14.482,513] net_dns_dispatcher: DNS recv error (-3) [00:00:14.497,253] net_dns_dispatcher: DNS recv error (-3) [00:00:14.510,406] net_dns_dispatcher: DNS recv error (-3) [00:00:14.523,468] net_dns_dispatcher: DNS recv error (-3) [00:00:14.536,499] net_dns_dispatcher: DNS recv error (-3) [00:00:14.549,652] net_dns_dispatcher: DNS recv error (-3) [00:00:14.562,744] net_dns_dispatcher: DNS recv error (-3) [00:00:14.575,805] net_dns_dispatcher: DNS recv error (-3) [00:00:14.588,775] net_dns_dispatcher: DNS recv error (-3) [00:00:14.602,020] net_dns_dispatcher: DNS recv error (-3) [00:00:14.615,325] net_dns_dispatcher: DNS recv error (-3) [00:00:14.628,356] net_dns_dispatcher: DNS recv error (-3) [00:00:14.641,510] net_dns_dispatcher: DNS recv error (-3) [00:00:14.662,200] net_dns_dispatcher: DNS recv error (-3) [00:00:14.676,757] net_dns_dispatcher: DNS recv error (-3) [00:00:14.689,819] net_dns_dispatcher: DNS recv error (-3) [00:00:14.703,063] net_dns_dispatcher: DNS recv error (-3) [00:00:14.716,033] net_dns_dispatcher: DNS recv error (-3) [00:00:14.729,095] net_dns_dispatcher: DNS recv error (-3) [00:00:14.742,309] net_dns_dispatcher: DNS recv error (-3) [00:00:14.755,462] net_dns_dispatcher: DNS recv error (-3) [00:00:14.768,585] net_dns_dispatcher: DNS recv error (-3) [00:00:14.781,829] net_dns_dispatcher: DNS recv error (-3) [00:00:14.795,043] net_dns_dispatcher: DNS recv error (-3) [00:00:14.808,349] net_dns_dispatcher: DNS recv error (-3) [00:00:14.821,502] net_dns_dispatcher: DNS recv error (-3) [00:00:14.834,716] net_dns_dispatcher: DNS recv error (-3) [00:00:14.847,869] net_dns_dispatcher: DNS recv error (-3) [00:00:14.861,083] net_dns_dispatcher: DNS recv error (-3) [00:00:14.874,328] net_dns_dispatcher: DNS recv error (-3) [00:00:14.887,542] net_dns_dispatcher: DNS recv error (-3) [00:00:14.900,695] net_dns_dispatcher: DNS recv error (-3) [00:00:14.913,818] net_dns_dispatcher: DNS recv error (-3) [00:00:14.926,971] net_dns_dispatcher: DNS recv error (-3) [00:00:14.940,185] net_dns_dispatcher: DNS recv error (-3) [00:00:14.953,308] net_dns_dispatcher: DNS recv error (-3) [00:00:14.969,757] net_dns_dispatcher: DNS recv error (-3) [00:00:14.982,910] net_dns_dispatcher: DNS recv error (-3) [00:00:14.996,063] net_dns_dispatcher: DNS recv error (-3) [00:00:15.009,307] net_dns_dispatcher: DNS recv error (-3) [00:00:15.022,613] net_dns_dispatcher: DNS recv error (-3) [00:00:15.035,736] net_dns_dispatcher: DNS recv error (-3) [00:00:15.048,858] net_dns_dispatcher: DNS recv error (-3) [00:00:15.062,194] net_dns_dispatcher: DNS recv error (-3) [00:00:15.075,439] net_dns_dispatcher: DNS recv error (-3) [00:00:15.088,653] net_dns_dispatcher: DNS recv error (-3) [00:00:15.101,776] net_dns_dispatcher: DNS recv error (-3) [00:00:15.114,746] net_dns_dispatcher: DNS recv error (-3) [00:00:15.127,990] net_dns_dispatcher: DNS recv error (-3) [00:00:15.141,113] net_dns_dispatcher: DNS recv error (-3) [00:00:15.154,235] net_dns_dispatcher: DNS recv error (-3) [00:00:15.167,358] net_dns_dispatcher: DNS recv error (-3) [00:00:15.180,511] net_dns_dispatcher: DNS recv error (-3) [00:00:15.193,572] net_dns_dispatcher: DNS recv error (-3) [00:00:15.206,787] net_dns_dispatcher: DNS recv error (-3) [00:00:15.220,092] net_dns_dispatcher: DNS recv error (-3) [00:00:15.233,184] net_dns_dispatcher: DNS recv error (-3) [00:00:15.246,337] net_dns_dispatcher: DNS recv error (-3) [00:00:15.259,399] net_dns_dispatcher: DNS recv error (-3) [00:00:15.274,169] net_dns_dispatcher: DNS recv error (-3) [00:00:15.287,414] net_dns_dispatcher: DNS recv error (-3) [00:00:15.300,537] net_dns_dispatcher: DNS recv error (-3) [00:00:15.313,720] net_dns_dispatcher: DNS recv error (-3) [00:00:15.326,934] net_dns_dispatcher: DNS recv error (-3) [00:00:15.340,026] net_dns_dispatcher: DNS recv error (-3) [00:00:15.353,088] net_dns_dispatcher: DNS recv error (-3) [00:00:15.366,333] net_dns_dispatcher: DNS recv error (-3) [00:00:15.379,455] net_dns_dispatcher: DNS recv error (-3) [00:00:15.392,547] net_dns_dispatcher: DNS recv error (-3) [00:00:15.405,609] net_dns_dispatcher: DNS recv error (-3) [00:00:15.418,914] net_dns_dispatcher: DNS recv error (-3) [00:00:15.432,159] net_dns_dispatcher: DNS recv error (-3) [00:00:15.445,404] net_dns_dispatcher: DNS recv error (-3) [00:00:15.458,435] net_dns_dispatcher: DNS recv error (-3) [00:00:15.471,496] net_dns_dispatcher: DNS recv error (-3) [00:00:15.484,649] net_dns_dispatcher: DNS recv error (-3) [00:00:15.497,711] net_dns_dispatcher: DNS recv error (-3) [00:00:15.510,681] net_dns_dispatcher: DNS recv error (-3) [00:00:15.523,986] net_dns_dispatcher: DNS recv error (-3) [00:00:15.537,261] net_dns_dispatcher: DNS recv error (-3) [00:00:15.550,384] net_dns_dispatcher: DNS recv error (-3) [00:00:15.563,720] net_dns_dispatcher: DNS recv error (-3) [00:00:15.579,132] net_dns_dispatcher: DNS recv error (-3) [00:00:15.592,010] net_dns_dispatcher: DNS recv error (-3) [00:00:15.605,224] net_dns_dispatcher: DNS recv error (-3) [00:00:15.618,469] net_dns_dispatcher: DNS recv error (-3) [00:00:15.631,622] net_dns_dispatcher: DNS recv error (-3) [00:00:15.644,775] net_dns_dispatcher: DNS recv error (-3) [00:00:15.657,989] net_dns_dispatcher: DNS recv error (-3) [00:00:15.671,051] net_dns_dispatcher: DNS recv error (-3) [00:00:15.684,295] net_dns_dispatcher: DNS recv error (-3) [00:00:15.697,601] net_dns_dispatcher: DNS recv error (-3) [00:00:15.710,845] net_dns_dispatcher: DNS recv error (-3) [00:00:15.723,999] net_dns_dispatcher: DNS recv error (-3) [00:00:15.737,121] net_dns_dispatcher: DNS recv error (-3) [00:00:15.750,335] net_dns_dispatcher: DNS recv error (-3) [00:00:15.763,549] net_dns_dispatcher: DNS recv error (-3) [00:00:15.776,702] net_dns_dispatcher: DNS recv error (-3) [00:00:15.789,947] net_dns_dispatcher: DNS recv error (-3) [00:00:15.803,131] net_dns_dispatcher: DNS recv error (-3) [00:00:15.816,284] net_dns_dispatcher: DNS recv error (-3) [00:00:15.829,406] net_dns_dispatcher: DNS recv error (-3) [00:00:15.842,590] net_dns_dispatcher: DNS recv error (-3) [00:00:15.855,834] net_dns_dispatcher: DNS recv error (-3) [00:00:15.869,079] net_dns_dispatcher: DNS recv error (-3) [00:00:15.885,131] net_dns_dispatcher: DNS recv error (-3) [00:00:15.899,291] net_dns_dispatcher: DNS recv error (-3) [00:00:15.912,475] net_dns_dispatcher: DNS recv error (-3) [00:00:15.925,598] net_dns_dispatcher: DNS recv error (-3) [00:00:15.938,842] net_dns_dispatcher: DNS recv error (-3) [00:00:15.952,117] net_dns_dispatcher: DNS recv error (-3) [00:00:15.965,362] net_dns_dispatcher: DNS recv error (-3) [00:00:15.978,332] net_dns_dispatcher: DNS recv error (-3) [00:00:15.991,363] net_dns_dispatcher: DNS recv error (-3) [00:00:16.004,425] net_dns_dispatcher: DNS recv error (-3) [00:00:16.017,761] net_dns_dispatcher: DNS recv error (-3) [00:00:16.030,975] net_dns_dispatcher: DNS recv error (-3) [00:00:16.044,006] net_dns_dispatcher: DNS recv error (-3) [00:00:16.057,281] net_dns_dispatcher: DNS recv error (-3) [00:00:16.070,434] net_dns_dispatcher: DNS recv error (-3) [00:00:16.083,740] net_dns_dispatcher: DNS recv error (-3) [00:00:16.096,862] net_dns_dispatcher: DNS recv error (-3) [00:00:16.110,015] net_dns_dispatcher: DNS recv error (-3) [00:00:16.123,260] net_dns_dispatcher: DNS recv error (-3) [00:00:16.136,474] net_dns_dispatcher: DNS recv error (-3) [00:00:16.149,536] net_dns_dispatcher: DNS recv error (-3) [00:00:16.162,780] net_dns_dispatcher: DNS recv error (-3) [00:00:16.176,025] net_dns_dispatcher: DNS recv error (-3) [00:00:16.189,086] net_dns_dispatcher: DNS recv error (-3) [00:00:16.202,117] net_dns_dispatcher: DNS recv error (-3) [00:00:16.215,362] net_dns_dispatcher: DNS recv error (-3) [00:00:16.228,576] net_dns_dispatcher: DNS recv error (-3) [00:00:16.241,821] net_dns_dispatcher: DNS recv error (-3) [00:00:16.254,913] net_dns_dispatcher: DNS recv error (-3) [00:00:16.267,944] net_dns_dispatcher: DNS recv error (-3) [00:00:16.281,158] net_dns_dispatcher: DNS recv error (-3) [00:00:16.294,464] net_dns_dispatcher: DNS recv error (-3) [00:00:16.307,586] net_dns_dispatcher: DNS recv error (-3) [00:00:16.320,770] net_dns_dispatcher: DNS recv error (-3) [00:00:16.333,984] net_dns_dispatcher: DNS recv error (-3) [00:00:16.347,137] net_dns_dispatcher: DNS recv error (-3) [00:00:16.360,198] net_dns_dispatcher: DNS recv error (-3) [00:00:16.373,260] net_dns_dispatcher: DNS recv error (-3) [00:00:16.386,444] net_dns_dispatcher: DNS recv error (-3) [00:00:16.399,505] net_dns_dispatcher: DNS recv error (-3) [00:00:16.412,719] net_dns_dispatcher: DNS recv error (-3) [00:00:16.425,872] net_dns_dispatcher: DNS recv error (-3) [00:00:16.439,178] net_dns_dispatcher: DNS recv error (-3) [00:00:16.452,514] net_dns_dispatcher: DNS recv error (-3) [00:00:16.465,698] net_dns_dispatcher: DNS recv error (-3) [00:00:16.478,912] net_dns_dispatcher: DNS recv error (-3) [00:00:16.492,065] net_dns_dispatcher: DNS recv error (-3) [00:00:16.505,218] net_dns_dispatcher: DNS recv error (-3) [00:00:16.518,280] net_dns_dispatcher: DNS recv error (-3) [00:00:16.531,341] net_dns_dispatcher: DNS recv error (-3) [00:00:16.544,403] net_dns_dispatcher: DNS recv error (-3) [00:00:16.557,525] net_dns_dispatcher: DNS recv error (-3) [00:00:16.570,617] net_dns_dispatcher: DNS recv error (-3) [00:00:16.583,770] net_dns_dispatcher: DNS recv error (-3) [00:00:16.597,015] net_dns_dispatcher: DNS recv error (-3) [00:00:16.610,229] net_dns_dispatcher: DNS recv error (-3) [00:00:16.623,321] net_dns_dispatcher: DNS recv error (-3) [00:00:16.636,535] net_dns_dispatcher: DNS recv error (-3) [00:00:16.649,597] net_dns_dispatcher: DNS recv error (-3) [00:00:16.662,719] net_dns_dispatcher: DNS recv error (-3) [00:00:16.675,811] net_dns_dispatcher: DNS recv error (-3) [00:00:16.689,025] net_dns_dispatcher: DNS recv error (-3) [00:00:16.702,362] net_dns_dispatcher: DNS recv error (-3) [00:00:16.715,606] net_dns_dispatcher: DNS recv error (-3) [00:00:16.728,637] net_dns_dispatcher: DNS recv error (-3) [00:00:16.741,882] net_dns_dispatcher: DNS recv error (-3) [00:00:16.755,035] net_dns_dispatcher: DNS recv error (-3) [00:00:16.768,157] net_dns_dispatcher: DNS recv error (-3) [00:00:16.781,250] net_dns_dispatcher: DNS recv error (-3) [00:00:16.794,372] net_dns_dispatcher: DNS recv error (-3) [00:00:16.809,143] net_dns_dispatcher: DNS recv error (-3) [00:00:16.822,326] net_dns_dispatcher: DNS recv error (-3) [00:00:16.835,449] net_dns_dispatcher: DNS recv error (-3) [00:00:16.848,602] net_dns_dispatcher: DNS recv error (-3) [00:00:16.861,633] net_dns_dispatcher: DNS recv error (-3) [00:00:16.874,755] net_dns_dispatcher: DNS recv error (-3) [00:00:16.887,939] net_dns_dispatcher: DNS recv error (-3) [00:00:16.901,123] net_dns_dispatcher: DNS recv error (-3) [00:00:16.914,062] net_dns_dispatcher: DNS recv error (-3) [00:00:16.927,124] net_dns_dispatcher: DNS recv error (-3) [00:00:16.940,246] net_dns_dispatcher: DNS recv error (-3) [00:00:16.953,369] net_dns_dispatcher: DNS recv error (-3) [00:00:16.966,491] net_dns_dispatcher: DNS recv error (-3) [00:00:16.979,736] net_dns_dispatcher: DNS recv error (-3) [00:00:16.992,858] net_dns_dispatcher: DNS recv error (-3) [00:00:17.006,042] net_dns_dispatcher: DNS recv error (-3) [00:00:17.019,256] net_dns_dispatcher: DNS recv error (-3) [00:00:17.032,348] net_dns_dispatcher: DNS recv error (-3) [00:00:17.045,379] net_dns_dispatcher: DNS recv error (-3) [00:00:17.058,563] net_dns_dispatcher: DNS recv error (-3) [00:00:17.071,685] net_dns_dispatcher: DNS recv error (-3) [00:00:17.084,960] net_dns_dispatcher: DNS recv error (-3) [00:00:17.098,175] net_dns_dispatcher: DNS recv error (-3) [00:00:17.111,480] net_dns_dispatcher: DNS recv error (-3) [00:00:17.130,432] net_dns_dispatcher: DNS recv error (-3) [00:00:17.143,554] net_dns_dispatcher: DNS recv error (-3) [00:00:17.156,585] net_dns_dispatcher: DNS recv error (-3) [00:00:17.169,799] net_dns_dispatcher: DNS recv error (-3) [00:00:17.182,983] net_dns_dispatcher: DNS recv error (-3) [00:00:17.196,289] net_dns_dispatcher: DNS recv error (-3) [00:00:17.209,503] net_dns_dispatcher: DNS recv error (-3) [00:00:17.222,656] net_dns_dispatcher: DNS recv error (-3) [00:00:17.235,778] net_dns_dispatcher: DNS recv error (-3) [00:00:17.248,840] net_dns_dispatcher: DNS recv error (-3) [00:00:17.262,023] net_dns_dispatcher: DNS recv error (-3) [00:00:17.275,329] net_dns_dispatcher: DNS recv error (-3) [00:00:17.288,543] net_dns_dispatcher: DNS recv error (-3) [00:00:17.301,666] net_dns_dispatcher: DNS recv error (-3) [00:00:17.314,636] net_dns_dispatcher: DNS recv error (-3) [00:00:17.327,606] net_dns_dispatcher: DNS recv error (-3) [00:00:17.340,667] net_dns_dispatcher: DNS recv error (-3) [00:00:17.353,790] net_dns_dispatcher: DNS recv error (-3) [00:00:17.366,760] net_dns_dispatcher: DNS recv error (-3) [00:00:17.379,760] net_dns_dispatcher: DNS recv error (-3) [00:00:17.393,066] net_dns_dispatcher: DNS recv error (-3) [00:00:17.406,311] net_dns_dispatcher: DNS recv error (-3) [00:00:17.419,555] net_dns_dispatcher: DNS recv error (-3) [00:00:17.434,204] net_dns_dispatcher: DNS recv error (-3) [00:00:17.447,143] net_dns_dispatcher: DNS recv error (-3) [00:00:17.460,357] net_dns_dispatcher: DNS recv error (-3) [00:00:17.473,480] net_dns_dispatcher: DNS recv error (-3) [00:00:17.486,511] net_dns_dispatcher: DNS recv error (-3) [00:00:17.499,664] net_dns_dispatcher: DNS recv error (-3) [00:00:17.512,725] net_dns_dispatcher: DNS recv error (-3) [00:00:17.525,970] net_dns_dispatcher: DNS recv error (-3) [00:00:17.539,093] net_dns_dispatcher: DNS recv error (-3) [00:00:17.552,154] net_dns_dispatcher: DNS recv error (-3) [00:00:17.565,368] net_dns_dispatcher: DNS recv error (-3) [00:00:17.578,582] net_dns_dispatcher: DNS recv error (-3) [00:00:17.591,644] net_dns_dispatcher: DNS recv error (-3) [00:00:17.604,766] net_dns_dispatcher: DNS recv error (-3) [00:00:17.621,002] net_dns_dispatcher: DNS recv error (-3) [00:00:17.634,338] net_dns_dispatcher: DNS recv error (-3) [00:00:17.647,338] net_dns_dispatcher: DNS recv error (-3) [00:00:17.663,970] net_dns_dispatcher: DNS recv error (-3) [00:00:17.676,910] net_dns_dispatcher: DNS recv error (-3) [00:00:17.690,124] net_dns_dispatcher: DNS recv error (-3) [00:00:17.703,216] net_dns_dispatcher: DNS recv error (-3) [00:00:17.716,369] net_dns_dispatcher: DNS recv error (-3) [00:00:17.734,619] net_dns_dispatcher: DNS recv error (-3) [00:00:17.747,314] net_dns_dispatcher: DNS recv error (-3) [00:00:17.760,375] net_dns_dispatcher: DNS recv error (-3) [00:00:17.773,590] net_dns_dispatcher: DNS recv error (-3) [00:00:17.786,895] net_dns_dispatcher: DNS recv error (-3) [00:00:17.800,170] net_dns_dispatcher: DNS recv error (-3) [00:00:17.813,385] net_dns_dispatcher: DNS recv error (-3) [00:00:17.826,629] net_dns_dispatcher: DNS recv error (-3) [00:00:17.839,813] net_dns_dispatcher: DNS recv error (-3) [00:00:17.852,874] net_dns_dispatcher: DNS recv error (-3) [00:00:17.865,936]