IBM Support

SBI HTTP Client Post service fails with HTTP Reason Phrase: Internal Error: Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE.

Troubleshooting


Problem

Upgraded to (SBI) Sterling B2B Integrator version 5.2.4.2 and BP HTTP Client Post service fails with HTTP Reason Phrase: Internal Error: Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE. Customer moved BP from SBI 5.0 to 5.2.4.2 base instance. CA certificates were added to the CA repository and the new CA CertificateID updated in the BP for this Secure HTTP session.

Symptom

HTTP Client Post Service Status Report post the following error:
Name: OB_Send_HTTPS Instance ID: 2511 Service Name: HTTP Client Post Service
Status Report:
HTTPClientPostService resumed with error.
HTTPClientAdapter [HTTPClientAdapter]
ServiceStartTime [2014-10-02 10:25:36.534]
ServiceEndTime [2014-10-02 10:25:37.625]
SessionToken [HTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200

HTTP Status Code: -1
HTTP Reason Phrase: Internal Error: Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE

SBI HTTP Client log at time of error:


HTTP Client log:
[2014-10-02 10:25:36.796] DEBUG <-1768980930> CustomConnectAgent.setupSSLConduit() - Starting ...
[2014-10-02 10:25:36.797] DEBUG <-1768980930> CustomConnectAgent.setupSSLConduit() - Found CA Certificate Id from session [bouxsbi02:node1:148ae638e9a:4847]
[2014-10-02 10:25:36.808] DEBUG <-1768980930> CustomConnectAgent.setupSSLConduit() - Found SSL CA system certificate from session.
[2014-10-02 10:25:36.808] DEBUG <-1768980930> CustomConnectAgent.setupSSLConduit() - Found cipher strength from session [ALL]
[2014-10-02 10:25:36.808] DEBUG <-1768980930> CustomConnectAgent.setupSSLConduit() - Found client Key Certificate Id from session [null]
[2014-10-02 10:25:36.872] DEBUG <-1768980930> CustomConnectAgent.fireConnectEvent() - remoteHostIpAddress = 12.46.184.253 remotePort = 443 localHostIpAddress 10.1.3.78 localPort = 50052
[2014-10-02 10:25:36.872] DEBUG <-1768980930> CustomConnectAgent.finishConnect(DC) got DataConduit of type com.sterlingcommerce.perimeter.api.conduit.SSLByteDataConduit
[2014-10-02 10:25:36.873] DEBUG <-1768980930> CustomConnectAgent.finishConnect(DC)finishConnect.commandAndHeader is not nullPOST /invoke/aitboise.purchaseOrder/purchaseOrder HTTP/1.1
Host: beta.appliedexchange.com:443
User-Agent: GIS/PsHttpClientAdapter
Content-Type: text/xml; charset=UTF-8
Content-Length: 6750


[2014-10-02 10:25:36.873] DEBUG <-1768980930> CustomConnectAgent.finishConnect(DC)  finished.
[2014-10-02 10:25:37.612] DEBUG <-1768980930> CustomConnectAgent.closed(CC) was closed from the perimeter side: CloseCode.HANDSHAKE_FAILURE
[2014-10-02 10:25:37.613] DEBUG <-1768980930> CustomConnectAgent.handleFailure() - Starting with failure message [Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE]
[2014-10-02 10:25:37.613] ERROR <-1768980930> CustomConnectAgent.closed(CC)encountered error due to [Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE]
[2014-10-02 10:25:37.613] DEBUG <-1768980930> HttpSession.handleHttpCommand(S) - received command [FAILURE].
[2014-10-02 10:25:37.614] DEBUG <-1768980930> HttpSession.handleHttpCommand(S,HCR) - received request [null]
[2014-10-02 10:25:37.614] DEBUG <-1768980930> HttpSession.handleHttpCommand(S,HCR) - parsed  [FAILURE] to [FAILURE].
[2014-10-02 10:25:37.614] DEBUG <-1768980930> psclientcommon.Session.handleCommand(C,O) received command [FAILURE].
[2014-10-02 10:25:37.614] DEBUG <-1768980930> StateMachine.handleCommand(IS,C,O) entering with state [Waiting], command [FAILURE], and argument [null].
[2014-10-02 10:25:37.615] WARN <-1768980930> StateMachine.handleCommand(IS,C,O) handling [FAILURE] with undefined transitions, executing unaccepted handler and not transitioning state.
[2014-10-02 10:25:37.615] DEBUG <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> Command.execute(IS,b,O) starting on command [FAILURE], accepted = [false], implemented = [true].
[2014-10-02 10:25:37.615] DEBUG <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> Command.execute(IS,b,O) ...Entering handleFAILURECommand
[2014-10-02 10:25:37.615] DEBUG <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> HttpCommand.handleFAILURECommand(IS,b,O) - starting on command [FAILURE], accepted = [false]
[2014-10-02 10:25:37.616] DEBUG <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> HttpCommand.handleFAILURECommand(IS,b,O) - calling performCallback on session [com.sterlingcommerce.woodstock.services.httpclient.session.HttpSession@bedcddf7] with response HttpClientResponse: HTTP status [-1] HTTP reason phrase [Internal Error: Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE] Primary Document  [null]
[2014-10-02 10:25:37.616] DEBUG <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> HttpSession.performCallback() - Final response document is [null/empty]. Performing workflow resume...
[2014-10-02 10:25:37.616] INFO <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> doing a set future result started===>2014-10-02 10:25:37.616
[2014-10-02 10:25:37.617] INFO <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> doing a set future result completed===>2014-10-02 10:25:37.617
[2014-10-02 10:25:37.617] DEBUG <-375692386> HttpSession.waitForResults(l) - results obtained
[2014-10-02 10:25:37.617] DEBUG <-375692386> HttpClient.sendRequest() - Verifying if any Session failure after sending ...
[2014-10-02 10:25:37.617] DEBUG <-375692386> HttpClient.checkForFailures() - checking for errors...
[2014-10-02 10:25:37.618] DEBUG <-375692386> HttpClient.checkForFailures() - session currently in state of [Waiting]
[2014-10-02 10:25:37.618] DEBUG <-375692386> HttpClient.checkForFailures() - ...none found; state machine and session are ready.
[2014-10-02 10:25:37.618] DEBUG <-375692386> HttpClient.sendRequest() - Completed.
[2014-10-02 10:25:37.619] INFO <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> Response is: HttpClientResponse: HTTP status [-1] HTTP reason phrase [Internal Error: Connection was closed from the perimeter side with error: CloseCode.HANDSHAKE_FAILURE] Primary Document  [null]
[2014-10-02 10:25:37.625] DEBUG <-1768980930 SHTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200> Command.execute(IS,b,O) ...Exiting handleFAILURECommand
[2014-10-02 10:25:37.625] DEBUG <-1768980930> CustomConnectAgent.close() - Closing data conduit...
[2014-10-02 10:25:37.626] DEBUG <-1768980930> CustomConnectAgent.close() - Completed.
[2014-10-02 10:25:37.626] DEBUG <-1768980930> CustomConnectAgent.handleFailure() - Completed.
[2014-10-02 10:25:37.626] DEBUG <-1768980930> CustomConnectAgent.close() - Closing data conduit...
[2014-10-02 10:25:37.626] DEBUG <-1768980930> CustomConnectAgent.close() - Completed.
[2014-10-02 10:25:37.627] DEBUG <-1768980930> CustomConnectAgent.closed(CC) closed.
[2014-10-02 10:25:37.627] DEBUG <-1768980930> CustomConnectAgent.closed(CC) was closed from the perimeter side: CloseCode.HANDSHAKE_FAILURE
[2014-10-02 10:25:37.628] DEBUG <-375692386> HttpClientAdapter.setWorkFlowStatus()Activity unregistered with workflow ID [2511034] and activity key [bouxsbi02:node1:148d1a20775:10538]
[2014-10-02 10:25:37.634] DEBUG <HAR.2511034.Thread> HTTPClientPostService.primitiveProcessData() - request sent, waiting for response ...
[2014-10-02 10:25:37.634] DEBUG <WFE.2511034.Thread> HTTPClientPostService.processData() - Processing of this service has completed with status [1].
[2014-10-02 10:25:37.653] DEBUG <WFE.2511034.Thread> HTTPClientEndService.processData() - Start processing ...
[2014-10-02 10:25:37.654] DEBUG <WFE.2511034.Thread> HTTPClientEndService.primitiveProcessData() - Starting ...
[2014-10-02 10:25:37.654] DEBUG <HAR.2511034.Thread> HTTPClientEndService.findLittleAdapter() - ServicesControllerImpl found an adapter of type class com.sterlingcommerce.woodstock.services.httpclient.HttpClientAdapterImpl_Stub given service name [HTTPClientAdapter_HTTPClientAdapter_node1]
[2014-10-02 10:25:37.654] DEBUG <-375692386> HttpClient.endSession() -  Starting ...
[2014-10-02 10:25:37.655] DEBUG <-375692386> HttpSession.shutdown() - closing agent...
[2014-10-02 10:25:37.655] DEBUG <-375692386> CustomConnectAgent.close() - Closing data conduit...
[2014-10-02 10:25:37.655] DEBUG <-375692386> CustomConnectAgent.close() - Completed.
[2014-10-02 10:25:37.655] DEBUG <HAR.2511034.Thread> HTTPClientEndService.primitiveProcessData() Decremented  Session Token Count for[ HTTPClientAdapter_HTTPClientAdapter_node1]
[2014-10-02 10:25:37.656] DEBUG <HAR.2511034.Thread> HTTPClientEndService.primitiveProcessData() - completed, ended session with Id [HTTPClientAdapter_HTTPClientAdapter_node1_14122671364952:19233200]
[2014-10-02 10:25:37.656] DEBUG <WFE.2511034.Thread> HTTPClientEndService.processData() - Processing of this service has completed with status [0].
[2014-10-02 10:26:36.556] DEBUG <HTTPClientAdapter_HTTPClientAdapter_node1-Thread-15=-1581175112> CustomConnectAgent:TimeoutHandler.run() - timeout hit, but response already received. Ignoring...
[2014-10-02 10:26:37.403] WARN <Finalizer thread> HttpClientResponse.finalize() - Already finalized. Ignoring...

[{"Product":{"code":"SS3JSW","label":"IBM Sterling B2B Integrator"},"Business Unit":{"code":"BU048","label":"IBM Software"},"Component":"--","Platform":[{"code":"PF016","label":"Linux"}],"Version":"5.2.4.2","Edition":"","Line of Business":{"code":"LOB77","label":"Automation Platform"}}]

Log InLog in to view more of this document

This document has the abstract of a technical article that is available to authorized users once you have logged on. Please use Log in button above to access the full document. After log in, if you do not have the right authorization for this document, there will be instructions on what to do next.

Document Information

Modified date:
18 May 2020

UID

swg21722408