Bug 1081009

Summary: JBossWS testsuite intermittent failures caused by "java.net.SocketException: Unexpected end of file from server"
Product: [JBoss] JBoss Enterprise Application Platform 6 Reporter: Petr Sakař <psakar>
Component: WebAssignee: Rémy Maucherat <rmaucher>
Status: CLOSED CURRENTRELEASE QA Contact: Radim Hatlapatka <rhatlapa>
Severity: medium Docs Contact: Russell Dickenson <rdickens>
Priority: medium    
Version: 6.3.0CC: nobody, rsvoboda
Target Milestone: ---   
Target Release: EAP 6.3.0   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: 1072242 Environment:
Last Closed: 2014-07-25 14:26:53 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On: 1075695    
Bug Blocks:    

Description Petr Sakař 2014-03-26 13:32:25 UTC
We see intermittent failures caused by java.net.SocketException: Unexpected end of file from server

Version-Release number of selected component (if applicable):
EAP-6.3.0.DR6
jdk1.8 GA

How reproducible:
intermittent

Steps to Reproduce:
1. run jbossws testsuite

Additional info:
https://jenkins.mw.lab.eng.bos.redhat.com/hudson/view/EAP6/view/EAP6-WS/job/eap-6x-jbossws-testsuite-rhel-ipv6-pure/lastCompletedBuild/ipv6label=linux-pure-ipv6,jdk=java18_default/testReport/org.jboss.test.ws.jaxws.cxf.gzip/GZIPServletTestCase/testGZIPUsingFeatureOnClient/

org.jboss.test.ws.jaxws.cxf.gzip.GZIPServletTestCase.testGZIPUsingFeatureOnClient
Error Message
Unexpected end of file from server
Stacktrace

java.net.SocketException: Unexpected end of file from server
  at sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:786)
  at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:647)
  at sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:783)
  at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:647)
  at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1534)
  at sun.net.www.protocol.http.HttpURLConnection.access$200(HttpURLConnection.java:90)
  at sun.net.www.protocol.http.HttpURLConnection$9.run(HttpURLConnection.java:1431)
  at sun.net.www.protocol.http.HttpURLConnection$9.run(HttpURLConnection.java:1429)
  at java.security.AccessController.doPrivileged(Native Method)
  at java.security.AccessController.doPrivileged(AccessController.java:713)
  at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1428)
  at java.net.URL.openStream(URL.java:1038)
  at org.jboss.test.ws.jaxws.cxf.gzip.GZIPServletTestCase.runTestInContainer(GZIPServletTestCase.java:81)
  at org.jboss.test.ws.jaxws.cxf.gzip.GZIPServletTestCase.testGZIPUsingFeatureOnClient(GZIPServletTestCase.java:53)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  at java.lang.reflect.Method.invoke(Method.java:483)
  at junit.framework.TestCase.runTest(TestCase.java:168)
  at junit.framework.TestCase.runBare(TestCase.java:134)
  at junit.framework.TestResult$1.protect(TestResult.java:110)
  at junit.framework.TestResult.runProtected(TestResult.java:128)
  at junit.framework.TestResult.run(TestResult.java:113)
  at junit.framework.TestCase.run(TestCase.java:124)
  at junit.framework.TestSuite.runTest(TestSuite.java:243)
  at junit.framework.TestSuite.run(TestSuite.java:238)
  at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24)
  at org.jboss.wsf.test.JBossWSTestSetup$1.protect(JBossWSTestSetup.java:142)
  at junit.framework.TestResult.runProtected(TestResult.java:128)
  at org.jboss.wsf.test.JBossWSTestSetup.run(JBossWSTestSetup.java:149)
  at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
  at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:234)
  at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:133)
  at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:114)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  at java.lang.reflect.Method.invoke(Method.java:483)
  at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:188)
  at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:166)
  at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86)
  at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:101)
  at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)

Comment 1 Alessio Soldano 2014-03-26 18:14:12 UTC
There's really something weird happening here. If you look at the logs at [1], you see a lot of exceptions like this:

[0m[33m06:11:26,082 WARNING [org.apache.cxf.phase.PhaseInterceptorChain] (http-/0:0:0:0:0:0:0:1%lo:8080-4) Interceptor for {http://org.jboss.ws/jaxws/cxf/gzip}HelloWorldService#{http://org.jboss.ws/jaxws/cxf/gzip}echo has thrown exception, unwinding now: org.apache.cxf.interceptor.Fault: Could not send Message.
	at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:64) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:565) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:474) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:377) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:330) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96) [cxf-rt-frontend-simple-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:135) [cxf-rt-frontend-jaxws-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at com.sun.proxy.$Proxy121.echo(Unknown Source)
	at org.jboss.test.ws.jaxws.cxf.gzip.Helper.testGZIPUsingFeatureOnBus(Helper.java:67) [classes:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0]
	at java.lang.reflect.Method.invoke(Method.java:483) [rt.jar:1.8.0]
	at org.jboss.wsf.test.TestServlet.invokeMethod(TestServlet.java:152) [classes:]
	at org.jboss.wsf.test.TestServlet.doGet(TestServlet.java:89) [classes:]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:734) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:340) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:353) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:911) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint$ChannelProcessor.run(NioEndpoint.java:930) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0]
	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.8.0]
Caused by: org.apache.cxf.transport.http.HTTPException: HTTP response '405: Method Not Allowed' when communicating with http://[::1]:8080/jaxws-cxf-gzip/HelloWorldService/HelloWorldImpl
	at org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponseInternal(HTTPConduit.java:1549) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse(HTTPConduit.java:1504) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.close(HTTPConduit.java:1310) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.HTTPConduit.close(HTTPConduit.java:628) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInterceptor.handleMessage(MessageSenderInterceptor.java:62) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	... 32 more

Basically, in all cases, the test is invoking a plain servlet (the jbossws TestServlet), which tries to create a jaxws client for an endpoint living in a different deployment. The server is returning a "HTTP 405 Method not allowed" error, which is printed in the logs by the servlet just after the exception (you can see the same thread is printing). If you look at the code of the org.jboss.wsf.test.TestServlet, you see that it should basically return a message to the client, saying the number of failed tests (1). That would cause the test to fail. However, if you look at the results in [2], you see most tests are marked as passed, in particular the "testGZIPUsingFeatureOnBus" is marked as passed, while the logs clearly indicate it has failed for the reason above (see Helper.testGZIPUsingFeatureOnBus(Helper.java:67) in the stacktrace).
Now, carefully looking at the logs seems to reveal that the TestServlet is called multiple additional times, perhaps (just my gut feeling) the handling of that HTTP 405 causes the container to re-invoke the servlet? I see multiple entries like this in the logs after the exception above:

[0m[0m06:12:26,297 INFO  [org.apache.cxf.service.factory.ReflectionServiceFactoryBean] (http-/0:0:0:0:0:0:0:1%lo:8080-12) Creating Service {http://org.jboss.ws/jaxws/cxf/gzip}HelloWorldService from WSDL: http://[::1]:8080/jaxws-cxf-gzip/HelloWorldService/HelloWorldImpl?wsdl

... each time a JAX-WS client is built from a wsdl contract *2* of the above entries are printed in the logs. But I count 17 of those entries for a test which cause 6 jaxws client instances to be built. This is definitely suspicious.

The same strange behavior is found for the JAXBIntroServletTestCase, which works basically the same.
As long as there's this mess, it's hard to tell why the client to the servlet got an unexpected EOF...

Also, please note that the strange HTTP 405 issue (which would be a problem and would need to be investigate regardless of these test failures), can be found in previous runs of the testsuite, I notice it in [3] for instance.

Finally, in [3] I've also noticed this alarming exception, which btw is exactly happening when retrieving the HTTP method...

[0m[31m03:47:34,390 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/jaxws-cxf-jbws3060].[EndpointTwoEJB3Impl]] (http-/0:0:0:0:0:0:0:1%lo:8080-31) JBWEB000236: Servlet.service() for servlet EndpointTwoEJB3Impl threw exception: java.lang.StringIndexOutOfBoundsException: String index out of range: -469
	at java.lang.String.checkBounds(String.java:369) [rt.jar:1.8.0]
	at java.lang.String.<init>(String.java:450) [rt.jar:1.8.0]
	at org.apache.tomcat.util.buf.ByteChunk.toStringInternal(ByteChunk.java:515)
	at org.apache.tomcat.util.buf.StringCache.toString(StringCache.java:215)
	at org.apache.tomcat.util.buf.ByteChunk.toString(ByteChunk.java:508)
	at org.apache.tomcat.util.buf.MessageBytes.toString(MessageBytes.java:208)
	at org.apache.catalina.connector.Request.getMethod(Request.java:2203)
	at org.apache.catalina.connector.RequestFacade.getMethod(RequestFacade.java:667)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:727) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140) [jbossws-spi-2.3.0.CR1.jar:2.3.0.CR1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149)
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:340)
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:353)
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:911)
	at org.apache.tomcat.util.net.NioEndpoint$ChannelProcessor.run(NioEndpoint.java:920)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0]
	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.8.0]

Please assign this BZ to however is supposed to identify problems in the web layer.

Thanks, hope this initial analysis helps.


[1] http://jenkins.mw.lab.eng.bos.redhat.com/hudson/view/EAP6/view/EAP6-WS/job/eap-6x-jbossws-testsuite-rhel-ipv6-pure/35/ipv6label=linux-pure-ipv6,jdk=java18_default/consoleText
[2] http://jenkins.mw.lab.eng.bos.redhat.com/hudson/view/EAP6/view/EAP6-WS/job/eap-6x-jbossws-testsuite-rhel-ipv6-pure/35/ipv6label=linux-pure-ipv6,jdk=java18_default/testReport/org.jboss.test.ws.jaxws.cxf.gzip/GZIPServletTestCase/
[3] http://jenkins.mw.lab.eng.bos.redhat.com/hudson/view/EAP6/view/EAP6-WS/job/eap-6x-jbossws-testsuite-rhel-ipv6-pure/34/ipv6label=linux-pure-ipv6,jdk=java18_default/consoleText

Comment 2 Petr Sakař 2014-03-27 06:49:05 UTC
changing assignment based on analysis from JBossWS project lead - please see comment#1 above

Comment 3 Petr Sakař 2014-03-27 07:27:59 UTC
Failed org.jboss.wsf.test.JBossWSCXFTestSetup.org.jboss.test.ws.jaxws.samples.wsse.policy.oasis.WSSecurityPolicyExamples21xTestCase (from org.jboss.test.ws.jaxws.samples.wsse.policy.oasis.WSSecurityPolicyExamples21xTestCase)

***Client side***

{"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-1" => {"JBAS014671: Failed services" => {"jboss.web.connector.jbws-test-https-connector" => "org.jboss.msc.service.StartException in service jboss.web.connector.jbws-test-https-connector: JBAS018007: Error starting web connector     Caused by: LifecycleException:  JBWEB000023: Protocol handler initialization failed"}}}}
Stacktrace

java.lang.Exception: {"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-1" => {"JBAS014671: Failed services" => {"jboss.web.connector.jbws-test-https-connector" => "org.jboss.msc.service.StartException in service jboss.web.connector.jbws-test-https-connector: JBAS018007: Error starting web connector
    Caused by: LifecycleException:  JBWEB000023: Protocol handler initialization failed"}}}}
	at org.jboss.as.webservices.deployer.RemoteDeployer.checkResult(RemoteDeployer.java:323)
	at org.jboss.as.webservices.deployer.RemoteDeployer.applyUpdate(RemoteDeployer.java:310)
	at org.jboss.as.webservices.deployer.RemoteDeployer.addHttpsConnector(RemoteDeployer.java:284)
	at org.jboss.wsf.test.JBossWSTestHelper.addHttpsConnector(JBossWSTestHelper.java:406)
	at org.jboss.wsf.test.JBossWSTestSetup.setUp(JBossWSTestSetup.java:220)
	at org.jboss.wsf.test.JBossWSCXFTestSetup.setUp(JBossWSCXFTestSetup.java:72)
	at org.jboss.wsf.test.JBossWSTestSetup$1.protect(JBossWSTestSetup.java:141)
	at junit.framework.TestResult.runProtected(TestResult.java:128)
	at org.jboss.wsf.test.JBossWSTestSetup.run(JBossWSTestSetup.java:149)
	at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:234)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:133)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:114)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:94)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:619)
	at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:188)
	at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:166)
	at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:101)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)
	
***Server side*** - note the exception in previous test (which actually did not fail, seems the invocation was duplicated ?) and is causing JBWEB003043: Error initializing endpoint: java.net.BindException: Address already in use


[0m16:48:26,499 INFO  [org.jboss.as.server] (management-handler-thread - 4) JBAS018559: Deployed "jaxws-samples-wsse-policy-username-jaas.war" (runtime-name : "jaxws-samples-wsse-policy-username-jaas.war")
[33m16:48:26,662 WARNING [org.apache.cxf.phase.PhaseInterceptorChain] (http-/0:0:0:0:0:0:0:1%1:8080-110) Interceptor for {http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}SecurityService#{http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}sayHello has thrown exception, unwinding now: java.lang.SecurityException: JBWS024057: Failed Authentication : Subject has not been created
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreatingPolicyInterceptor.createSubject(SubjectCreatingPolicyInterceptor.java:111)
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreatingPolicyInterceptor.handleMessage(SubjectCreatingPolicyInterceptor.java:84)
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:239) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.jboss.wsf.stack.cxf.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:97)
	at org.jboss.wsf.stack.cxf.transport.ServletHelper.callRequestHandler(ServletHelper.java:131)
	at org.jboss.wsf.stack.cxf.CXFServletExt.invoke(CXFServletExt.java:88)
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:286) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:206) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140) [jbossws-spi-2.3.0.Final.jar:2.3.0.Final]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:340) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:353) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:911) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint$ChannelProcessor.run(NioEndpoint.java:930) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]
Caused by: java.lang.SecurityException: JBWS024047: Authentication failed, principal=snoopy
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreator.createSubject(SubjectCreator.java:99)
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreatingPolicyInterceptor.createSubject(SubjectCreatingPolicyInterceptor.java:107)
	... 28 more

[33m16:48:26,717 WARNING [org.apache.cxf.phase.PhaseInterceptorChain] (http-/0:0:0:0:0:0:0:1%1:8080-71) Interceptor for {http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}SecurityService#{http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}greetMe has thrown exception, unwinding now: org.apache.cxf.interceptor.security.AccessDeniedException: Unauthorized
	at org.apache.cxf.interceptor.security.AbstractAuthorizingInInterceptor.handleMessage(AbstractAuthorizingInInterceptor.java:56) [cxf-rt-core-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:239) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.jboss.wsf.stack.cxf.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:97)
	at org.jboss.wsf.stack.cxf.transport.ServletHelper.callRequestHandler(ServletHelper.java:131)
	at org.jboss.wsf.stack.cxf.CXFServletExt.invoke(CXFServletExt.java:88)
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:286) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:206) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140) [jbossws-spi-2.3.0.Final.jar:2.3.0.Final]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:340) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:353) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:911) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint$ChannelProcessor.run(NioEndpoint.java:930) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]

[0m16:48:26,827 INFO  [org.jboss.web] (ServerService Thread Pool -- 64) JBAS018224: Unregister web context: /jaxws-samples-wsse-policy-username-jaas
[0m16:48:26,832 INFO  [org.jboss.as.webservices] (MSC service thread 1-6) JBAS015540: Stopping service jboss.ws.endpoint."jaxws-samples-wsse-policy-username-jaas.war".TestService
[0m16:48:26,835 INFO  [org.jboss.as.webservices] (MSC service thread 1-2) JBAS015540: Stopping service jboss.ws.port-component-link
[0m16:48:26,843 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015877: Stopped deployment jaxws-samples-wsse-policy-username-jaas.war (runtime-name: jaxws-samples-wsse-policy-username-jaas.war) in 16ms
[0m16:48:26,856 INFO  [org.jboss.as.repository] (management-handler-thread - 1) JBAS014901: Content removed from location /qa/hudson_ws/workspace/eap-6x-jbossws-testsuite-rhel-ipv6-dualstack/1903b966/build/jboss-eap-6.3/standalone/data/content/10/c932687075cf216dfda5186e2be91e4ca34653/content
[0m16:48:26,856 INFO  [org.jboss.as.server] (management-handler-thread - 1) JBAS018558: Undeployed "jaxws-samples-wsse-policy-username-jaas.war" (runtime-name: "jaxws-samples-wsse-policy-username-jaas.war")





Running org.jboss.test.ws.jaxws.samples.wsse.policy.oasis.WSSecurityPolicyExamples21xTestCase
[31m16:48:26,952 ERROR [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003043: Error initializing endpoint: java.net.BindException: Address already in use
	at sun.nio.ch.Net.bind0(Native Method) [rt.jar:1.7.0]
	at sun.nio.ch.Net.bind(Net.java:567) [rt.jar:1.7.0]
	at sun.nio.ch.Net.bind(Net.java:553) [rt.jar:1.7.0]
	at sun.nio.ch.AsynchronousServerSocketChannelImpl.bind(AsynchronousServerSocketChannelImpl.java:174) [rt.jar:1.7.0]
	at org.apache.tomcat.util.net.DefaultNioServerSocketChannelFactory.createServerChannel(DefaultNioServerSocketChannelFactory.java:107) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint.init(NioEndpoint.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol.init(Http11NioProtocol.java:113) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.Connector.init(Connector.java:983) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.WebConnectorService.start(WebConnectorService.java:304) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1980) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1913) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]

[31m16:48:26,958 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-8) MSC000001: Failed to start service jboss.web.connector.jbws-test-https-connector: org.jboss.msc.service.StartException in service jboss.web.connector.jbws-test-https-connector: JBAS018007: Error starting web connector
	at org.jboss.as.web.WebConnectorService.start(WebConnectorService.java:362)
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1980) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1913) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]
Caused by: LifecycleException:  JBWEB000023: Protocol handler initialization failed
	at org.apache.catalina.connector.Connector.init(Connector.java:985)
	at org.jboss.as.web.WebConnectorService.start(WebConnectorService.java:304)
	... 5 more

[0m16:48:26,963 INFO  [org.jboss.as.controller] (management-handler-thread - 3) JBAS014774: Service status report
JBAS014777:   Services which failed to start:      service jboss.web.connector.jbws-test-https-connector

Comment 4 Petr Sakař 2014-03-27 07:29:20 UTC
IBM JDK


Failed org.jboss.wsf.test.JBossWSCXFTestSetup.org.jboss.test.ws.jaxws.samples.wsse.policy.oasis.WSSecurityPolicyExamples21xTestCase (from org.jboss.test.ws.jaxws.samples.wsse.policy.oasis.WSSecurityPolicyExamples21xTestCase)

***Client side***

{"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-1" => {"JBAS014671: Failed services" => {"jboss.web.connector.jbws-test-https-connector" => "org.jboss.msc.service.StartException in service jboss.web.connector.jbws-test-https-connector: JBAS018007: Error starting web connector     Caused by: LifecycleException:  JBWEB000023: Protocol handler initialization failed"}}}}
Stacktrace

java.lang.Exception: {"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-1" => {"JBAS014671: Failed services" => {"jboss.web.connector.jbws-test-https-connector" => "org.jboss.msc.service.StartException in service jboss.web.connector.jbws-test-https-connector: JBAS018007: Error starting web connector
    Caused by: LifecycleException:  JBWEB000023: Protocol handler initialization failed"}}}}
	at org.jboss.as.webservices.deployer.RemoteDeployer.checkResult(RemoteDeployer.java:323)
	at org.jboss.as.webservices.deployer.RemoteDeployer.applyUpdate(RemoteDeployer.java:310)
	at org.jboss.as.webservices.deployer.RemoteDeployer.addHttpsConnector(RemoteDeployer.java:284)
	at org.jboss.wsf.test.JBossWSTestHelper.addHttpsConnector(JBossWSTestHelper.java:406)
	at org.jboss.wsf.test.JBossWSTestSetup.setUp(JBossWSTestSetup.java:220)
	at org.jboss.wsf.test.JBossWSCXFTestSetup.setUp(JBossWSCXFTestSetup.java:72)
	at org.jboss.wsf.test.JBossWSTestSetup$1.protect(JBossWSTestSetup.java:141)
	at junit.framework.TestResult.runProtected(TestResult.java:128)
	at org.jboss.wsf.test.JBossWSTestSetup.run(JBossWSTestSetup.java:149)
	at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:83)
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:234)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:133)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:114)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:94)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:619)
	at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:188)
	at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:166)
	at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:86)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:101)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)
	
***Server side*** - note the exception in previous test (which actually did not fail, seems the invocation was duplicated ?) and is causing JBWEB003043: Error initializing endpoint: java.net.BindException: Address already in use


[0m16:48:26,499 INFO  [org.jboss.as.server] (management-handler-thread - 4) JBAS018559: Deployed "jaxws-samples-wsse-policy-username-jaas.war" (runtime-name : "jaxws-samples-wsse-policy-username-jaas.war")
[33m16:48:26,662 WARNING [org.apache.cxf.phase.PhaseInterceptorChain] (http-/0:0:0:0:0:0:0:1%1:8080-110) Interceptor for {http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}SecurityService#{http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}sayHello has thrown exception, unwinding now: java.lang.SecurityException: JBWS024057: Failed Authentication : Subject has not been created
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreatingPolicyInterceptor.createSubject(SubjectCreatingPolicyInterceptor.java:111)
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreatingPolicyInterceptor.handleMessage(SubjectCreatingPolicyInterceptor.java:84)
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:239) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.jboss.wsf.stack.cxf.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:97)
	at org.jboss.wsf.stack.cxf.transport.ServletHelper.callRequestHandler(ServletHelper.java:131)
	at org.jboss.wsf.stack.cxf.CXFServletExt.invoke(CXFServletExt.java:88)
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:286) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:206) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140) [jbossws-spi-2.3.0.Final.jar:2.3.0.Final]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:340) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:353) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:911) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint$ChannelProcessor.run(NioEndpoint.java:930) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]
Caused by: java.lang.SecurityException: JBWS024047: Authentication failed, principal=snoopy
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreator.createSubject(SubjectCreator.java:99)
	at org.jboss.wsf.stack.cxf.security.authentication.SubjectCreatingPolicyInterceptor.createSubject(SubjectCreatingPolicyInterceptor.java:107)
	... 28 more

[33m16:48:26,717 WARNING [org.apache.cxf.phase.PhaseInterceptorChain] (http-/0:0:0:0:0:0:0:1%1:8080-71) Interceptor for {http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}SecurityService#{http://www.jboss.org/jbossws/ws-extensions/wssecuritypolicy}greetMe has thrown exception, unwinding now: org.apache.cxf.interceptor.security.AccessDeniedException: Unauthorized
	at org.apache.cxf.interceptor.security.AbstractAuthorizingInInterceptor.handleMessage(AbstractAuthorizingInInterceptor.java:56) [cxf-rt-core-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:272) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) [cxf-api-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:239) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.jboss.wsf.stack.cxf.RequestHandlerImpl.handleHttpRequest(RequestHandlerImpl.java:97)
	at org.jboss.wsf.stack.cxf.transport.ServletHelper.callRequestHandler(ServletHelper.java:131)
	at org.jboss.wsf.stack.cxf.CXFServletExt.invoke(CXFServletExt.java:88)
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:286) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPost(AbstractHTTPServlet.java:206) [cxf-rt-transports-http-2.7.10.redhat-1.jar:2.7.10.redhat-1]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.jboss.wsf.stack.cxf.CXFServletExt.service(CXFServletExt.java:136)
	at org.jboss.wsf.spi.deployment.WSFServlet.service(WSFServlet.java:140) [jbossws-spi-2.3.0.Final.jar:2.3.0.Final]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.2.Final-redhat-1.jar:1.0.2.Final-redhat-1]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:295) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:231) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:149) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:145) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:97) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:102) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:340) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:353) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:911) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint$ChannelProcessor.run(NioEndpoint.java:930) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]

[0m16:48:26,827 INFO  [org.jboss.web] (ServerService Thread Pool -- 64) JBAS018224: Unregister web context: /jaxws-samples-wsse-policy-username-jaas
[0m16:48:26,832 INFO  [org.jboss.as.webservices] (MSC service thread 1-6) JBAS015540: Stopping service jboss.ws.endpoint."jaxws-samples-wsse-policy-username-jaas.war".TestService
[0m16:48:26,835 INFO  [org.jboss.as.webservices] (MSC service thread 1-2) JBAS015540: Stopping service jboss.ws.port-component-link
[0m16:48:26,843 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015877: Stopped deployment jaxws-samples-wsse-policy-username-jaas.war (runtime-name: jaxws-samples-wsse-policy-username-jaas.war) in 16ms
[0m16:48:26,856 INFO  [org.jboss.as.repository] (management-handler-thread - 1) JBAS014901: Content removed from location /qa/hudson_ws/workspace/eap-6x-jbossws-testsuite-rhel-ipv6-dualstack/1903b966/build/jboss-eap-6.3/standalone/data/content/10/c932687075cf216dfda5186e2be91e4ca34653/content
[0m16:48:26,856 INFO  [org.jboss.as.server] (management-handler-thread - 1) JBAS018558: Undeployed "jaxws-samples-wsse-policy-username-jaas.war" (runtime-name: "jaxws-samples-wsse-policy-username-jaas.war")





Running org.jboss.test.ws.jaxws.samples.wsse.policy.oasis.WSSecurityPolicyExamples21xTestCase
[31m16:48:26,952 ERROR [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003043: Error initializing endpoint: java.net.BindException: Address already in use
	at sun.nio.ch.Net.bind0(Native Method) [rt.jar:1.7.0]
	at sun.nio.ch.Net.bind(Net.java:567) [rt.jar:1.7.0]
	at sun.nio.ch.Net.bind(Net.java:553) [rt.jar:1.7.0]
	at sun.nio.ch.AsynchronousServerSocketChannelImpl.bind(AsynchronousServerSocketChannelImpl.java:174) [rt.jar:1.7.0]
	at org.apache.tomcat.util.net.DefaultNioServerSocketChannelFactory.createServerChannel(DefaultNioServerSocketChannelFactory.java:107) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.tomcat.util.net.NioEndpoint.init(NioEndpoint.java:214) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.coyote.http11.Http11NioProtocol.init(Http11NioProtocol.java:113) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.apache.catalina.connector.Connector.init(Connector.java:983) [jbossweb-7.4.0.Final.jar:7.4.0.Final]
	at org.jboss.as.web.WebConnectorService.start(WebConnectorService.java:304) [jboss-as-web-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6]
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1980) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1913) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]

[31m16:48:26,958 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-8) MSC000001: Failed to start service jboss.web.connector.jbws-test-https-connector: org.jboss.msc.service.StartException in service jboss.web.connector.jbws-test-https-connector: JBAS018007: Error starting web connector
	at org.jboss.as.web.WebConnectorService.start(WebConnectorService.java:362)
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1980) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1913) [jboss-msc-1.1.5.Final.jar:1.1.5.Final]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1170) [rt.jar:1.7.0]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:640) [rt.jar:1.7.0]
	at java.lang.Thread.run(Thread.java:853) [vm.jar:1.7.0]
Caused by: LifecycleException:  JBWEB000023: Protocol handler initialization failed
	at org.apache.catalina.connector.Connector.init(Connector.java:985)
	at org.jboss.as.web.WebConnectorService.start(WebConnectorService.java:304)
	... 5 more

[0m16:48:26,963 INFO  [org.jboss.as.controller] (management-handler-thread - 3) JBAS014774: Service status report
JBAS014777:   Services which failed to start:      service jboss.web.connector.jbws-test-https-connector

Comment 6 Rostislav Svoboda 2014-07-25 14:26:53 UTC
As per email discussion checked on EAP 6.3.0 ER10.

No unexpected failure, closing.