SSL handshake error

While i've found that using a 1.5 JRE makes this problem go away, this reduces the usefulness of SOAP UI because HermesJMS needs a 1.6 JRE.

Any news on a proper fix for this bug?

Thanks,

Mark

Hi Mark,

no news from us unfortunately since we are not able to reproduce this. The upcoming nightly-build of 3.5.x will have a slight change in host-configuration handling that might help out here.. please give it a go when it is out (in about 5 hours) and let us know..

regards,

/Ole
eviware.com

I'll give it a try - thanks.

Mark

I have other members of my team using SOAPUI Pro 3.5 and they are also seeing this issue appear - it happens after a few calls using SSL - the only solution is to restart SOAPUI.

Is there an ETA as to when this issue will be corrected.

We are using SSL more due to PCI compliance requirements and this issue is impacting our ability to test services quickly.



Thanks,

Jim Cusack

I have updated SOAPUI Pro 3.5 to latest nightly build and am still getting an error after a single successful call over SSL:

2010-04-20 16:34:15,599 ERROR [WsdlSubmit] Exception in request: javax.net.ssl.SSLException: Received fatal alert: unexpected_message
2010-04-20 16:34:15,599 ERROR [SoapUI] An error occured [Received fatal alert: unexpected_message], see error log for details
2010-04-20 16:34:15,599 ERROR [errorlog] javax.net.ssl.SSLException: Received fatal alert: unexpected_message
javax.net.ssl.SSLException: Received fatal alert: unexpected_message
at com.sun.net.ssl.internal.ssl.Alerts.getSSLException(Unknown Source)
at com.sun.net.ssl.internal.ssl.Alerts.getSSLException(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLSocketImpl.recvAlert(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLSocketImpl.performInitialHandshake(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLSocketImpl.writeRecord(Unknown Source)
at com.sun.net.ssl.internal.ssl.AppOutputStream.write(Unknown Source)
at java.io.BufferedOutputStream.flushBuffer(Unknown Source)
at java.io.BufferedOutputStream.write(Unknown Source)
at java.io.FilterOutputStream.write(Unknown Source)
at org.apache.commons.httpclient.WireLogOutputStream.write(WireLogOutputStream.java:86)
at org.apache.commons.httpclient.methods.ByteArrayRequestEntity.writeRequest(ByteArrayRequestEntity.java:90)
at org.apache.commons.httpclient.methods.EntityEnclosingMethod.writeRequestBody(EntityEnclosingMethod.java:499)
at org.apache.commons.httpclient.HttpMethodBase.writeRequest(HttpMethodBase.java:2114)
at com.eviware.soapui.impl.wsdl.submit.transports.http.support.methods.ExtendedPostMethod.writeRequest(ExtendedPostMethod.java:107)
at org.apache.commons.httpclient.HttpMethodBase.execute(HttpMethodBase.java:1096)
at org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:398)
at org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171)
at org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397)
at com.eviware.soapui.impl.wsdl.submit.transports.http.HttpClientRequestTransport.sendRequest(HttpClientRequestTransport.java:192)
at com.eviware.soapui.impl.wsdl.WsdlSubmit.run(WsdlSubmit.java:122)
at com.eviware.soapui.impl.wsdl.WsdlSubmit.submitRequest(WsdlSubmit.java:75)
at com.eviware.soapui.impl.wsdl.WsdlRequest.submit(WsdlRequest.java:235)
at com.eviware.soapui.impl.wsdl.teststeps.WsdlTestRequestStep.run(WsdlTestRequestStep.java:324)
at com.eviware.soapui.impl.wsdl.testcase.WsdlTestCaseRunner.runTestStep(WsdlTestCaseRunner.java:207)
at com.eviware.soapui.impl.wsdl.testcase.WsdlTestCaseRunner.internalRun(WsdlTestCaseRunner.java:138)
at com.eviware.soapui.impl.wsdl.testcase.WsdlTestCaseRunner.internalRun(WsdlTestCaseRunner.java:39)
at com.eviware.soapui.impl.wsdl.support.AbstractTestRunner.run(AbstractTestRunner.java:139)
at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source)
at java.util.concurrent.FutureTask$Sync.innerRun(Unknown Source)
at java.util.concurrent.FutureTask.run(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source)

Hi Jim,

Have you tried using a 1.5 JRE? That resolved the issue for me.

Its a workaround that will only work for HTTP interfaces though, because HermesJMS requires a 1.6 JRE...

Regards,

Mark

Hi,

yes.. switching to the 1.5 jre is all we have for you now.. we are not able to reproduce this ourselved unfortunately..

Have you tried the latest jre 1.6_20 update?

/Ole
eviware.com

I am using Hermes JMS so I need to stay on JRE 1.6 - I downloaded the latest 3.5.1 SOUPUI Pro release and am still getting the error.

Are you suggesting I update the JRE used by SOAPUI Pro manually?

IF you have a new build with the latest JRE 1.6 - please let me know.

Thanks,

Jim Cusack

I tried upgrading to JRE 1.6.0_20 and am still getting this error.

I can stop/start SOAPUI and the problem still occurs after one good call - or if I leave SOAPUI idle for several minutes (over 10) I can get a good call, then the failures happen again.

It seems like the thread is not being cleaned up or some other type of timing issue is the root cause.

I really need this bug fixed as I can't go back to JRE 1.5 because I need the Hermes JMS tool to work.

Thanks,

Jim Cusack

I noticed on the failed requests it appears the HTTP Post is happening 4 times - how is this being controlled?


Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "POST /soap/SOAP_CREATE_ORDER_IN_ORDERMAX HTTP/1.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Accept-Encoding: gzip,deflate[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Type: text/xml;charset=UTF-8[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "SOAPAction: ""[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Host: qa-services.omx.com:9148[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Length: 4533[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "POST /soap/SOAP_CREATE_ORDER_IN_ORDERMAX HTTP/1.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Accept-Encoding: gzip,deflate[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Type: text/xml;charset=UTF-8[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "SOAPAction: ""[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Length: 4533[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Host: qa-services.omx.com:9148[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "POST /soap/SOAP_CREATE_ORDER_IN_ORDERMAX HTTP/1.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Accept-Encoding: gzip,deflate[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Type: text/xml;charset=UTF-8[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "SOAPAction: ""[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Length: 4533[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Host: qa-services.omx.com:9148[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "POST /soap/SOAP_CREATE_ORDER_IN_ORDERMAX HTTP/1.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Accept-Encoding: gzip,deflate[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Type: text/xml;charset=UTF-8[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "SOAPAction: ""[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Content-Length: 4533[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "Host: qa-services.omx.com:9148[\r][\n]"
Wed Apr 21 16:45:40 CDT 2010:DEBUG:>> "[\r][\n]"