[ 3255 -148723392]@localhost.localdomain[3 Aug 10:20:51] snx: starting debug - Tue Aug 3 10:20:51 2021 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] browser::browser(): called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] snx_CCC_browser::snx_CCC_browser: called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] snx_browser::auth: entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] gwinfo:gwinfo: entered!0x8698620 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] creating the ssl layer [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::talkssl(): entered with chunk=512, opaque=8698310, link_established=80ee120, link_failure=80ee100, packet_receive=80ee0d0, verify_gw=80ee140 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::set_sslalg: setting ssl alg to 2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSLctx_New: prefs = 1e [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSLctx_New: CKPSSL_ACCEPT_TLS1_2 is turned on + (CKPSSL_ACCEPT_TLSV1 | CKPSSL_ACCEPT_SSL3) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSLctx_New: choose SSLv23_method == the highest TLS version available -> should provide TLS 1.2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] is_initialized: new process or forked [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] The PRNG was not initialized properly [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] create_rand_mutex: failed to create mutex: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] rand_add_external_source: Failed to create mutex.: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] create_rand_mutex: failed to create mutex: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] rand_add_seedfile: Failed to create mutex.: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] create_rand_mutex: failed to create mutex: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwrand_write_seed: Failed to create mutex.: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] isExist: ProxyEntity didn't initiated yet [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::start_async: Creating a new connection [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::start_async: Connecting to gw: 0x0293b55f, port: 443 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_make_connection: 5fb59302/443: dowait is -1 sock is 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::start_async: Connection created successfully [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_conn_params: -> <5fb59302,443> [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: state: CONN_INIT - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: start ssl negotaition [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: start openSSL negotaition [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_PrepareConnection: verify mode: 0 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] My SSL Ciphers: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Cipher List: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 0: AES128-SHA SSLv3 Kx=RSA Au=RSA Enc=AES(128) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 1: AES256-SHA SSLv3 Kx=RSA Au=RSA Enc=AES(256) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 2: DES-CBC3-SHA SSLv3 Kx=RSA Au=RSA Enc=3DES(168) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 3: RC4-SHA SSLv3 Kx=RSA Au=RSA Enc=RC4(128) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 4: DES-CBC-SHA SSLv3 Kx=RSA Au=RSA Enc=DES(56) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: Returning OK!!! [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: current state = before/connect initialization [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: should retry. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: current state = SSLv2/v3 read server hello A [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_VerifyCallback: no params or params->key_holder [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: should retry. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: current state = SSLv3 read finished A [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: conncected, used TLSv1/SSLv3 ,AES128-SHA (-1) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_connected: peer authenticated [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_connected: current state: SSL negotiation finished successfully [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Certificate is: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] X509 Certificate Version 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Serial Number: 48dafbb504ba6c9ea9406444 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Issuer: CN=GlobalSign GCC R3 DV TLS CA 2020,O=GlobalSign nv-sa,C=BE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Subject: CN=io.corp.ru [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Not valid before: Fri Jul 2 15:37:02 2021 Local Time [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Not valid after: Wed Jul 27 09:50:13 2022 Local Time [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Extensions: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Extended Key Usage: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] serverAuth [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] clientAuth [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Subject Alternate names: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] DNS: io.corp.ru [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] CRL distribution Points: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] URI: http://crl.globalsign.com/gsgccr3dvtlsca2020.crl [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Basic Constraint: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] not CA [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Authority Info Access: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwAuthorityInfoAccess_imp: Authority Info Access [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SEQUENCE/SET OF { [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SEQUENCE { [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessMethod [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] (1.3.6.1.5.5.7.48.2) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessLocation [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] uniformResourceIdentifier [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Octet String [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 68 74 74 70 3a 2f 2f 73 65 63 75 72 65 2e 67 6c 6f 62 61 6c [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 73 69 67 6e 2e 63 6f 6d 2f 63 61 63 65 72 74 2f 67 73 67 63 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 63 72 33 64 76 74 6c 73 63 61 32 30 32 30 2e 63 72 74 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] http://secure.global [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] sign.com/cacert/gsgc [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] cr3dvtlsca2020.crt [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] } [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SEQUENCE { [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessMethod [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] (1.3.6.1.5.5.7.48.1) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessLocation [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] uniformResourceIdentifier [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Octet String [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 68 74 74 70 3a 2f 2f 6f 63 73 70 2e 67 6c 6f 62 61 6c 73 69 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 67 6e 2e 63 6f 6d 2f 67 73 67 63 63 72 33 64 76 74 6c 73 63 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 61 32 30 32 30 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] http://ocsp.globalsi [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] gn.com/gsgccr3dvtlsc [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] a2020 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] } [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] } [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Key Usage (Critical): [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] digitalSignature [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] keyEncipherment [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_connbuf_realloc: reallocating 0 from 0 to 1025 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_mux_in: 4: got 0 of 0 bytes == 0 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_mux_in: 4: managed to read 0 of 0 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_mux_in: 4: call: 80f2060 with 2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: state: << SSL_NEGOTIATION >> - negotiation ended and succeeded [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CN: chain index is 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CN: returning io.corp.ru [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CAHash: chain index is 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CAHash: returning 20 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: server_fingerprint = HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: server_cn = io.corp.ru, server_fingerprint = HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] snx_browser::VG: called with io.corp.ru and HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] snx_browser::VG: calling ext vg callback [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] snx_vg: called with io.corp.ru and HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SNX_UserDB_IsAcceptedCN: CN 'io.corp.ru' does not exist in DB [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] snx_vg: OK [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] talkssl::client_handler: calling the link_established cb [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] Established called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] ===snx_CCC_browser::send_auth_message=== [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] sending message [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] talkssl::send_data: Entering for 297 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_connbuf_realloc: reallocating 0 from 0 to 1321 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 297w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: sent 0 of 297 bytes == 297 bytes to send [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] ckpSSL_do_write: write 297 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: managed to send 297 of 297 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] talkssl::client_handler: after sending packet [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_do_read: read 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: managed to read 424 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: got 424 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: calling recv with dlen 424 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: got 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: return should retry rc = -1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: SSL should retry [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 424 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 424 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 424 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 424 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 424 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 424 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 191 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 191 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 191 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 191 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 191 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: complete message received [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: failed to parse server response. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx: quit. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::~snx_CCC_browser: called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] browser::~browser: called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::~talkssl: delete link [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::end_handler: ending connection [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Failure: entering with code: 2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] got link down!- exit [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::~talkssl: end [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] done [ 3255 -148723392]@localhost.localdomain[3 Aug 10:20:51] snx: starting debug - Tue Aug 3 10:20:51 2021 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] browser::browser(): called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] snx_CCC_browser::snx_CCC_browser: called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] snx_browser::auth: entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:00] gwinfo:gwinfo: entered!0x8698620 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] creating the ssl layer [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::talkssl(): entered with chunk=512, opaque=8698310, link_established=80ee120, link_failure=80ee100, packet_receive=80ee0d0, verify_gw=80ee140 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::set_sslalg: setting ssl alg to 2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSLctx_New: prefs = 1e [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSLctx_New: CKPSSL_ACCEPT_TLS1_2 is turned on + (CKPSSL_ACCEPT_TLSV1 | CKPSSL_ACCEPT_SSL3) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSLctx_New: choose SSLv23_method == the highest TLS version available -> should provide TLS 1.2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] is_initialized: new process or forked [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] The PRNG was not initialized properly [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] create_rand_mutex: failed to create mutex: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] rand_add_external_source: Failed to create mutex.: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] create_rand_mutex: failed to create mutex: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] rand_add_seedfile: Failed to create mutex.: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] create_rand_mutex: failed to create mutex: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwrand_write_seed: Failed to create mutex.: Operation not permitted [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] isExist: ProxyEntity didn't initiated yet [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::start_async: Creating a new connection [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::start_async: Connecting to gw: 0x0293b55f, port: 443 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_make_connection: 5fb59302/443: dowait is -1 sock is 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::start_async: Connection created successfully [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_conn_params: -> <5fb59302,443> [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: state: CONN_INIT - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: start ssl negotaition [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: start openSSL negotaition [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_PrepareConnection: verify mode: 0 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] My SSL Ciphers: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Cipher List: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 0: AES128-SHA SSLv3 Kx=RSA Au=RSA Enc=AES(128) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 1: AES256-SHA SSLv3 Kx=RSA Au=RSA Enc=AES(256) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 2: DES-CBC3-SHA SSLv3 Kx=RSA Au=RSA Enc=3DES(168) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 3: RC4-SHA SSLv3 Kx=RSA Au=RSA Enc=RC4(128) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 4: DES-CBC-SHA SSLv3 Kx=RSA Au=RSA Enc=DES(56) Mac=SHA1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: Returning OK!!! [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: current state = before/connect initialization [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: should retry. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: current state = SSLv2/v3 read server hello A [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_VerifyCallback: no params or params->key_holder [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: should retry. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: current state = SSLv3 read finished A [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_NegotiateStep: conncected, used TLSv1/SSLv3 ,AES128-SHA (-1) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_connected: peer authenticated [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_connected: current state: SSL negotiation finished successfully [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Certificate is: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] X509 Certificate Version 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Serial Number: 48dafbb504ba6c9ea9406444 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Issuer: CN=GlobalSign GCC R3 DV TLS CA 2020,O=GlobalSign nv-sa,C=BE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Subject: CN=io.corp.ru [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Not valid before: Fri Jul 2 15:37:02 2021 Local Time [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Not valid after: Wed Jul 27 09:50:13 2022 Local Time [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Extensions: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Extended Key Usage: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] serverAuth [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] clientAuth [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Subject Alternate names: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] DNS: io.corp.ru [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] CRL distribution Points: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] URI: http://crl.globalsign.com/gsgccr3dvtlsca2020.crl [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Basic Constraint: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] not CA [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Authority Info Access: [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwAuthorityInfoAccess_imp: Authority Info Access [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SEQUENCE/SET OF { [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SEQUENCE { [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessMethod [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] (1.3.6.1.5.5.7.48.2) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessLocation [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] uniformResourceIdentifier [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Octet String [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 68 74 74 70 3a 2f 2f 73 65 63 75 72 65 2e 67 6c 6f 62 61 6c [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 73 69 67 6e 2e 63 6f 6d 2f 63 61 63 65 72 74 2f 67 73 67 63 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 63 72 33 64 76 74 6c 73 63 61 32 30 32 30 2e 63 72 74 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] http://secure.global [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] sign.com/cacert/gsgc [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] cr3dvtlsca2020.crt [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] } [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SEQUENCE { [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessMethod [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] (1.3.6.1.5.5.7.48.1) [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] accessLocation [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] uniformResourceIdentifier [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Octet String [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 68 74 74 70 3a 2f 2f 6f 63 73 70 2e 67 6c 6f 62 61 6c 73 69 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 67 6e 2e 63 6f 6d 2f 67 73 67 63 63 72 33 64 76 74 6c 73 63 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] 61 32 30 32 30 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] http://ocsp.globalsi [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] gn.com/gsgccr3dvtlsc [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] a2020 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] } [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] } [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] Key Usage (Critical): [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] digitalSignature [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] keyEncipherment [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_connbuf_realloc: reallocating 0 from 0 to 1025 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_mux_in: 4: got 0 of 0 bytes == 0 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_mux_in: 4: managed to read 0 of 0 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] fwasync_mux_in: 4: call: 80f2060 with 2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: state: << SSL_NEGOTIATION >> - negotiation ended and succeeded [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CN: chain index is 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CN: returning io.corp.ru [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CAHash: chain index is 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] ckpSSL_get_CAHash: returning 20 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: server_fingerprint = HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] talkssl::client_handler: server_cn = io.corp.ru, server_fingerprint = HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] snx_browser::VG: called with io.corp.ru and HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] snx_browser::VG: calling ext vg callback [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] snx_vg: called with io.corp.ru and HUG BOO FLIT DRY AFAR EDGY FLUB GAP LEAF KNEE TEST LYLE [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:01] SNX_UserDB_IsAcceptedCN: CN 'io.corp.ru' does not exist in DB [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] snx_vg: OK [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] talkssl::client_handler: calling the link_established cb [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] Established called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] ===snx_CCC_browser::send_auth_message=== [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] sending message [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] talkssl::send_data: Entering for 297 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_connbuf_realloc: reallocating 0 from 0 to 1321 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 297w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: sent 0 of 297 bytes == 297 bytes to send [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] ckpSSL_do_write: write 297 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: managed to send 297 of 297 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] talkssl::client_handler: after sending packet [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:07] fwasync_mux_out: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] ckpSSL_do_read: read 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: managed to read 424 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: got 424 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] talkssl::client_handler: calling recv with dlen 424 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: got 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:12] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: return should retry rc = -1 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: SSL should retry [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 424 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 424 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 424 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 424 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 424 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 424 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 424 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 512 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 512 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 512 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: incomplete data [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: rc=1, next: 80f2060 with 3, req: 512r, 0w [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_InputPending 1 pending bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: got 0 of 512 bytes == 512 bytes required [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] ckpSSL_do_read: read 191 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: managed to read 191 of 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_mux_in: 4: call: 80f2060 with 3 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: state: SSL_RECV - entering [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: got 191 bytes, wanted 512 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] fwasync_conn_reset_read: 4 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::client_handler: calling recv with dlen 191 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] Receive started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: started [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: got 191 bytes [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::getMessageSize: header length is 310, content length found - 5245 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: message size should be = 5559 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: complete message received [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Receive: failed to parse server response. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx: quit. [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_CCC_browser::~snx_CCC_browser: called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] browser::~browser: called [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::~talkssl: delete link [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::end_handler: ending connection [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] snx_browser::Failure: entering with code: 2 [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] got link down!- exit [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] talkssl::~talkssl: end [ 3255 -148723392]@localhost.localdomain[3 Aug 10:21:13] done