TopicPublisher.exe tcps://hk-np1.fs-solace.dev.net:55443 app-test-user@vpn-poc-d1 app-test-user User Nameapp-test-user VPN Nmaevpn-poc-d1 Host tcps://hk-np1.fs-solace.dev.net:55443 Password app-test-user Info - Sdk: 12:29:32 PM | solClient.c:9363 (000041d4) solClient_initialize called, init count now 0 | null Info - Sdk: 12:29:32 PM | solClientGenReleaseVersion.c:82 (000041d4) solClient version '7.11.0.8', variant 'Win64_opt - C SDK', date/time 'Aug 7 2019 16:52:27' | null Debug - Sdk: 12:29:32 PM | solClient.c:9454 (000041d4) User info: Support1, Computer: 'HKWVAUAPP058', ProcessId: 14624 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:4843 (000041d4) _solClient_getAddrInfo called for 127.0.0.1 | null Debug - Sdk: 12:29:32 PM | solClientGenReleaseVersion.c:100 (000041d4) solClient_version_get called | null Info - Sdk: 12:29:32 PM | solClientGenReleaseVersion.c:147 (000041d4) _solClient_version_set called | null Info - Sdk: 12:29:32 PM | solClientGenReleaseVersion.c:171 (000041d4) solClient new info: version '.NET API 10.6.0 / C API 7.11.0.8', variant '.NET API / Win64_opt - C SDK', date/time '.NET API Aug 07 2019 17:24:00 / C API Aug 7 2019 16:52:27' | null Debug - Sdk: 12:29:32 PM | solClient.c:9259 (000041d4) Allocated opaque handle '0000000004000FFF', actual ptr '000001C789324100' for type 1 | null Info - Sdk: 12:29:32 PM | solClient.c:10530 (000041d4) solClient_context_create called, context 0 being created | null Info - Sdk: 12:29:32 PM | solClient.c:8023 (000041d4) Setting context 0 property 'CONTEXT_TIME_RES_MS', value '50' | null Info - Sdk: 12:29:32 PM | solClient.c:8023 (000041d4) Setting context 0 property 'CONTEXT_CREATE_THREAD', value '1' | null Info - Sdk: 12:29:32 PM | solClient.c:8023 (000041d4) Setting context 0 property 'CONTEXT_INTER_THREAD_PROTOCOL', value 'TCP' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6701 (000041d4) _solClient_createEventProcInfo is called in context 0, regFdFunc_p=0000000000000000, unregFdFunc_p=0000000000000000 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6735 (000041d4) Allocated 16 context fd events | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6596 (000041d4) Creating inter-thread CMD pipe, TCP protocol, in context 0 | null Info - Sdk: 12:29:32 PM | solClientOS.c:4060 (000041d4) _solClient_setNonBlocking (fd 772, enable = 1) | null Info - Sdk: 12:29:32 PM | solClientOS.c:4060 (000041d4) _solClient_setNonBlocking (fd 776, enable = 1) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3773 (000041d4) Enabling TCP_NODELAY on fd 776 | null Info - Sdk: 12:29:32 PM | solClientOS.c:4060 (000041d4) _solClient_setNonBlocking (fd 792, enable = 1) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6679 (000041d4) Created inter-thread CMD pipe, TCP protocol, read fd 792, write fd 776, read port 54633 in context 0 | null Info - Sdk: 12:29:32 PM | solClientOS.c:8629 (00005abc) Entering internal context thread 00005abc for context 0 | null Before Run Method before X509 certificate after X509 certificate Connecting as app-test-user@vpn-poc-d1 on tcps://hk-np1.fs-solace.dev.net:55443... Debug - Sdk: 12:29:32 PM | solClient.c:9259 (000041d4) Allocated opaque handle '0000000004000FFE', actual ptr '000001C7A1C20650' for type 2 | null Info - Sdk: 12:29:32 PM | solClientSession.c:3097 (000041d4) solClient_session_create called, session 1 being created in context 0 | null Info - Sdk: 12:29:32 PM | solClientTransport.c:57 (000041d4) _solClient_channel_setTransport(transData_p='000001C7A1C206D8', name='TCP' | null Info - Sdk: 12:29:32 PM | solClientTransport.c:57 (000041d4) _solClient_channel_setTransport(transData_p='000001C7A1C21020', name='TCP' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_USERNAME', value 'app-test-user' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_PASSWORD', value '' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_VPN_NAME', value 'vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CLIENT_NAME', value '' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_HOST', value 'tcps://hk-np1.fs-solace.dev.net:55443' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CONNECT_BLOCKING', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SEND_BLOCKING', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SUBSCRIBE_BLOCKING', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_BUFFER_SIZE', value '90000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_WRITE_TIMEOUT_MS', value '100000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CONNECT_TIMEOUT_MS', value '30000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_IGNORE_DUP_SUBSCRIPTION_ERROR', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_TCP_NODELAY', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SOCKET_SEND_BUF_SIZE', value '90000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SOCKET_RCV_BUF_SIZE', value '150000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_KEEP_ALIVE_INTERVAL_MS', value '3000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_KEEP_ALIVE_LIMIT', value '3' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_APPLICATION_DESCRIPTION', value '' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CLIENT_MODE', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_BIND_IP', value '' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_PUB_ACK_TIMER', value '2000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_PUB_WINDOW_SIZE', value '50' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_MAX_FLOWS', value '100' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_BLOCK_WHILE_CONNECTING', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SUBCONFIRM_TIMEOUT_MS', value '10000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SUBSCRIBER_LOCAL_PRIORITY', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SUBSCRIBER_NETWORK_PRIORITY', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_COMPRESSION_LEVEL', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_REAPPLY_SUBSCRIPTIONS', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_RCV_TIMESTAMP', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SEND_TIMESTAMP', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SEND_SENDER_ID', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SEND_SEQUENCE_NUMBER', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CONNECT_RETRIES', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_RECONNECT_RETRIES', value '3' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_RECONNECT_RETRY_WAIT_MS', value '3000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CONNECT_RETRIES_PER_HOST', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_PROVISION_TIMEOUT_MS', value '3000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_TOPIC_DISPATCH', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_CALCULATE_MESSAGE_EXPIRATION', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_NO_LOCAL', value '0' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_MODIFYPROP_TIMEOUT_MS', value '10000' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_ACK_EVENT_MODE', value 'SESSION_ACK_EVENT_MODE_PER_MSG' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SSL_EXCLUDED_PROTOCOLS', value '' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SSL_VALIDATE_CERTIFICATE', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SSL_VALIDATE_CERTIFICATE_DATE', value '1' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SSL_CIPHER_SUITES', value 'ECDHE-RSA-AES256-GCM-SHA384,ECDHE-RSA-AES256-SHA384,ECDHE-RSA-AES256-SHA,AES256-GCM-SHA384,AES256-SHA256,AES256-SHA,ECDHE-RSA-DES-CBC3-SHA,DES-CBC3-SHA,ECDHE-RSA-AES128-GCM-SHA256,ECDHE-RSA-AES128-SHA256,ECDHE-RSA-AES128-SHA,AES128-GCM-SHA256,AES128-SHA256,AES128-SHA,RC4-SHA,RC4-MD5' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_SSL_TRUST_STORE_DIR', value 'D:\Miles\Bhavika\SolacePOC\certificates' | null Info - Sdk: 12:29:32 PM | solClientSession.c:1262 (000041d4) Setting session '(c0,s1)' property 'SESSION_AUTHENTICATION_SCHEME', value 'AUTHENTICATION_SCHEME_BASIC' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:2131 (000041d4) _solClient_ssl_ASN1Data_deepCopy copying 1707 bytes from '000001C7A1E12090' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:2131 (000041d4) _solClient_ssl_ASN1Data_deepCopy copying 1191 bytes from '000001C787A028B0' | null Debug - Sdk: 12:29:32 PM | solClient.c:6744 (000041d4) _solClient_defaultP2PTopic set to #P2P/HKWVAUAPP058/HKWVAUAPP058/14624/#00000001/clcgmMI5mN for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientSubscription.c:7836 (000041d4) New P2P base topic is '#P2P/HKWVAUAPP058/HKWVAUAPP058/14624/#00000001/clcgmMI5mN', old is '' for session '(c0,s1)_vpn-poc-d1' in _solClient_subscriptionStorage_setP2PTopic | null Debug - Sdk: 12:29:32 PM | solClientOS.c:4843 (000041d4) _solClient_getAddrInfo called for hk-np1.fs-solace.dev.net | null Debug - Sdk: 12:29:32 PM | solClientSubscription.c:138 (000041d4) Subscription re-apply is disabled for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClient.c:9259 (000041d4) Allocated opaque handle '0000000004000FFD', actual ptr '000001C7A1C27E50' for type 5 | null Debug - Sdk: 12:29:32 PM | solClientMsg.c:701 (000041d4) Allocated dataBlock '000001C789328BB0', size 10240, for requested size 268 | null Info - Sdk: 12:29:32 PM | solClientSubscription.c:8088 (000041d4) _solClient_session_registerForTopicDispatchDestroy called for session '(c0,s1)_vpn-poc-d1', callback ptr '000001C7A1D0099C' | null Info - Sdk: 12:29:32 PM | solClient.c:12555 (000041d4) solClient_session_connect called for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:7647 (000041d4) Sending opcode SESSION_FUNC (1), size 40, confirmed, to context 0 thread from solClient_session_connect | null Debug - Sdk: 12:29:32 PM | solClientCondition.c:474 (000041d4) solClient_session_connect for context 0, blocking calling thread while wait for context cmd to complete, num other waiters = 0 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3445 (000041d4) _solClient_condTimedWait(waiters_count=0, currentTimeInUs=1579696172676413, absExpTimeInUs=1579696182676413) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:8043 (00005abc) Scanning callbackInfo setFds = 1 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:569 (00005abc) Invoking context 0 fd callback '00007FFD385F7870', fd 792, events 1, user data '00007FFD38696CC0' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:7552 (00005abc) Context 0 woke up on inter-thread event, fd 792 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6937 (00005abc) Processing command SESSION_FUNC (1) size 40 from _solClient_InterThreadCmdPipe_cb | null Debug - Sdk: 12:29:32 PM | solClient.c:11216 (00005abc) session '(c0,s1)_vpn-poc-d1' cur host index = -1, num hosts = 1, connect attempt countdown = 1 | null Info - Sdk: 12:29:32 PM | solClient.c:11018 (00005abc) _solClient_cleanUpConnection, session '(c0,s1)_vpn-poc-d1', new session state is CONNECTING, flag 1 | null Debug - Sdk: 12:29:32 PM | solClientCondition.c:412 (00005abc) _solClient_cleanUpConnection for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for assured delivery send window not open | null Debug - Sdk: 12:29:32 PM | solClientCondition.c:412 (00005abc) _solClient_cleanUpConnection for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for can send to socket | null Debug - Sdk: 12:29:32 PM | solClientSubscription.c:7989 (00005abc) Removing source routing subscriptions for session '(c0,s1)_vpn-poc-d1', last session = 0 | null Debug - Sdk: 12:29:32 PM | solClientSubscription.c:3726 (00005abc) _solClient_subscriptionStorage_removeExactForSession removed 0 callbacks and 0 subscriptions for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientSubscription.c:3932 (00005abc) _solClient_subscriptionStorage_removeWildcardForSession removed 0 callbacks and 0 subscriptions for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientSocket.c:612 (00005abc) _solClient_socket_close 'TCP': fd = -1 | null Info - Sdk: 12:29:32 PM | solClientSocket.c:612 (00005abc) _solClient_socket_close 'TCP': fd = -1 | null Info - Sdk: 12:29:32 PM | solClient.c:2968 (00005abc) Attempting to connect session '(c0,s1)_vpn-poc-d1' to host 'tcps://hk-np1.fs-solace.dev.net:55443', hostname 'hk-np1.fs-solace.dev.net:55443' IP 10.23.142.125:55443 (host 1 of 1) (host connection attempt 1 of 1) (total connection attempt 1 of 1) | null Debug - Sdk: 12:29:32 PM | solClient.c:6038 (00005abc) Connect channel (tcps_TxRx), current transport TCP, final transport TCP for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientTransport.c:57 (00005abc) _solClient_channel_setTransport(transData_p='000001C7A1C206D8', name='TCP' | null Info - Sdk: 12:29:32 PM | solClientTransport.c:111 (00005abc) _solClient_channel_addTransport(transport_p='000001C789321300:TCP', transData_p='000001C7A1C20E78', name='SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:814 (00005abc) Doing global SSL initialization due to session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientSSL.c:872 (00005abc) Dynamically loaded D:\Miles\Bhavika\Queue\libssl-1_1.dll and D:\Miles\Bhavika\Queue\libcrypto-1_1.dll | null Info - Sdk: 12:29:32 PM | solClientSSL.c:878 (00005abc) SSL Using: OpenSSL 1.1.1c 28 May 2019 | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1059 (00005abc) create SSL context for session '(c0,s1)_vpn-poc-d1' | null Notice - Sdk: 12:29:32 PM | solClientSSL.c:1194 (00005abc) Session '(c0,s1)_vpn-poc-d1': failed to load the certificate file 'D:\Miles\Bhavika\SolacePOC\certificates\app-test-user.pfx', SSLerr:'no certificate or crl found'(0x0b084088) | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1222 (00005abc) Session '(c0,s1)_vpn-poc-d1': _solClient_ssl_createContext the certificate file 'D:\Miles\Bhavika\SolacePOC\certificates\scb-uat-Intermediate-ca-sha2.cer' loaded | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1222 (00005abc) Session '(c0,s1)_vpn-poc-d1': _solClient_ssl_createContext the certificate file 'D:\Miles\Bhavika\SolacePOC\certificates\scb-uat-root-ca-sha2.cer' loaded | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1258 (00005abc) SSL context: 2 objects | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1263 (00005abc) X509 object type 1 | null Notice - Sdk: 12:29:32 PM | solClientSSL.c:1274 (00005abc) X509 cert: subject: /C=GB/O=Standard Chartered Bank/CN=Standard Chartered Bank UAT Root CA G1 - SHA2; issuer: /C=GB/O=Standard Chartered Bank/CN=Standard Chartered Bank UAT Root CA G1 - SHA2 | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1263 (00005abc) X509 object type 1 | null Notice - Sdk: 12:29:32 PM | solClientSSL.c:1274 (00005abc) X509 cert: subject: /C=GB/O=Standard Chartered Bank/CN=Standard Chartered Bank UAT SSL CA G1 - SHA2; issuer: /C=GB/O=Standard Chartered Bank/CN=Standard Chartered Bank UAT Root CA G1 - SHA2 | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1629 (00005abc) Hostname for SNI: hk-np1.fs-solace.dev.ne | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'TLS_AES_256_GCM_SHA384' priority 0 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'TLS_CHACHA20_POLY1305_SHA256' priority 1 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'TLS_AES_128_GCM_SHA256' priority 2 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'ECDHE-RSA-AES256-GCM-SHA384' priority 3 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'ECDHE-RSA-AES256-SHA384' priority 4 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'ECDHE-RSA-AES256-SHA' priority 5 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'AES256-GCM-SHA384' priority 6 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'AES256-SHA256' priority 7 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'AES256-SHA' priority 8 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'ECDHE-RSA-AES128-GCM-SHA256' priority 9 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'ECDHE-RSA-AES128-SHA256' priority 10 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'ECDHE-RSA-AES128-SHA' priority 11 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'AES128-GCM-SHA256' priority 12 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1596 (00005abc) SSL cipher 'AES128-SHA256' priority 13 for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Info - Sdk: 12:29:32 PM | solClientSSL.c:2542 (00005abc) _solClient_ssl_open ('tcps://hk-np1.fs-solace.dev.net:55443') on '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientSocket.c:451 (00005abc) _solClient_socket_open 'TCP': session = '000001C7A1C20650' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:4843 (00005abc) _solClient_getAddrInfo called for hk-np1.fs-solace.dev.net:55443 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:4232 (00005abc) Creating socket for family 2 | null Info - Sdk: 12:29:32 PM | solClientOS.c:4060 (00005abc) _solClient_setNonBlocking (fd 1436, enable = 1) | null Info - Sdk: 12:29:32 PM | solClientSocket.c:99 (00005abc) Session '(c0,s1)_vpn-poc-d1', fd 1436 allocated for connect to 'hostname 'hk-np1.fs-solace.dev.net:55443' IP 10.23.142.125:5544' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3773 (00005abc) Enabling TCP_NODELAY on fd 1436 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3851 (00005abc) Changing SO_SNDBUF socket option from 65536 to 90000 on fd 1436 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3901 (00005abc) SO_SNDBUF socket option set to 90000 on fd 1436 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3929 (00005abc) Setting SO_RCVBUF socket option to 150000 from 65536 on fd 1436 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3978 (00005abc) SO_RCVBUF socket option set to 150000 on fd 1436 | null Info - Sdk: 12:29:32 PM | solClientSocket.c:136 (00005abc) Local connection address 'IP 0.0.0.0:54642' for fd 1436, session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:29:32 PM | solClientSocket.c:685 (00005abc) _solClient_socket_registerForFdEvents, fd 1436, events 2, callback '00007FFD3864EE00', user data '000001C7A1C20650' for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6937 (00005abc) Processing command FD_EVENT_REG (3) size 56 from _solClient_context_registerForFdEvents | null Debug - Sdk: 12:29:32 PM | solClientOS.c:253 (00005abc) _solClient_callbackInfo_findOrAllocate(): fd=1436 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:211 (00005abc) _solClient_callbackInfo_allocate(): allocated index 1 (fd=1436) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:7030 (00005abc) Register events 2, fd 1436 events now 0, status 1 in context 0 | null Debug - Sdk: 12:29:32 PM | solClient.c:11334 (00005abc) Waiting 30000 ms while connecting to next host for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientTimer.c:374 (00005abc) solClient_context_startTimer called for context 0, timer mode = 0, duration = 30000 ms | null Debug - Sdk: 12:29:32 PM | solClientTimer.c:97 (00005abc) Context 0 is allocating 16 more timers, new timer count is 16 | null Debug - Sdk: 12:29:32 PM | solClientTimer.c:158 (00005abc) solClient_context_startTimer: timer id 0, mode one-shot, duration 600 ticks, expiring at tick time 611 (current tick time 8) in context 0 | null Debug - Sdk: 12:29:32 PM | solClientCondition.c:412 (00005abc) _solClient_executeInterThreadCmd for context 0, unblocking other waiters (1) for wait for context cmd to complete | null Debug - Sdk: 12:29:32 PM | solClientOS.c:8043 (00005abc) Scanning callbackInfo setFds = 1 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:569 (00005abc) Invoking context 0 fd callback '00007FFD3864BCB0', fd 1436, events 2, user data '000001C789321300' | null Debug - Sdk: 12:29:32 PM | solClientSocket.c:203 (00005abc) socketFdCallback on fd=1436, events=2, transport_p=000001C789321300, name='TCP' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:2262 (00005abc) sslFdCallback: Fd 1436 event(s) 2 received in SSL state SSL_TRANSPORT_OPEN | null Info - Sdk: 12:29:32 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd 1436, events 2 for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:237 (00005abc) _solClient_callbackInfo_find(): found index 1 (fd=1436) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:7871 (00005abc) Removing events 0x2 from fd 1436's events of 0x2 in context 0 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6937 (00005abc) Processing command FD_EVENT_UNREG (4) size 56 from _solClient_socket_unregisterForFdEvents | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3487 (000041d4) _solClient_condTimedWait returned from wait (waiters_count=1, release_count=1 currentTimeInUs=1579696172676413, absExpTimeInUs=1579696182676413) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:237 (00005abc) _solClient_callbackInfo_find(): found index 1 (fd=1436) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:7113 (00005abc) Unregister events 2, fd 1436 events now 0 in context 0 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:283 (00005abc) replacing callback index 1 (fd=1436) with 1 (fd=1436) | null Debug - Sdk: 12:29:32 PM | solClientCondition.c:474 (000041d4) solClient_session_connect for session '(c0,s1)_vpn-poc-d1', blocking calling thread while connection in progress, num other waiters = 0 | null Info - Sdk: 12:29:32 PM | solClientSSL.c:2298 (00005abc) Starting SSL establishment as the client for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:3445 (000041d4) _solClient_condTimedWait(waiters_count=0, currentTimeInUs=1579696172812453, absExpTimeInUs=1579696235812453) | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1546 (00005abc) SSL callback, state 'before SSL initialization', handshake start (where 0x10, ret 1) for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1489 (00005abc) SSL callback, state 'before SSL initialization', connect (where 0x1001, ret 1) for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1489 (00005abc) SSL callback, state 'SSLv3/TLS write client hello', connect (where 0x1001, ret 1) for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientSSL.c:1526 (00005abc) SSL callback, state 'SSLv3/TLS write client hello', connect error (where 0x1002, ret:err -1:2) for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientErrorStore.c:521 (00005abc) solClient_getLastErrorInfo called, threadData_p = '000001C7A1C2C6C0' | null Info - Sdk: 12:29:32 PM | solClientSSL.c:1826 (00005abc) SSL client establishment state 'SSLv3/TLS write client hello', err 2 (ret -1) for session '(c0,s1)_vpn-poc-d1', connection 'SSL-client' | null Info - Sdk: 12:29:32 PM | solClientSocket.c:685 (00005abc) _solClient_socket_registerForFdEvents, fd 1436, events 1, callback '00007FFD3864EE00', user data '000001C7A1C20650' for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:29:32 PM | solClientOS.c:6937 (00005abc) Processing command FD_EVENT_REG (3) size 56 from _solClient_context_registerForFdEvents | null Debug - Sdk: 12:29:32 PM | solClientOS.c:253 (00005abc) _solClient_callbackInfo_findOrAllocate(): fd=1436 | null Debug - Sdk: 12:29:32 PM | solClientOS.c:211 (00005abc) _solClient_callbackInfo_allocate(): allocated index 1 (fd=1436) | null Debug - Sdk: 12:29:32 PM | solClientOS.c:7030 (00005abc) Register events 1, fd 1436 events now 0, status 1 in context 0 | null Debug - Sdk: 12:30:02 PM | solClientTimer.c:800 (00005abc) Invoking context 0 timer expiry callback '00007FFD385E62D0' for timer id 0, user data '000001C7A1C20650' | null Notice - Sdk: 12:30:02 PM | solClient.c:12211 (00005abc) Connect attempt for host 'tcps://hk-np1.fs-solace.dev.net:55443' for session '(c0,s1)_vpn-poc-d1(client name 'HKWVAUAPP058/14624/#00000001/clcgmMI5mN', VPN name 'vpn-poc-d1', peer address 'hostname 'hk-np1.fs-solace.dev.net:55443' IP 10.23.142.125:5544', connection 'tcps_TxRx' local address 'IP 10.23.205.237:54642')' timed out | null Debug - Sdk: 12:30:02 PM | solClientErrorStore.c:521 (00005abc) solClient_getLastErrorInfo called, threadData_p = '000001C7A1C2C6C0' | null Info - Sdk: 12:30:02 PM | solClient.c:3706 (00005abc) _solClient_updateSessionState for session '(c0,s1)_vpn-poc-d1', current session state = CONNECTING, tcps_TxRx state = FAILED | null Debug - Sdk: 12:30:02 PM | solClient.c:11216 (00005abc) session '(c0,s1)_vpn-poc-d1' cur host index = 0, num hosts = 1, connect attempt countdown = 1 | null Info - Sdk: 12:30:02 PM | solClient.c:11018 (00005abc) _solClient_cleanUpConnection, session '(c0,s1)_vpn-poc-d1', new session state is FAILED, flag 1 | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_cleanUpConnection for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for assured delivery send window not open | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_cleanUpConnection for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for can send to socket | null Debug - Sdk: 12:30:02 PM | solClientSubscription.c:7989 (00005abc) Removing source routing subscriptions for session '(c0,s1)_vpn-poc-d1', last session = 0 | null Debug - Sdk: 12:30:02 PM | solClientSubscription.c:3726 (00005abc) _solClient_subscriptionStorage_removeExactForSession removed 0 callbacks and 0 subscriptions for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientSubscription.c:3932 (00005abc) _solClient_subscriptionStorage_removeWildcardForSession removed 0 callbacks and 0 subscriptions for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd 1436, events 3 for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:237 (00005abc) _solClient_callbackInfo_find(): found index 1 (fd=1436) | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7871 (00005abc) Removing events 0x3 from fd 1436's events of 0x1 in context 0 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6937 (00005abc) Processing command FD_EVENT_UNREG (4) size 56 from _solClient_socket_unregisterForFdEvents | null Debug - Sdk: 12:30:02 PM | solClientOS.c:237 (00005abc) _solClient_callbackInfo_find(): found index 1 (fd=1436) | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7113 (00005abc) Unregister events 3, fd 1436 events now 0 in context 0 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:283 (00005abc) replacing callback index 1 (fd=1436) with 1 (fd=1436) | null Info - Sdk: 12:30:02 PM | solClientSSL.c:2621 (00005abc) _solClient_ssl_close on '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd 1436, events 3 for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6937 (00005abc) Processing command FD_EVENT_UNREG (4) size 56 from _solClient_socket_unregisterForFdEvents | null Info - Sdk: 12:30:02 PM | solClientSocket.c:612 (00005abc) _solClient_socket_close 'TCP': fd = 1436 | null Debug - Sdk: 12:30:02 PM | solClientSSL.c:2655 (00005abc) _solClient_ssl_close complete on '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:612 (00005abc) _solClient_socket_close 'TCP': fd = -1 | null Debug - Sdk: 12:30:02 PM | solClientErrorStore.c:521 (00005abc) solClient_getLastErrorInfo called, threadData_p = '000001C7A1C2C6C0' | null Info - Sdk: 12:30:02 PM | solClient.c:2101 (00005abc) Invoking session '(c0,s1)_vpn-poc-d1' event callback '000001C7A1D008FC' for event Session connection attempt failed (2), response code 0, info 'Session connect timeout', user data '0000000000000000' | null Debug - Sdk: 12:30:02 PM | solClientErrorStore.c:521 (00005abc) solClient_getLastErrorInfo called, threadData_p = '000001C7A1C2C6C0' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6937 (00005abc) Processing command SESSION_EVENTCALLBACK (6) size 336 from _solClient_sendSessionEvent | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_updateSessionState for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (1) for connection in progress | null Debug - Sdk: 12:30:02 PM | solClientOS.c:3487 (000041d4) _solClient_condTimedWait returned from wait (waiters_count=1, release_count=1 currentTimeInUs=1579696172812453, absExpTimeInUs=1579696235812453) | null Info - Sdk: 12:30:02 PM | solClient.c:12659 (000041d4) solClient_session_disconnect called for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7647 (000041d4) Sending opcode SESSION_FUNC (1), size 40, confirmed, to context 0 thread from solClient_session_disconnect | null Debug - Sdk: 12:30:02 PM | solClientOS.c:8043 (00005abc) Scanning callbackInfo setFds = 1 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:569 (00005abc) Invoking context 0 fd callback '00007FFD385F7870', fd 792, events 1, user data '00007FFD38696CC0' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7552 (00005abc) Context 0 woke up on inter-thread event, fd 792 | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:474 (000041d4) solClient_session_disconnect for context 0, blocking calling thread while wait for context cmd to complete, num other waiters = 0 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:3445 (000041d4) _solClient_condTimedWait(waiters_count=0, currentTimeInUs=1579696202809363, absExpTimeInUs=1579696212803345) | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6937 (00005abc) Processing command SESSION_FUNC (1) size 40 from _solClient_InterThreadCmdPipe_cb | null Debug - Sdk: 12:30:02 PM | solClientFlow.c:4330 (00005abc) Flow session disconnect for session '(c0,s1)_vpn-poc-d1', unbound=0000000000000000 | null Info - Sdk: 12:30:02 PM | solClient.c:11018 (00005abc) _solClient_cleanUpConnection, session '(c0,s1)_vpn-poc-d1', new session state is DISCONNECTING, flag 1 | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_cleanUpConnection for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for assured delivery send window not open | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_cleanUpConnection for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for can send to socket | null Debug - Sdk: 12:30:02 PM | solClientSubscription.c:7989 (00005abc) Removing source routing subscriptions for session '(c0,s1)_vpn-poc-d1', last session = 0 | null Debug - Sdk: 12:30:02 PM | solClientSubscription.c:3726 (00005abc) _solClient_subscriptionStorage_removeExactForSession removed 0 callbacks and 0 subscriptions for session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientSubscription.c:3932 (00005abc) _solClient_subscriptionStorage_removeWildcardForSession removed 0 callbacks and 0 subscriptions for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSSL.c:2621 (00005abc) _solClient_ssl_close on '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientSSL.c:2655 (00005abc) _solClient_ssl_close complete on '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:612 (00005abc) _solClient_socket_close 'TCP': fd = -1 | null Debug - Sdk: 12:30:02 PM | solClient.c:2232 (00005abc) Session '(c0,s1)_vpn-poc-d1', unblocking all blocked threads due to solClient_session_disconnect | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_unblockAllThreads for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for connection in progress | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_unblockAllThreads for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for can send to socket | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_unblockAllThreads for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for can send to socket | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_unblockAllThreads for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for assured delivery send window not open | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_unblockAllThreads for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for cache request create | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_unblockAllThreads for session '(c0,s1)_vpn-poc-d1', unblocking other waiters (0) for (un)subscribe window or response | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_executeInterThreadCmd for context 0, unblocking other waiters (1) for wait for context cmd to complete | null Debug - Sdk: 12:30:02 PM | solClientOS.c:3487 (000041d4) _solClient_condTimedWait returned from wait (waiters_count=1, release_count=1 currentTimeInUs=1579696202809363, absExpTimeInUs=1579696212803345) | null Debug - Sdk: 12:30:02 PM | solClient.c:12621 (000041d4) solClient_session_connect for session '(c0,s1)_vpn-poc-d1', connection state is IDLE | null Warning - SessionImpl: 12:30:02 PM | Failed to connect session ReturnCode is: SOLCLIENT_NOT_READY | null Debug - Sdk: 12:30:02 PM | solClientErrorStore.c:521 (000041d4) solClient_getLastErrorInfo called, threadData_p = '000001C7A1C20380' | null Info - Sdk: 12:30:02 PM | solClient.c:10960 (000041d4) solClient_session_destroy called, session '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7647 (000041d4) Sending opcode SESSION_FUNC (1), size 40, confirmed, to context 0 thread from solClient_session_destroy | null Debug - Sdk: 12:30:02 PM | solClientOS.c:8043 (00005abc) Scanning callbackInfo setFds = 1 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:569 (00005abc) Invoking context 0 fd callback '00007FFD385F7870', fd 792, events 1, user data '00007FFD38696CC0' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7552 (00005abc) Context 0 woke up on inter-thread event, fd 792 | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:474 (000041d4) solClient_session_destroy for context 0, blocking calling thread while wait for context cmd to complete, num other waiters = 0 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:3445 (000041d4) _solClient_condTimedWait(waiters_count=0, currentTimeInUs=1579696202823362, absExpTimeInUs=1579696212823362) | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6937 (00005abc) Processing command SESSION_FUNC (1) size 40 from _solClient_InterThreadCmdPipe_cb | null Info - Sdk: 12:30:02 PM | solClient.c:10025 (00005abc) _solClient_doSessionDestroy for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSocket.c:764 (00005abc) _solClient_socket_unregisterForFdEvents, fd -1, events 3 for session '(c0,s1)_vpn-poc-d1' | null Info - Sdk: 12:30:02 PM | solClientSSL.c:2621 (00005abc) _solClient_ssl_close on '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientSSL.c:2655 (00005abc) _solClient_ssl_close complete on '(c0,s1)_vpn-poc-d1' | null Debug - Sdk: 12:30:02 PM | solClientSmf.c:6462 (00005abc) ClientdataParser: _solClient_cleanUpParse, ref count adj 999999999, prev adj 999999999, buffer '000001C789328BB0' ref count 1000000000 | null Debug - Sdk: 12:30:02 PM | solClient.c:9296 (00005abc) Freed opaque handle '0000000004000FFD' | null Debug - Sdk: 12:30:02 PM | solClientMsg.c:930 (00005abc) datablock_free '000001C789328BB0', refcount=1 C:/jenkins/slave/workspace/ccsmp_tag_win7-64/ccsmp/impl/solClientMsg.c:930 | null Info - Sdk: 12:30:02 PM | solClientSocket.c:612 (00005abc) _solClient_socket_close 'TCP': fd = -1 | null Debug - Sdk: 12:30:02 PM | solClient.c:10230 (00005abc) _solClient_doSessionDestroy '(c0,s1)_vpn-poc-d1' cleanup condata complete | null Debug - Sdk: 12:30:02 PM | solClient.c:9296 (00005abc) Freed opaque handle '0000000004000FFE' | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_session_destroyImpl for zeroSessionCondition, context 0, unblocking other waiters (0) for wait for zero session | null Debug - Sdk: 12:30:02 PM | solClient.c:10396 (00005abc) _solClient_doSessionDestroy '(c0,s1)_vpn-poc-d1' complete | null Debug - Sdk: 12:30:02 PM | solClientCondition.c:412 (00005abc) _solClient_executeInterThreadCmd for context 0, unblocking other waiters (1) for wait for context cmd to complete | null Debug - Sdk: 12:30:02 PM | solClientOS.c:3487 (000041d4) _solClient_condTimedWait returned from wait (waiters_count=1, release_count=1 currentTimeInUs=1579696202823362, absExpTimeInUs=1579696212823362) | null Error: Failed to connect session Info - Sdk: 12:30:02 PM | solClient.c:10653 (000041d4) solClient_context_destroy called, context 0 | null Info - Sdk: 12:30:02 PM | solClientSSL.c:1354 (000041d4) Doing context SSL cleanup for context number 0 | null Debug - Sdk: 12:30:02 PM | solClient.c:10779 (000041d4) solClient_context_destroy for context 0 (context_p->sessionCount = 0) stopping context thread | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7647 (000041d4) Sending opcode WAKEUP (0), size 24, not confirmed, to context 0 thread from solClient_context_destroy | null Debug - Sdk: 12:30:02 PM | solClientOS.c:8043 (00005abc) Scanning callbackInfo setFds = 1 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:569 (00005abc) Invoking context 0 fd callback '00007FFD385F7870', fd 792, events 1, user data '00007FFD38696CC0' | null Debug - Sdk: 12:30:02 PM | solClientOS.c:7552 (00005abc) Context 0 woke up on inter-thread event, fd 792 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6937 (00005abc) Processing command WAKEUP (0) size 24 from _solClient_InterThreadCmdPipe_cb | null Info - Sdk: 12:30:02 PM | solClientOS.c:8653 (00005abc) Exiting internal context thread 00005abc for context 0 | null Debug - Sdk: 12:30:02 PM | solClientOS.c:8601 (000041d4) _solClient_stopThread on threadID '00000000' complete | null Debug - Sdk: 12:30:02 PM | solClient.c:10794 (000041d4) solClient_context_destroy for context 0 checking sessionCount (0) is zero | null Debug - Sdk: 12:30:02 PM | solClientOS.c:6844 (000041d4) _solClient_destroyEventProcInfo is called in context 0, sessionCount=0, regFdFunc_p=0000000000000000, unregFdFunc_p=0000000000000000 | null Debug - Sdk: 12:30:02 PM | solClient.c:9296 (000041d4) Freed opaque handle '0000000004000FFF' | null Info - Sdk: 12:30:02 PM | solClient.c:9533 (000041d4) solClient_cleanup called, initcalled was 1 | null Info - Sdk: 12:30:02 PM | solClient.c:9565 (000041d4) solClient_cleanup started, initCount was 1 | null Finished.