Hi all, 

 

I am new to the CXF workspace. 

 

I have built and imported CXF into Eclipse on my own branch based on 3.2.6

 

I am attempting to run Junit test
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest inside eclipse.

 

Upon doing so I get an error message:

 

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime 1539650859720,[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.TgsRequest issueTicket

INFO: TGS_REQ ISSUE: authtime 1539650859732,[email protected] for
bob/[email protected]

Oct 15, 2018 7:47:39 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoSymmetricPort.spn
ego-client.SecurityTokenService

INFO: Outbound Message

---------------------------

ID: 5

Address: http://localhost:9001/DoubleItSpnegoSymmetric

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml

Headers: {Accept=[*/*],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue"]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:75d15aed-804d-4f8a-a35
e-11e0ca790529</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>http://localhost:9001/DoubleItS
pnegoSymmetric</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_11750cef-9724-4705-9651-0344c30437f8"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>http://localho
st:9001/DoubleItSpnegoSymmetric</wsa:Address></wsa:EndpointReference></wsp:A
ppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx/ws-secureconversati
on/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySize><wst:BinaryExchan
ge
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGorFpqK9AENbRc+cbhenbif4zvR2TVT0lrAAG2Wm2L10JBV8rV
YmLfGJT5DevSLYzXbgLfCpT4I1Hb1Cy2P5gMoYa69u3onwQFMOHOoDIK9pnqACB+w68FpMboH9Re
TaJ4kfXE1IAyVtL+UnP5bIQVm05cL+fj1hUYkEydwgP4xxXkoOq5kCVKa2iIM22yAjYst00+BWyY
5ZFS44EveBf9fTUcL1Vgh8jupIHIMIHFoAMCARGigb0Egbo/VO2Znw2/N5OrizxZlM3P9MJC5rtC
IauG/xBTAZ0ufIRDo+GW46U6mzL/WEmQ/NWTgS7JZatlabV9+pAAqFxRdXZ5eefb3Kl3MfP82/6x
XCfRqn/20XyFb6lw0EJY2bmLG/YZHxI5224lTTeY/+dLoQ6g5J61Il+5jM7xz9lt5fjHw00JKZEI
eJzvshAFzA4HZdNnwKEhQe0F1G5lEJrZN/50lhkIaM/BWR/1kJeXQthKMzqTAj3AIVE=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:39 PM
org.apache.cxf.services.DoubleItService.DoubleItSpnegoSymmetricPort.DoubleIt
PortType

INFO: Inbound Message

----------------------------

ID: 6

Address: http://localhost:9001/DoubleItSpnegoSymmetric

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml; charset=UTF-8

Headers: {Accept=[*/*], Cache-Control=[no-cache], connection=[keep-alive],
Content-Length=[2210], content-type=[text/xml; charset=UTF-8],
Host=[localhost:9001], Pragma=[no-cache],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue";],
User-Agent=[Apache-CXF/${project.version}]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:75d15aed-804d-4f8a-a35
e-11e0ca790529</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>http://localhost:9001/DoubleItS
pnegoSymmetric</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_11750cef-9724-4705-9651-0344c30437f8"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>http://localho
st:9001/DoubleItSpnegoSymmetric</wsa:Address></wsa:EndpointReference></wsp:A
ppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx/ws-secureconversati
on/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySize><wst:BinaryExchan
ge
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGorFpqK9AENbRc+cbhenbif4zvR2TVT0lrAAG2Wm2L10JBV8rV
YmLfGJT5DevSLYzXbgLfCpT4I1Hb1Cy2P5gMoYa69u3onwQFMOHOoDIK9pnqACB+w68FpMboH9Re
TaJ4kfXE1IAyVtL+UnP5bIQVm05cL+fj1hUYkEydwgP4xxXkoOq5kCVKa2iIM22yAjYst00+BWyY
5ZFS44EveBf9fTUcL1Vgh8jupIHIMIHFoAMCARGigb0Egbo/VO2Znw2/N5OrizxZlM3P9MJC5rtC
IauG/xBTAZ0ufIRDo+GW46U6mzL/WEmQ/NWTgS7JZatlabV9+pAAqFxRdXZ5eefb3Kl3MfP82/6x
XCfRqn/20XyFb6lw0EJY2bmLG/YZHxI5224lTTeY/+dLoQ6g5J61Il+5jM7xz9lt5fjHw00JKZEI
eJzvshAFzA4HZdNnwKEhQe0F1G5lEJrZN/50lhkIaM/BWR/1kJeXQthKMzqTAj3AIVE=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:39 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime
1539650859771,bob/[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:39 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}SecurityTokenService#
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}RequestSecurityToken
has thrown exception, unwinding now

org.apache.cxf.interceptor.Fault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:144)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationOb
server.java:121)

       at
org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDes
tination.java:267)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPDestination.doService(JettyHTTP
Destination.java:247)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPHandler.handle(JettyHTTPHandler
.java:79)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java
:257)

       at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java
:1317)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:
205)

       at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:
1219)

       at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144
)

       at
org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHand
lerCollection.java:219)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at org.eclipse.jetty.server.Server.handle(Server.java:531)

       at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)

       at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)

       at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConne
ction.java:281)

       at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)

       at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:
762)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:6
80)

       at java.lang.Thread.run(Unknown Source)

Caused by: java.lang.NoSuchMethodError:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.ws.security.tokenstore.EHCacheTokenStore.add(EHCacheTokenStor
e.java:102)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.doIssue(SpnegoContextTokenInInterceptor.java:257)

       at
org.apache.cxf.ws.security.policy.interceptors.STSInvoker.invoke(STSInvoker.
java:124)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.invoke(SpnegoContextTokenInInterceptor.java:1)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInt
erceptor.java:59)

       at java.util.concurrent.Executors$RunnableAdapter.call(Unknown
Source)

       at java.util.concurrent.FutureTask.run(Unknown Source)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$2.run(ServiceInvokerInt
erceptor.java:126)

       at
org.apache.cxf.workqueue.SynchronousExecutor.execute(SynchronousExecutor.jav
a:37)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:131)

       ... 22 more

 

Oct 15, 2018 7:47:39 PM
org.apache.cxf.services.SecurityTokenService.SecurityTokenService.SecurityTo
kenService

INFO: Outbound Message

---------------------------

ID: 6

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml

Headers: {}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:39 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoSymmetricPort.spn
ego-client.SecurityTokenService

INFO: Inbound Message

----------------------------

ID: 5

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml;charset=utf-8

Headers: {content-type=[text/xml;charset=utf-8], Date=[Tue, 16 Oct 2018
00:47:39 GMT], Server=[Jetty(9.4.11.v20180605)],
transfer-encoding=[chunked]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:39 PM org.apache.cxf.ws.addressing.soap.MAPCodec
restoreExchange

WARNING: Response message does not contain WS-Addressing properties.  Not
correlating response.

Oct 15, 2018 7:47:39 PM org.apache.cxf.ws.addressing.ContextUtils
retrieveMAPs

WARNING: WS-Addressing - failed to retrieve Message Addressing Properties
from context

Oct 15, 2018 7:47:39 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://www.example.org/contract/DoubleIt}DoubleItService#{http://www.exampl
e.org/contract/DoubleIt}DoubleIt has thrown exception, unwinding now

org.apache.cxf.binding.soap.SoapFault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.unmarshalFa
ult(Soap11FaultInInterceptor.java:87)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:53)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(Abs
tractFaultChainInitiatorObserver.java:112)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:70)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.onMessage(ClientImpl.java:833)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
Internal(HTTPConduit.java:1695)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
(HTTPConduit.java:1572)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.close(HTTPCond
uit.java:1373)

       at
org.apache.cxf.io.CacheAndWriteOutputStream.postClose(CacheAndWriteOutputStr
eam.java:56)

       at
org.apache.cxf.io.CachedOutputStream.close(CachedOutputStream.java:225)

       at
org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56)

       at
org.apache.cxf.transport.http.HTTPConduit.close(HTTPConduit.java:673)

       at
org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInter
ceptor.handleMessage(MessageSenderInterceptor.java:63)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.ws.security.trust.AbstractSTSClient.issue(AbstractSTSClient.j
ava:874)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:61)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:55)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.issueToken(SpnegoContextTokenOutInterceptor.java:138)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:75)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)

       at
org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:140)

       at com.sun.proxy.$Proxy58.doubleIt(Unknown Source)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.runKerberosTest
(SpnegoTokenTest.java:241)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.testSpnegoOverS
ymmetric(SpnegoTokenTest.java:132)

       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

       at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

       at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

       at java.lang.reflect.Method.invoke(Unknown Source)

       at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.
java:50)

       at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.j
ava:12)

       at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.ja
va:47)

       at
org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.jav
a:17)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:78)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:57)

       at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)

       at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)

       at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)

       at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)

       at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)

       at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26
)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.run(ParentRunner.java:363)

       at
org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestRef
erence.java:86)

       at
org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:3
8)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:459)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:678)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.
java:382)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner
.java:192)

 

Oct 15, 2018 7:47:39 PM
org.springframework.context.support.AbstractApplicationContext
prepareRefresh

INFO: Refreshing org.apache.cxf.bus.spring.BusApplicationContext@71aaf151:
startup date [Mon Oct 15 19:47:39 CDT 2018]; root of context hierarchy

Oct 15, 2018 7:47:39 PM org.apache.cxf.bus.spring.BusApplicationContext
getConfigResources

INFO: Loaded configuration file
file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cxf
/systest/kerberos/wssec/spnego/client.xml.

Oct 15, 2018 7:47:39 PM
org.springframework.beans.factory.xml.XmlBeanDefinitionReader
loadBeanDefinitions

INFO: Loading XML bean definitions from URL
[file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cx
f/systest/kerberos/wssec/spnego/client.xml]

Oct 15, 2018 7:47:39 PM
org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean
buildServiceFromWSDL

INFO: Creating Service
{http://www.example.org/contract/DoubleIt}DoubleItService from WSDL:
file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cxf
/systest/kerberos/wssec/spnego/DoubleItSpnego.wsdl

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:39 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime 1539650859943,[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.TgsRequest issueTicket

INFO: TGS_REQ ISSUE: authtime 1539650859950,[email protected] for
bob/[email protected]

Oct 15, 2018 7:47:39 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoSymmetricEncryptB
eforeSigningPort.spnego-client.SecurityTokenService

INFO: Outbound Message

---------------------------

ID: 7

Address: http://localhost:9001/DoubleItSpnegoSymmetricEncryptBeforeSigning

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml

Headers: {Accept=[*/*],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue"]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:deaafdc4-737b-4aff-bc4
1-aca16365e62a</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>http://localhost:9001/DoubleItS
pnegoSymmetricEncryptBeforeSigning</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_e53834a7-e4c4-4631-914b-52db11115c15"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>http://localho
st:9001/DoubleItSpnegoSymmetricEncryptBeforeSigning</wsa:Address></wsa:Endpo
intReference></wsp:AppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx
/ws-secureconversation/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySi
ze><wst:BinaryExchange
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGo9SfQKmDEPchLjK2+NmFXkG10Cq0tmPXAdkkac19Xnx+dZvcs
HvTuFbj1mhVO+a02FzpqBkn4rz2rGaBTTvkSt21/MLikuQS5l4Gfxx4hJif8XOlLF116hryGgjVL
gdf5iFbZZ6+3d0tnhSmnzFpCKVKeTqBBRMMB7TzBrMUJqpCPYMYWjavWuIpDUmdGfSY4rg8ugiZr
PEmAGHP0Emx6nkJ7QfSaleChpIHIMIHFoAMCARGigb0EgbomOdCABtqSTmG53UqVo9kIkz5Qaedh
8vqth8YKjx7UiKOsBZjfu29xv/T7m2FZhodg3cFbHI8SkcWRJkeeX6aHxvmiAKMFksbEtsUWuzvv
fSd6LsZhFdjC/KYTIrkG0imkTy62MllMNX6ee00O+r4TJA1mXAFZC9G9WmflNlV+vDNbGR79ASBE
aht74HUL6qMXWCmwyvoKEJOPbnnY3aklEevnHYASZER4P7YwrEzVvQRzrEWsCpsiPU4=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:39 PM
org.apache.cxf.services.DoubleItService.DoubleItSpnegoSymmetricEncryptBefore
SigningPort.DoubleItPortType

INFO: Inbound Message

----------------------------

ID: 8

Address: http://localhost:9001/DoubleItSpnegoSymmetricEncryptBeforeSigning

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml; charset=UTF-8

Headers: {Accept=[*/*], Cache-Control=[no-cache], connection=[keep-alive],
Content-Length=[2250], content-type=[text/xml; charset=UTF-8],
Host=[localhost:9001], Pragma=[no-cache],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue";],
User-Agent=[Apache-CXF/${project.version}]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:deaafdc4-737b-4aff-bc4
1-aca16365e62a</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>http://localhost:9001/DoubleItS
pnegoSymmetricEncryptBeforeSigning</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_e53834a7-e4c4-4631-914b-52db11115c15"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>http://localho
st:9001/DoubleItSpnegoSymmetricEncryptBeforeSigning</wsa:Address></wsa:Endpo
intReference></wsp:AppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx
/ws-secureconversation/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySi
ze><wst:BinaryExchange
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGo9SfQKmDEPchLjK2+NmFXkG10Cq0tmPXAdkkac19Xnx+dZvcs
HvTuFbj1mhVO+a02FzpqBkn4rz2rGaBTTvkSt21/MLikuQS5l4Gfxx4hJif8XOlLF116hryGgjVL
gdf5iFbZZ6+3d0tnhSmnzFpCKVKeTqBBRMMB7TzBrMUJqpCPYMYWjavWuIpDUmdGfSY4rg8ugiZr
PEmAGHP0Emx6nkJ7QfSaleChpIHIMIHFoAMCARGigb0EgbomOdCABtqSTmG53UqVo9kIkz5Qaedh
8vqth8YKjx7UiKOsBZjfu29xv/T7m2FZhodg3cFbHI8SkcWRJkeeX6aHxvmiAKMFksbEtsUWuzvv
fSd6LsZhFdjC/KYTIrkG0imkTy62MllMNX6ee00O+r4TJA1mXAFZC9G9WmflNlV+vDNbGR79ASBE
aht74HUL6qMXWCmwyvoKEJOPbnnY3aklEevnHYASZER4P7YwrEzVvQRzrEWsCpsiPU4=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:39 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:39 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime
1539650859982,bob/[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:40 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}SecurityTokenService#
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}RequestSecurityToken
has thrown exception, unwinding now

org.apache.cxf.interceptor.Fault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:144)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationOb
server.java:121)

       at
org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDes
tination.java:267)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPDestination.doService(JettyHTTP
Destination.java:247)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPHandler.handle(JettyHTTPHandler
.java:79)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java
:257)

       at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java
:1317)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:
205)

       at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:
1219)

       at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144
)

       at
org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHand
lerCollection.java:219)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at org.eclipse.jetty.server.Server.handle(Server.java:531)

       at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)

       at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)

       at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConne
ction.java:281)

       at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)

       at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill
.java:333)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKi
ll.java:310)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouK
ill.java:168)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill
.java:132)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:
762)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:6
80)

       at java.lang.Thread.run(Unknown Source)

Caused by: java.lang.NoSuchMethodError:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.ws.security.tokenstore.EHCacheTokenStore.add(EHCacheTokenStor
e.java:102)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.doIssue(SpnegoContextTokenInInterceptor.java:257)

       at
org.apache.cxf.ws.security.policy.interceptors.STSInvoker.invoke(STSInvoker.
java:124)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.invoke(SpnegoContextTokenInInterceptor.java:1)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInt
erceptor.java:59)

       at java.util.concurrent.Executors$RunnableAdapter.call(Unknown
Source)

       at java.util.concurrent.FutureTask.run(Unknown Source)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$2.run(ServiceInvokerInt
erceptor.java:126)

       at
org.apache.cxf.workqueue.SynchronousExecutor.execute(SynchronousExecutor.jav
a:37)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:131)

       ... 26 more

 

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.SecurityTokenService.SecurityTo
kenService

INFO: Outbound Message

---------------------------

ID: 8

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml

Headers: {}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoSymmetricEncryptB
eforeSigningPort.spnego-client.SecurityTokenService

INFO: Inbound Message

----------------------------

ID: 7

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml;charset=utf-8

Headers: {content-type=[text/xml;charset=utf-8], Date=[Tue, 16 Oct 2018
00:47:39 GMT], Server=[Jetty(9.4.11.v20180605)],
transfer-encoding=[chunked]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:40 PM org.apache.cxf.ws.addressing.soap.MAPCodec
restoreExchange

WARNING: Response message does not contain WS-Addressing properties.  Not
correlating response.

Oct 15, 2018 7:47:40 PM org.apache.cxf.ws.addressing.ContextUtils
retrieveMAPs

WARNING: WS-Addressing - failed to retrieve Message Addressing Properties
from context

Oct 15, 2018 7:47:40 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://www.example.org/contract/DoubleIt}DoubleItService#{http://www.exampl
e.org/contract/DoubleIt}DoubleIt has thrown exception, unwinding now

org.apache.cxf.binding.soap.SoapFault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.unmarshalFa
ult(Soap11FaultInInterceptor.java:87)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:53)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(Abs
tractFaultChainInitiatorObserver.java:112)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:70)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.onMessage(ClientImpl.java:833)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
Internal(HTTPConduit.java:1695)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
(HTTPConduit.java:1572)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.close(HTTPCond
uit.java:1373)

       at
org.apache.cxf.io.CacheAndWriteOutputStream.postClose(CacheAndWriteOutputStr
eam.java:56)

       at
org.apache.cxf.io.CachedOutputStream.close(CachedOutputStream.java:225)

       at
org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56)

       at
org.apache.cxf.transport.http.HTTPConduit.close(HTTPConduit.java:673)

       at
org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInter
ceptor.handleMessage(MessageSenderInterceptor.java:63)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.ws.security.trust.AbstractSTSClient.issue(AbstractSTSClient.j
ava:874)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:61)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:55)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.issueToken(SpnegoContextTokenOutInterceptor.java:138)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:75)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)

       at
org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:140)

       at com.sun.proxy.$Proxy58.doubleIt(Unknown Source)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.runKerberosTest
(SpnegoTokenTest.java:241)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.testSpnegoOverS
ymmetricEncryptBeforeSigning(SpnegoTokenTest.java:158)

       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

       at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

       at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

       at java.lang.reflect.Method.invoke(Unknown Source)

       at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.
java:50)

       at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.j
ava:12)

       at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.ja
va:47)

       at
org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.jav
a:17)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:78)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:57)

       at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)

       at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)

       at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)

       at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)

       at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)

       at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26
)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.run(ParentRunner.java:363)

       at
org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestRef
erence.java:86)

       at
org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:3
8)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:459)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:678)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.
java:382)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner
.java:192)

 

Oct 15, 2018 7:47:40 PM
org.springframework.context.support.AbstractApplicationContext
prepareRefresh

INFO: Refreshing org.apache.cxf.bus.spring.BusApplicationContext@415a3f6a:
startup date [Mon Oct 15 19:47:40 CDT 2018]; root of context hierarchy

Oct 15, 2018 7:47:40 PM org.apache.cxf.bus.spring.BusApplicationContext
getConfigResources

INFO: Loaded configuration file
file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cxf
/systest/kerberos/wssec/spnego/client.xml.

Oct 15, 2018 7:47:40 PM
org.springframework.beans.factory.xml.XmlBeanDefinitionReader
loadBeanDefinitions

INFO: Loading XML bean definitions from URL
[file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cx
f/systest/kerberos/wssec/spnego/client.xml]

Oct 15, 2018 7:47:40 PM
org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean
buildServiceFromWSDL

INFO: Creating Service
{http://www.example.org/contract/DoubleIt}DoubleItService from WSDL:
file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cxf
/systest/kerberos/wssec/spnego/DoubleItSpnego.wsdl

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:40 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime 1539650860139,[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.TgsRequest issueTicket

INFO: TGS_REQ ISSUE: authtime 1539650860147,[email protected] for
bob/[email protected]

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoTransportPort.spn
ego-client.SecurityTokenService

INFO: Outbound Message

---------------------------

ID: 9

Address: https://localhost:9003/DoubleItSpnegoTransport

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml

Headers: {Accept=[*/*],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue"]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:53420084-fcf4-44eb-9de
1-e2ab8112f703</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>https://localhost:9003/DoubleIt
SpnegoTransport</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_d738bf9a-4ece-42f6-a638-96151f4b345b"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>https://localh
ost:9003/DoubleItSpnegoTransport</wsa:Address></wsa:EndpointReference></wsp:
AppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx/ws-secureconversat
ion/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySize><wst:BinaryExcha
nge
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGo6p5mQF7aPJ2Pb2NMwaaB3wn6lFQhvuOGMc3VopdvP4ERYDU5
MheYTSCIxMhkc8Pll4zQ/OdSi1/emYTCNFUS3K7f3zTo6uc1/ekpbL7mlzDpT9nXEzqnyFSBavL8
H+iZ/PFN4y0bnDIQLnifN/d5FgRxdc+bN58yQgsH2ZLqDZFk4qBehA55Bl8BnfSfeoIULRXjxC3D
IBY3f1Eqtwj/cZw6nEREaQyapIHIMIHFoAMCARGigb0Egboqm/8zY2IFdeIv7IaHNS83MoiGSTmI
vaorB17fkVp3UneCL0H14AFFpBsKrBLqnioVKa2wy0i04DSmrSi28MU2vhdfMnoTJRK1K5uvk3SU
wifsMAQorrt/w0rRsb82ZYTdtBpd9bStt0nkBvOAGaAQRrZOQuix3EqqqGBzu2bC4dGeYH/pLKuQ
hAOZ5eGfF3HZE5IGV3jRM9EQxv2+LYRx+na6LK2UfoD2tuR29meIVpfYcrE70yqUfiM=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.DoubleItService.DoubleItSpnegoTransportPort.DoubleIt
PortType

INFO: Inbound Message

----------------------------

ID: 10

Address: https://localhost:9003/DoubleItSpnegoTransport

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml; charset=UTF-8

Headers: {Accept=[*/*], Cache-Control=[no-cache], connection=[keep-alive],
Content-Length=[2212], content-type=[text/xml; charset=UTF-8],
Host=[localhost:9003], Pragma=[no-cache],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue";],
User-Agent=[Apache-CXF/${project.version}]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:53420084-fcf4-44eb-9de
1-e2ab8112f703</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>https://localhost:9003/DoubleIt
SpnegoTransport</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_d738bf9a-4ece-42f6-a638-96151f4b345b"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>https://localh
ost:9003/DoubleItSpnegoTransport</wsa:Address></wsa:EndpointReference></wsp:
AppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx/ws-secureconversat
ion/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySize><wst:BinaryExcha
nge
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGo6p5mQF7aPJ2Pb2NMwaaB3wn6lFQhvuOGMc3VopdvP4ERYDU5
MheYTSCIxMhkc8Pll4zQ/OdSi1/emYTCNFUS3K7f3zTo6uc1/ekpbL7mlzDpT9nXEzqnyFSBavL8
H+iZ/PFN4y0bnDIQLnifN/d5FgRxdc+bN58yQgsH2ZLqDZFk4qBehA55Bl8BnfSfeoIULRXjxC3D
IBY3f1Eqtwj/cZw6nEREaQyapIHIMIHFoAMCARGigb0Egboqm/8zY2IFdeIv7IaHNS83MoiGSTmI
vaorB17fkVp3UneCL0H14AFFpBsKrBLqnioVKa2wy0i04DSmrSi28MU2vhdfMnoTJRK1K5uvk3SU
wifsMAQorrt/w0rRsb82ZYTdtBpd9bStt0nkBvOAGaAQRrZOQuix3EqqqGBzu2bC4dGeYH/pLKuQ
hAOZ5eGfF3HZE5IGV3jRM9EQxv2+LYRx+na6LK2UfoD2tuR29meIVpfYcrE70yqUfiM=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:40 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime
1539650860203,bob/[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:40 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}SecurityTokenService#
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}RequestSecurityToken
has thrown exception, unwinding now

org.apache.cxf.interceptor.Fault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:144)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationOb
server.java:121)

       at
org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDes
tination.java:267)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPDestination.doService(JettyHTTP
Destination.java:247)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPHandler.handle(JettyHTTPHandler
.java:79)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java
:257)

       at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java
:1317)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:
205)

       at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:
1219)

       at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144
)

       at
org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHand
lerCollection.java:219)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at org.eclipse.jetty.server.Server.handle(Server.java:531)

       at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)

       at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)

       at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConne
ction.java:281)

       at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)

       at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:291)

       at
org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:151)

       at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)

       at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill
.java:333)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKi
ll.java:310)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouK
ill.java:168)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.jav
a:126)

       at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(Rese
rvedThreadExecutor.java:366)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:
762)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:6
80)

       at java.lang.Thread.run(Unknown Source)

Caused by: java.lang.NoSuchMethodError:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.ws.security.tokenstore.EHCacheTokenStore.add(EHCacheTokenStor
e.java:102)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.doIssue(SpnegoContextTokenInInterceptor.java:257)

       at
org.apache.cxf.ws.security.policy.interceptors.STSInvoker.invoke(STSInvoker.
java:124)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.invoke(SpnegoContextTokenInInterceptor.java:1)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInt
erceptor.java:59)

       at java.util.concurrent.Executors$RunnableAdapter.call(Unknown
Source)

       at java.util.concurrent.FutureTask.run(Unknown Source)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$2.run(ServiceInvokerInt
erceptor.java:126)

       at
org.apache.cxf.workqueue.SynchronousExecutor.execute(SynchronousExecutor.jav
a:37)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:131)

       ... 30 more

 

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.SecurityTokenService.SecurityTo
kenService

INFO: Outbound Message

---------------------------

ID: 10

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml

Headers: {}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoTransportPort.spn
ego-client.SecurityTokenService

INFO: Inbound Message

----------------------------

ID: 9

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml;charset=utf-8

Headers: {content-type=[text/xml;charset=utf-8], Date=[Tue, 16 Oct 2018
00:47:40 GMT], Server=[Jetty(9.4.11.v20180605)],
transfer-encoding=[chunked]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:40 PM org.apache.cxf.ws.addressing.soap.MAPCodec
restoreExchange

WARNING: Response message does not contain WS-Addressing properties.  Not
correlating response.

Oct 15, 2018 7:47:40 PM org.apache.cxf.ws.addressing.ContextUtils
retrieveMAPs

WARNING: WS-Addressing - failed to retrieve Message Addressing Properties
from context

Oct 15, 2018 7:47:40 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://www.example.org/contract/DoubleIt}DoubleItService#{http://www.exampl
e.org/contract/DoubleIt}DoubleIt has thrown exception, unwinding now

org.apache.cxf.binding.soap.SoapFault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.unmarshalFa
ult(Soap11FaultInInterceptor.java:87)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:53)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(Abs
tractFaultChainInitiatorObserver.java:112)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:70)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.onMessage(ClientImpl.java:833)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
Internal(HTTPConduit.java:1695)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
(HTTPConduit.java:1572)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.close(HTTPCond
uit.java:1373)

       at
org.apache.cxf.io.CacheAndWriteOutputStream.postClose(CacheAndWriteOutputStr
eam.java:56)

       at
org.apache.cxf.io.CachedOutputStream.close(CachedOutputStream.java:225)

       at
org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56)

       at
org.apache.cxf.transport.http.HTTPConduit.close(HTTPConduit.java:673)

       at
org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInter
ceptor.handleMessage(MessageSenderInterceptor.java:63)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.ws.security.trust.AbstractSTSClient.issue(AbstractSTSClient.j
ava:874)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:61)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:55)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.issueToken(SpnegoContextTokenOutInterceptor.java:138)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:75)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)

       at
org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:140)

       at com.sun.proxy.$Proxy58.doubleIt(Unknown Source)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.runKerberosTest
(SpnegoTokenTest.java:241)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.testSpnegoOverT
ransport(SpnegoTokenTest.java:171)

       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

       at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

       at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

       at java.lang.reflect.Method.invoke(Unknown Source)

       at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.
java:50)

       at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.j
ava:12)

       at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.ja
va:47)

       at
org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.jav
a:17)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:78)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:57)

       at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)

       at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)

       at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)

       at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)

       at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)

       at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26
)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.run(ParentRunner.java:363)

       at
org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestRef
erence.java:86)

       at
org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:3
8)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:459)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:678)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.
java:382)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner
.java:192)

 

Oct 15, 2018 7:47:40 PM
org.springframework.context.support.AbstractApplicationContext
prepareRefresh

INFO: Refreshing org.apache.cxf.bus.spring.BusApplicationContext@119290b9:
startup date [Mon Oct 15 19:47:40 CDT 2018]; root of context hierarchy

Oct 15, 2018 7:47:40 PM org.apache.cxf.bus.spring.BusApplicationContext
getConfigResources

INFO: Loaded configuration file
file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cxf
/systest/kerberos/wssec/spnego/client.xml.

Oct 15, 2018 7:47:40 PM
org.springframework.beans.factory.xml.XmlBeanDefinitionReader
loadBeanDefinitions

INFO: Loading XML bean definitions from URL
[file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cx
f/systest/kerberos/wssec/spnego/client.xml]

Oct 15, 2018 7:47:40 PM
org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean
buildServiceFromWSDL

INFO: Creating Service
{http://www.example.org/contract/DoubleIt}DoubleItService from WSDL:
file:/C:/lucidworks/cxf/systests/kerberos/target/test-classes/org/apache/cxf
/systest/kerberos/wssec/spnego/DoubleItSpnego.wsdl

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:40 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime 1539650860353,[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.TgsRequest issueTicket

INFO: TGS_REQ ISSUE: authtime 1539650860363,[email protected] for
bob/[email protected]

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoSymmetricDerivedP
ort.spnego-client.SecurityTokenService

INFO: Outbound Message

---------------------------

ID: 11

Address: http://localhost:9001/DoubleItSpnegoSymmetricDerived

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml

Headers: {Accept=[*/*],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue"]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:01875216-b23d-4a8b-bbb
8-8de1a4b7b90f</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>http://localhost:9001/DoubleItS
pnegoSymmetricDerived</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_e1924146-000c-4c87-856a-bfb922875a7c"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>http://localho
st:9001/DoubleItSpnegoSymmetricDerived</wsa:Address></wsa:EndpointReference>
</wsp:AppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx/ws-securecon
versation/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySize><wst:Binar
yExchange
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGopFQwyeOwp2Hik0FHUic7x9pgHOF6VVc4A4pA/HedIFVfMntH
spfvtKyCoojK10UDxgTyXa8ZtWBT15u/ikodJmtLcrtEXLWIa6kDf0Ph/66+mcyyFfjOrLqTvtys
SpArDoLM8Ht6mRv3po7fELCeLCG8vLydK+4ENW0uq7zSEKj6V6Ba/C8Wg0X8C0K6RHxN/sYctkOe
cmQyezudbWzaCt9OiKM4PcRtpIHIMIHFoAMCARGigb0EgboApGXncZqeNzEaWDFO77RwssxBjkT1
d5W0HucVfSQwIklB7GG/Erer5Ai93sJHlUQGagNOMDIqMEZQtLaS975vCLFhDA+Bz28gf4IKhooC
MR+Rpt5za+TWIgTGY0jChMVcUvCgfTDr+p4jNKuplL7W0zM2P4et2YiKy0YZM0pPuS+QpyEHx27b
W6TCeZLxtTvsis5jJf+QhEb2f77JTheaaSrv6+bF3Ar+fpOMT+YhBxkK9vqmcei2V7s=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.DoubleItService.DoubleItSpnegoSymmetricDerivedPort.D
oubleItPortType

INFO: Inbound Message

----------------------------

ID: 12

Address: http://localhost:9001/DoubleItSpnegoSymmetricDerived

Encoding: UTF-8

Http-Method: POST

Content-Type: text/xml; charset=UTF-8

Headers: {Accept=[*/*], Cache-Control=[no-cache], connection=[keep-alive],
Content-Length=[2224], content-type=[text/xml; charset=UTF-8],
Host=[localhost:9001], Pragma=[no-cache],
SOAPAction=["http://docs.oasis-open.org/ws-sx/ws-trust/200512/RST/Issue";],
User-Agent=[Apache-CXF/${project.version}]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Header><Action
xmlns="http://www.w3.org/2005/08/addressing";>http://docs.oasis-open.org/ws-s
x/ws-trust/200512/RST/Issue</Action><MessageID
xmlns="http://www.w3.org/2005/08/addressing";>urn:uuid:01875216-b23d-4a8b-bbb
8-8de1a4b7b90f</MessageID><To
xmlns="http://www.w3.org/2005/08/addressing";>http://localhost:9001/DoubleItS
pnegoSymmetricDerived</To><ReplyTo
xmlns="http://www.w3.org/2005/08/addressing";><Address>http://www.w3.org/2005
/08/addressing/anonymous</Address></ReplyTo></soap:Header><soap:Body><wst:Re
questSecurityToken
xmlns:wst="http://docs.oasis-open.org/ws-sx/ws-trust/200512";
Context="_e1924146-000c-4c87-856a-bfb922875a7c"><wst:RequestType>http://docs
.oasis-open.org/ws-sx/ws-trust/200512/Issue</wst:RequestType><wsp:AppliesTo
xmlns:wsp="http://www.w3.org/ns/ws-policy";><wsa:EndpointReference
xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:Address>http://localho
st:9001/DoubleItSpnegoSymmetricDerived</wsa:Address></wsa:EndpointReference>
</wsp:AppliesTo><wst:TokenType>http://docs.oasis-open.org/ws-sx/ws-securecon
versation/200512/sct</wst:TokenType><wst:KeySize>256</wst:KeySize><wst:Binar
yExchange
EncodingType="http://docs.oasis-open.org/wss/2004/01/oasis-200401-wss-soap-m
essage-security-1.0#Base64Binary"
ValueType="http://docs.oasis-open.org/ws-sx/ws-trust/200512/spnego";>YIICNQYG
KwYBBQUCoIICKTCCAiWgDTALBgkqhkiG9xIBAgKhBAMCATaiggIMBIICCGCCAgQGCSqGSIb3EgEC
AgEAboIB8zCCAe+gAwIBBaEDAgEOogcDBQAAAAAAo4IBDWGCAQkwggEFoAMCAQWhFxsVc2Vydmlj
ZS53cy5hcGFjaGUub3JnoicwJaADAgEAoR4wHBsDYm9iGxVzZXJ2aWNlLndzLmFwYWNoZS5vcmej
gbswgbigAwIBEaEDAgEBooGrBIGopFQwyeOwp2Hik0FHUic7x9pgHOF6VVc4A4pA/HedIFVfMntH
spfvtKyCoojK10UDxgTyXa8ZtWBT15u/ikodJmtLcrtEXLWIa6kDf0Ph/66+mcyyFfjOrLqTvtys
SpArDoLM8Ht6mRv3po7fELCeLCG8vLydK+4ENW0uq7zSEKj6V6Ba/C8Wg0X8C0K6RHxN/sYctkOe
cmQyezudbWzaCt9OiKM4PcRtpIHIMIHFoAMCARGigb0EgboApGXncZqeNzEaWDFO77RwssxBjkT1
d5W0HucVfSQwIklB7GG/Erer5Ai93sJHlUQGagNOMDIqMEZQtLaS975vCLFhDA+Bz28gf4IKhooC
MR+Rpt5za+TWIgTGY0jChMVcUvCgfTDr+p4jNKuplL7W0zM2P4et2YiKy0YZM0pPuS+QpyEHx27b
W6TCeZLxtTvsis5jJf+QhEb2f77JTheaaSrv6+bF3Ar+fpOMT+YhBxkK9vqmcei2V7s=</wst:Bi
naryExchange><wst:Renewing/></wst:RequestSecurityToken></soap:Body></soap:En
velope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.KdcRequest preauth

INFO: The preauth data is empty.

Oct 15, 2018 7:47:40 PM org.apache.kerby.kerberos.kerb.server.KdcHandler
handleRecoverableException

INFO: KRB error occurred while processing request: Additional
pre-authentication required

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.request.AsRequest issueTicket

INFO: AS_REQ ISSUE: authtime
1539650860404,bob/[email protected] for
krbtgt/[email protected]

Oct 15, 2018 7:47:40 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}SecurityTokenService#
{http://docs.oasis-open.org/ws-sx/ws-trust/200512/wsdl}RequestSecurityToken
has thrown exception, unwinding now

org.apache.cxf.interceptor.Fault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:144)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationOb
server.java:121)

       at
org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDes
tination.java:267)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPDestination.doService(JettyHTTP
Destination.java:247)

       at
org.apache.cxf.transport.http_jetty.JettyHTTPHandler.handle(JettyHTTPHandler
.java:79)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java
:257)

       at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java
:1317)

       at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:
205)

       at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:
1219)

       at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144
)

       at
org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHand
lerCollection.java:219)

       at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:1
32)

       at org.eclipse.jetty.server.Server.handle(Server.java:531)

       at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)

       at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)

       at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConne
ction.java:281)

       at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)

       at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill
.java:333)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKi
ll.java:310)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouK
ill.java:168)

       at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.jav
a:126)

       at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(Rese
rvedThreadExecutor.java:366)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:
762)

       at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:6
80)

       at java.lang.Thread.run(Unknown Source)

Caused by: java.lang.NoSuchMethodError:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.ws.security.tokenstore.EHCacheTokenStore.add(EHCacheTokenStor
e.java:102)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.doIssue(SpnegoContextTokenInInterceptor.java:257)

       at
org.apache.cxf.ws.security.policy.interceptors.STSInvoker.invoke(STSInvoker.
java:124)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenInIntercept
or$SpnegoSTSInvoker.invoke(SpnegoContextTokenInInterceptor.java:1)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInt
erceptor.java:59)

       at java.util.concurrent.Executors$RunnableAdapter.call(Unknown
Source)

       at java.util.concurrent.FutureTask.run(Unknown Source)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor$2.run(ServiceInvokerInt
erceptor.java:126)

       at
org.apache.cxf.workqueue.SynchronousExecutor.execute(SynchronousExecutor.jav
a:37)

       at
org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceIn
vokerInterceptor.java:131)

       ... 27 more

 

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.SecurityTokenService.SecurityTo
kenService

INFO: Outbound Message

---------------------------

ID: 12

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml

Headers: {}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:40 PM
org.apache.cxf.services.SecurityTokenService.DoubleItSpnegoSymmetricDerivedP
ort.spnego-client.SecurityTokenService

INFO: Inbound Message

----------------------------

ID: 11

Response-Code: 500

Encoding: UTF-8

Content-Type: text/xml;charset=utf-8

Headers: {content-type=[text/xml;charset=utf-8], Date=[Tue, 16 Oct 2018
00:47:40 GMT], Server=[Jetty(9.4.11.v20180605)],
transfer-encoding=[chunked]}

Payload: <soap:Envelope
xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/";><soap:Body><soap:Faul
t><faultcode>soap:Server</faultcode><faultstring>net.sf.ehcache.Element.&lt;
init>(Ljava/lang/Object;Ljava/lang/Object;II)V</faultstring></soap:Fault></s
oap:Body></soap:Envelope>

--------------------------------------

Oct 15, 2018 7:47:40 PM org.apache.cxf.ws.addressing.soap.MAPCodec
restoreExchange

WARNING: Response message does not contain WS-Addressing properties.  Not
correlating response.

Oct 15, 2018 7:47:40 PM org.apache.cxf.ws.addressing.ContextUtils
retrieveMAPs

WARNING: WS-Addressing - failed to retrieve Message Addressing Properties
from context

Oct 15, 2018 7:47:40 PM org.apache.cxf.phase.PhaseInterceptorChain
doDefaultLogging

WARNING: Interceptor for
{http://www.example.org/contract/DoubleIt}DoubleItService#{http://www.exampl
e.org/contract/DoubleIt}DoubleIt has thrown exception, unwinding now

org.apache.cxf.binding.soap.SoapFault:
net.sf.ehcache.Element.<init>(Ljava/lang/Object;Ljava/lang/Object;II)V

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.unmarshalFa
ult(Soap11FaultInInterceptor.java:87)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:53)

       at
org.apache.cxf.binding.soap.interceptor.Soap11FaultInInterceptor.handleMessa
ge(Soap11FaultInInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at
org.apache.cxf.interceptor.AbstractFaultChainInitiatorObserver.onMessage(Abs
tractFaultChainInitiatorObserver.java:112)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:70)

       at
org.apache.cxf.binding.soap.interceptor.CheckFaultInterceptor.handleMessage(
CheckFaultInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.onMessage(ClientImpl.java:833)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
Internal(HTTPConduit.java:1695)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.handleResponse
(HTTPConduit.java:1572)

       at
org.apache.cxf.transport.http.HTTPConduit$WrappedOutputStream.close(HTTPCond
uit.java:1373)

       at
org.apache.cxf.io.CacheAndWriteOutputStream.postClose(CacheAndWriteOutputStr
eam.java:56)

       at
org.apache.cxf.io.CachedOutputStream.close(CachedOutputStream.java:225)

       at
org.apache.cxf.transport.AbstractConduit.close(AbstractConduit.java:56)

       at
org.apache.cxf.transport.http.HTTPConduit.close(HTTPConduit.java:673)

       at
org.apache.cxf.interceptor.MessageSenderInterceptor$MessageSenderEndingInter
ceptor.handleMessage(MessageSenderInterceptor.java:63)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.ws.security.trust.AbstractSTSClient.issue(AbstractSTSClient.j
ava:874)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:61)

       at
org.apache.cxf.ws.security.trust.STSClient.requestSecurityToken(STSClient.ja
va:55)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.issueToken(SpnegoContextTokenOutInterceptor.java:138)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:75)

       at
org.apache.cxf.ws.security.policy.interceptors.SpnegoContextTokenOutIntercep
tor.handleMessage(SpnegoContextTokenOutInterceptor.java:1)

       at
org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain
.java:308)

       at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:537)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:446)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:361)

       at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319)

       at
org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96)

       at
org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:140)

       at com.sun.proxy.$Proxy58.doubleIt(Unknown Source)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.runKerberosTest
(SpnegoTokenTest.java:241)

       at
org.apache.cxf.systest.kerberos.wssec.spnego.SpnegoTokenTest.testSpnegoOverS
ymmetricDerived(SpnegoTokenTest.java:145)

       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

       at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)

       at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

       at java.lang.reflect.Method.invoke(Unknown Source)

       at
org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.
java:50)

       at
org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.j
ava:12)

       at
org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.ja
va:47)

       at
org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.jav
a:17)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:78)

       at
org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.jav
a:57)

       at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)

       at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)

       at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)

       at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)

       at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)

       at
org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26
)

       at
org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)

       at org.junit.runners.ParentRunner.run(ParentRunner.java:363)

       at
org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestRef
erence.java:86)

       at
org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:3
8)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:459)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRu
nner.java:678)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.
java:382)

       at
org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner
.java:192)

 

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@4930539b{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@57b75756{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@2e34384c{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@545e57d7{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@2ba33e2c{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@3fae596{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM
org.springframework.context.support.AbstractApplicationContext doClose

INFO: Closing org.apache.cxf.bus.spring.BusApplicationContext@7e97551f:
startup date [Mon Oct 15 19:47:37 CDT 2018]; root of context hierarchy

Oct 15, 2018 7:47:40 PM
org.apache.cxf.transport.http_jetty.JettyHTTPServerEngine shutdown

WARNING: Failed to shutdown Jetty server on port 9001 because it is still in
use

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.AbstractConnector doStop

INFO: Stopped ServerConnector@258ee7de{HTTP/1.1,[http/1.1]}{localhost:9002}

Oct 15, 2018 7:47:40 PM
org.apache.cxf.transport.http_jetty.JettyHTTPServerEngine shutdown

WARNING: Failed to shutdown Jetty server on port 9003 because it is still in
use

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.AbstractConnector doStop

INFO: Stopped ServerConnector@5a67e962{ssl,[ssl, http/1.1]}{0.0.0.0:9004}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@138a7441{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@779dfe55{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@775594f2{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@988246e{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@4d6f623d{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@5e76a2bb{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.handler.ContextHandler
doStop

INFO: Stopped o.e.j.s.h.ContextHandler@4f449e8f{/,null,UNAVAILABLE}

Oct 15, 2018 7:47:40 PM
org.springframework.context.support.AbstractApplicationContext doClose

INFO: Closing org.apache.cxf.bus.spring.BusApplicationContext@c730b35:
startup date [Mon Oct 15 19:47:35 CDT 2018]; root of context hierarchy

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.AbstractConnector doStop

INFO: Stopped ServerConnector@288a4658{HTTP/1.1,[http/1.1]}{localhost:9001}

Oct 15, 2018 7:47:40 PM org.eclipse.jetty.server.AbstractConnector doStop

INFO: Stopped ServerConnector@381cad29{ssl,[ssl, http/1.1]}{0.0.0.0:9003}

Oct 15, 2018 7:47:40 PM
org.apache.kerby.kerberos.kerb.server.impl.DefaultInternalKdcServerImpl
doStop

INFO: Default Internal kdc server stopped.

 

Seems like a class incompatibility with respect to the Ehcache library. 

 

Am I missing something? Perhaps some environment variables, etc? 

 

Much thanks,

-Nicholas

Reply via email to