You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@ode.apache.org by Jens Müller <bl...@tessarakt.de> on 2009/10/09 18:02:01 UTC

Re: Securing web service call with Rampart - "Error in obtaining a token"

On 09.10.2009 17:43, Jens Müller wrote:
> On 08.10.2009 16:27, Jens Müller wrote:
>> I have the following config:
>>
>> * Apache Tomcat 6.0.20
>>  * Apache Axis2 1.5
>>   * Apache Rampart 1.5 RC1 (installed according to
>> http://wso2.org/library/knowledge-base/run-rampart-samples-apache-tomcat)
>>  * Apache Ode 1.3.3
>>
> 
> OK, I changed the sample.axis03 to this:

After providing the password callback handler class, I get this:

Any ideas?

> DEBUG - GeronimoLog.debug(66) | ODE initializing
> DEBUG - GeronimoLog.debug(66) | BPEL SERVER initializing
> DEBUG - GeronimoLog.debug(66) | Initializing HTTP connection manager
> DEBUG - GeronimoLog.debug(66) | http.connection-manager.max-per-host=10
> DEBUG - GeronimoLog.debug(66) | http.connection-manager.max-total=10
> DEBUG - GeronimoLog.debug(66) | Process store event: {ProcessStoreEvent#ACTVIATED:{http://example.com/Test1/Pool}Pool-1}
> DEBUG - GeronimoLog.debug(66) | Unregister process: serviceId={http://example.com/Test1/Pool}Pool-1, process=null
> DEBUG - GeronimoLog.debug(66) | register: {http://example.com/Test1/Pool}Pool-1
> DEBUG - GeronimoLog.debug(66) | Registering process {http://example.com/Test1/Pool}Pool-1 with server.
> DEBUG - GeronimoLog.debug(66) | Register process: serviceId={http://example.com/Test1/Pool}CanonicServiceForPool1:canonicPort, process=BpelProcess[{http:
> //example.com/Test1/Pool}Pool-1]
> DEBUG - GeronimoLog.debug(66) | Activating {http://example.com/Test1/Pool}Pool-1
> DEBUG - GeronimoLog.debug(66) | Create AxisService: service={http://example.com/Test1/Pool}CanonicServiceForPool1 port=canonicPort WSDL=Test1-Pool.wsdl B
> PEL=Test1-Pool.bpel
> DEBUG - GeronimoLog.debug(66) | Looking for Axis2 service configuration file: file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.
> 2/CanonicServiceForPool1.axis2
> DEBUG - GeronimoLog.debug(66) | Axis2 service configuration not found: file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.2/Canon
> icServiceForPool1.axis2
> DEBUG - GeronimoLog.debug(66) | Destroying service {http://example.com/Test1/Pool}CanonicServiceForPool1 port canonicPort
> DEBUG - GeronimoLog.debug(66) | Couldn't find service {http://example.com/Test1/Pool}CanonicServiceForPool1 port canonicPort to destroy.
> DEBUG - GeronimoLog.debug(66) | Created Axis2 service {http://example.com/Test1/Pool}CanonicServiceForPool1
> DEBUG - GeronimoLog.debug(66) | Activated {http://example.com/Test1/Pool}Pool-1 myrole poolAndPool1PlkVar: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1f9
> b804
> DEBUG - GeronimoLog.debug(66) | Activated {http://example.com/Test1/Pool}Pool-1
> DEBUG - GeronimoLog.debug(66) | Rehydrating process {http://example.com/Test1/Pool}Pool-1
> DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {http://example.com/Test1/Pool}Pool-1: partnerlink pool0AndPoolForPortSample03Htt
> pSoap12EndpointPlkVar --> {http://sample03.policy.samples.rampart.apache.org}sample03:sample03HttpSoap11Endpoint
> DEBUG - GeronimoLog.debug(66) | Creating SOAP-bound external service {http://sample03.policy.samples.rampart.apache.org}sample03
> DEBUG - GeronimoLog.debug(66) | Created external service {http://sample03.policy.samples.rampart.apache.org}sample03
> DEBUG - GeronimoLog.debug(66) | Activated {http://example.com/Test1/Pool}Pool-1 partnerrole pool0AndPoolForPortSample03HttpSoap12EndpointPlkVar: EPR is o
> rg.apache.ode.bpel.epr.WSAEndpoint@128453c
> DEBUG - GeronimoLog.debug(66) | Creating new process DAO for {http://example.com/Test1/Pool}Pool-1 (guid=hqejbhcnphr4nl24ndvkqn)...
> DEBUG - GeronimoLog.debug(66) | Found ProcessDAO for {http://example.com/Test1/Pool}Pool-1 with GUID hqejbhcnphr4nl24ndvkqn
> INFO - GeronimoLog.info(79) | Created new process DAO for {http://example.com/Test1/Pool}Pool-1 (guid=hqejbhcnphr4nl24ndvkqn).
> INFO - GeronimoLog.info(79) | Registered process {http://example.com/Test1/Pool}Pool-1.
> DEBUG - GeronimoLog.debug(66) | (Re)scheduling cron scheduled jobs on store event: {ProcessStoreEvent#ACTVIATED:{http://example.com/Test1/Pool}Pool-1}
> INFO - GeronimoLog.info(79) | Cancelling PROCESS CRON jobs for: {http://example.com/Test1/Pool}Pool-1
> INFO - GeronimoLog.info(79) | Scheduling PROCESS CRON jobs for: {http://example.com/Test1/Pool}Pool-1
> DEBUG - GeronimoLog.debug(66) | BPEL SERVER starting.
> INFO - GeronimoLog.info(79) | BPEL Server Started.
> DEBUG - GeronimoLog.debug(66) | No deploy-ext.properties found.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration: C:\apache-tomcat-6.0.20\webapps\ode\WEB-INF\conf\schedules.xml
> DEBUG - GeronimoLog.debug(66) | Initializing Deployment Web Service
> DEBUG - GeronimoLog.debug(66) | Starting scheduler
> DEBUG - GeronimoLog.debug(66) | Initializing JCA adapter.
> INFO - GeronimoLog.info(79) | Poller started.
> INFO - GeronimoLog.info(79) | Process deployment polling started on path C:\apache-tomcat-6.0.20\webapps\ode\WEB-INF\processes.
> INFO - GeronimoLog.info(79) | ODE Service Engine has been started.
> DEBUG - GeronimoLog.debug(66) | Process store event: {ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{http://example.com/Test1/Pool}Pool-1}
> DEBUG - GeronimoLog.debug(66) | Ignoring store event: {ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{http://example.com/Test1/Pool}Pool-1}
> DEBUG - GeronimoLog.debug(66) | (Re)scheduling cron scheduled jobs on store event: {ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{http://example.com/Test1
> /Pool}Pool-1}
> INFO - GeronimoLog.info(79) | Cancelling PROCESS CRON jobs for: {http://example.com/Test1/Pool}Pool-1
> INFO - GeronimoLog.info(79) | Scheduling PROCESS CRON jobs for: {http://example.com/Test1/Pool}Pool-1
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> [INFO] Clustering has been disabled
> [INFO] Deploying module: addressing-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/addressing-1.5.mar
> [INFO] Deploying module: jaxws-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/axis2-jaxws-mar-1.5.mar
> [INFO] Deploying module: script-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/axis2-scripting-1.5.mar
> [INFO] Deploying module: metadataExchange-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/mex-1.5.mar
> [INFO] Deploying module: mtompolicy-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/mtompolicy-1.5.mar
> [INFO] Deploying module: ping-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/ping-1.5.mar
> [INFO] Deploying module: rahas-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/rahas-1.5.mar
> [INFO] Deploying module: rampart-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/rampart-1.5.mar
> [INFO] Deploying module: soapmonitor-1.5 - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/soapmonitor-1.5.mar
> [INFO] Deploying module: metadataExchange - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/lib/mex-1.5-impl.jar
> [INFO] Deploying Web service: sample03.aar - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/services/sample03.aar
> [INFO] Deploying Web service: version-1.5.aar - file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/services/version-1.5.aar
> 09.10.2009 17:50:50 org.apache.coyote.http11.Http11Protocol start
> INFO: Starting Coyote HTTP/1.1 on http-8081
> 09.10.2009 17:50:51 org.apache.jk.common.ChannelSocket init
> INFO: JK: ajp13 listening on /0.0.0.0:8109
> 09.10.2009 17:50:51 org.apache.jk.server.JkMain start
> INFO: Jk running ID=0 time=0/16  config=null
> 09.10.2009 17:50:51 org.apache.catalina.startup.Catalina start
> INFO: Server startup in 10286 ms
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> DEBUG - GeronimoLog.debug(66) | Checking for Service using target endpoint address : http://localhost:8081/ode/processes/RampartOdeTest/Test1/Pool/Pool1
> DEBUG - GeronimoLog.debug(66) | Found service in registry from name RampartOdeTest/Test1/Pool/Pool1: RampartOdeTest/Test1/Pool/Pool1
> DEBUG - GeronimoLog.debug(66) | The system is checking for the operation using the following WSAAction: http://example.com/Test1/Pool/ForPool1/EventStart
> Message
> DEBUG - GeronimoLog.debug(66) | Received request message for RampartOdeTest/Test1/Pool/Pool1.{http://example.com/Test1/Pool}EventStartMessage
> DEBUG - GeronimoLog.debug(66) | Starting transaction.
> DEBUG - GeronimoLog.debug(66) | Routed: svcQname {http://example.com/Test1/Pool}CanonicServiceForPool1 --> [BpelProcess[{http://example.com/Test1/Pool}Po
> ol-1]]
> DEBUG - GeronimoLog.debug(66) | ODE routed to operation Operation: name=EventStartMessage
> style=REQUEST_RESPONSE,1
> Input: name=EventStartMessage
> Message: name={http://example.com/Test1/Pool}EventStartMessageRequest
> Part: name=body
> elementName={http://example.com/Test1/Pool}EventStartMessageRequest
> Output: name=EventStartMessageResponse
> Message: name={http://example.com/Test1/Pool}EventStartMessageResponse
> Part: name=body
> elementName={http://example.com/Test1/Pool}EventStartMessageResponse from service {http://example.com/Test1/Pool}CanonicServiceForPool1
> DEBUG - GeronimoLog.debug(66) | SET MEX property isTwoWay = true
> DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {http://example.com/Test
> 1/Pool}CanonicServiceForPool1.EventStartMessage(...)}
> DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0" encoding="UTF-8"?>
> <message><body><EventStartMessageRequest xmlns="http://example.com/Test1/Pool" xmlns:pool="http://example.com/Test1/Pool" xmlns:soapenv="http://schemas.x
> mlsoap.org/soap/envelope/">?</EventStartMessageRequest></body></message>
> DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> BpelProcess[{http://example.com/Test1/Pool}Pool-1]
> DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {http://example.com/Test1/Poo
> l}CanonicServiceForPool1.EventStartMessage(...)}
> DEBUG - GeronimoLog.debug(66) | Commiting transaction.
> DEBUG - GeronimoLog.debug(66) | handleWorkEvent: InvokeInternal event for mexid hqejbhcnphr4nogdhmowu8
> DEBUG - GeronimoLog.debug(66) | >> handleWorkEvent(jobData={mexid=hqejbhcnphr4nogdhmowu8, pid={http://example.com/Test1/Pool}Pool-1, type=INVOKE_INTERNAL
> })
> DEBUG - GeronimoLog.debug(66) | InvokeInternal event for mexid hqejbhcnphr4nogdhmowu8
> DEBUG - GeronimoLog.debug(66) | GET MEX property org.apache.ode.bpel.myRoleSessionId = null
> DEBUG - GeronimoLog.debug(66) | GET MEX property org.apache.ode.bpel.myRoleSessionId = null
> DEBUG - GeronimoLog.debug(66) | GET MEX property org.apache.ode.bpel.partnerRoleSessionId = null
> DEBUG - GeronimoLog.debug(66) | INPUTMSG: poolAndPool1PlkVar.EventStartMessage: MSG RCVD keys=[] mySessionId=null partnerSessionId=null
> DEBUG - GeronimoLog.debug(66) | INPUTMSG: poolAndPool1PlkVar.EventStartMessage: routing failed, CREATING NEW INSTANCE
> DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance 8551. INDEXED STATE={}
> DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: USING CORRELATOR poolAndPool1PlkVar.EventStartMessage
> DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: CHECKING for NEW INSTANCE match
> DEBUG - GeronimoLog.debug(66) | INPUTMSGMATCH: Changing process instance state from ready to active
> DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: FOUND match for NEW instance mexRef={MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4
> nogdhmowu7] calling {http://example.com/Test1/Pool}CanonicServiceForPool1.EventStartMessage(...)}
> DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER: partnerLink={PartnerLinkInstance partnerLinkDecl=OPartnerLink#21,scopeInstanceId=8601}, op=echo channel
> =InvokeResponseChannel#23)
> DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null partnerSessionId=null
> DEBUG - GeronimoLog.debug(66) | SET MEX property activityId = 47
> DEBUG - GeronimoLog.debug(66) | Routed: svcQname {http://sample03.policy.samples.rampart.apache.org}sample03 --> null
> DEBUG - GeronimoLog.debug(66) | Creating invocation check event in 180000ms for mexid hqejbhcnphr4nogdhmowub
> DEBUG - GeronimoLog.debug(66) | SET MEX property invokeCheckJobId = hqejbhcnphr4nogdhmowuc
> DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: echo
> DEBUG - GeronimoLog.debug(66) | The service to invoke is the external service {http://sample03.policy.samples.rampart.apache.org}sample03:sample03HttpSoa
> p11Endpoint
> DEBUG - GeronimoLog.debug(66) | Looking for Axis2 service configuration file: file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.
> 2/sample03.axis2
> DEBUG - GeronimoLog.debug(66) | Configuring service axis_service_for_{http://sample03.policy.samples.rampart.apache.org}sample03#sample03HttpSoap11Endpoi
> nt_hqejbhcnphr4nogdhmowud using: file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.2/sample03.axis2
> DEBUG - GeronimoLog.debug(66) | Create a new ServiceClient for axis_service_for_{http://sample03.policy.samples.rampart.apache.org}sample03#sample03HttpS
> oap11Endpoint_hqejbhcnphr4nogdhmowud
> DEBUG - GeronimoLog.debug(66) | Resolving endpoint reference <?xml version="1.0" encoding="UTF-8"?>
> <service-ref xmlns="http://docs.oasis-open.org/wsbpel/2.0/serviceref"><EndpointReference xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceN
> ame xmlns="http://www.w3.org/2006/05/addressing/wsdl" EndpointName="sample03HttpSoap11Endpoint" xmlns:servicens="http://sample03.policy.samples.rampart.a
> pache.org">servicens:sample03</ServiceName></Metadata><Address>http://localhost:8081/axis2/services/sample03.sample03HttpSoap11Endpoint/</Address></Endpo
> intReference></service-ref>
> DEBUG - GeronimoLog.debug(66) | GET MEX property org.apache.ode.bpel.partnerRoleSessionId = null
> DEBUG - GeronimoLog.debug(66) | GET MEX property org.apache.ode.bpel.myRoleSessionId = null
> DEBUG - GeronimoLog.debug(66) | My-Role EPR not specified, SEP will not be used.
> DEBUG - GeronimoLog.debug(66) | Axis2 sending message to http://localhost:8081/axis2/services/sample03.sample03HttpSoap11Endpoint/ using MEX {PartnerRole
> Mex#hqejbhcnphr4nogdhmowub [PID {http://example.com/Test1/Pool}Pool-1] calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}
> DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0' encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
> "><soapenv:Body><echo xmlns="http://sample03.policy.samples.rampart.apache.org">
>   <ns:args0 xmlns:ns="http://sample03.policy.samples.rampart.apache.org">?</ns:args0>
> </echo></soapenv:Body></soapenv:Envelope>
> DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr4nogdhmowub
> DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 8551
> DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header using session null
> DEBUG - GeronimoLog.debug(66) | Sending a message containing wsa endpoints in headers for session passing.
> DEBUG - GeronimoLog.debug(66) | <?xml version='1.0' encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapen
> v:Header><addr:To xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8081/axis2/services/sample03.sample03HttpSoap11Endpoint/</addr:To><a
> ddr:Action xmlns:addr="http://www.w3.org/2005/08/addressing">urn:echo</addr:Action><addr:ReplyTo xmlns:addr="http://www.w3.org/2005/08/addressing"><addr:
> Address>http://www.w3.org/2005/08/addressing/anonymous</addr:Address></addr:ReplyTo><addr:MessageID xmlns:addr="http://www.w3.org/2005/08/addressing">uui
> d:hqejbhcnphr4nogdhmowue</addr:MessageID></soapenv:Header><soapenv:Body><echo xmlns="http://sample03.policy.samples.rampart.apache.org">
>   <ns:args0 xmlns:ns="http://sample03.policy.samples.rampart.apache.org">?</ns:args0>
> </echo></soapenv:Body></soapenv:Envelope>
> WARN - GeronimoLog.warn(96) | Caught Exception while in Loader.getResource. This may be innocuous.
> java.lang.NullPointerException
>         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
>         at sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
>         at java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
>         at org.apache.catalina.loader.WebappClassLoader.getResource(WebappClassLoader.java:1124)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:94)
>         at org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
>         at org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>         at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>         at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> WARN - GeronimoLog.warn(96) | Caught Exception while in Loader.getResource. This may be innocuous.
> java.lang.NullPointerException
>         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
>         at sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
>         at java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
>         at org.apache.catalina.loader.WebappClassLoader.getResource(WebappClassLoader.java:1124)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:62)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:102)
>         at org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
>         at org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>         at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>         at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> java.lang.reflect.InvocationTargetException
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>         at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>         at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> Caused by: java.lang.NullPointerException
>         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
>         at sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
>         at java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
>         at java.lang.ClassLoader.getSystemResource(ClassLoader.java:1075)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:76)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:102)
>         at org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
>         at org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
>         ... 25 more
> ERROR - GeronimoLog.error(108) | Unable to instantiate (1): org.apache.ws.security.components.crypto.Merlin
> java.lang.reflect.InvocationTargetException
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
>         at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
>         at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
>         at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> Caused by: java.lang.NullPointerException
>         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
>         at sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
>         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
>         at java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
>         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
>         at java.lang.ClassLoader.getSystemResource(ClassLoader.java:1075)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:76)
>         at org.apache.ws.security.util.Loader.getResource(Loader.java:102)
>         at org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
>         at org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
>         ... 25 more
> java.lang.InstantiationException: org.apache.ws.security.components.crypto.Merlin
>         at java.lang.Class.newInstance0(Class.java:340)
>         at java.lang.Class.newInstance(Class.java:308)
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:219)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> ERROR - GeronimoLog.error(108) | Unable to instantiate (2): org.apache.ws.security.components.crypto.Merlin
> java.lang.InstantiationException: org.apache.ws.security.components.crypto.Merlin
>         at java.lang.Class.newInstance0(Class.java:340)
>         at java.lang.Class.newInstance(Class.java:308)
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:219)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> ERROR - GeronimoLog.error(108) | Error sending message (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowub [PID {http://example.com/Test1/Pool}Pool-1] calling or
> g.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}): org.apache.ws.security.components.crypto.Merlin cannot create instance
> java.lang.RuntimeException: org.apache.ws.security.components.crypto.Merlin cannot create instance
>         at org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:224)
>         at org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
>         at org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
>         at org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
>         at org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
>         at org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
>         at org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
>         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
>         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
>         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
>         at org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
>         at org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
>         at org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>         at org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> DEBUG - GeronimoLog.debug(66) | replyWithFailure mex=hqejbhcnphr4nogdhmowub failureType=COMMUNICATION_ERROR description=Error sending message (mex={Partn
> erRoleMex#hqejbhcnphr4nogdhmowub [PID {http://example.com/Test1/Pool}Pool-1] calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}): org.apache.
> ws.security.components.crypto.Merlin cannot create instance details=null
> DEBUG - GeronimoLog.debug(66) | create work event for mex=hqejbhcnphr4nogdhmowub
> DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-2,5,main]: lock(iid=8551, time=1MICROSECONDS)
> DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-2,5,main]: lock(iid=8551, time=1MICROSECONDS)-->GRANTED
> DEBUG - GeronimoLog.debug(66) | >> handleWorkEvent(jobData={mexid=hqejbhcnphr4nogdhmowub, iid=8551, type=INVOKE_RESPONSE, channel=23})
> DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance 8551. INDEXED STATE={{OScope 'Task-1' id=46}::12=[ACTIVE(...)], {OScope 'Task
> -1' id=46}::11=[ACTIVITYGUARD(...)], OSequence#26-sequence-activity-line-16::1=[ACTIVITYGUARD(...)], {OScope '__PROCESS_SCOPE:Pool' id=3}::0=[ACTIVE(...)
> ], OInvoke#47-Task-1::14=[INVOKE(...)], OInvoke#47-Task-1::13=[ACTIVITYGUARD(...)], {OScope '__PROCESS_SCOPE:Pool' id=3}::0=[SCOPE(...)], {OScope 'Task-1
> ' id=46}::12=[SCOPE(...)], OSequence#26-sequence-activity-line-16::2=[SEQUENCE(self=(OSequence#26-sequence-activity-line-16,TerminationChannel#3,ParentSc
> opeChannel#5), linkframe=org.apache.ode.bpel.runtime.LinkFrame@123fab3, remaining=[{OScope 'Task-1' id=46}, {OAssign : Message_End_Event, joinCondition=n
> ull}, OReply#59-Message_End_Event-1])]}
> DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 8551
> DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid hqejbhcnphr4nogdhmowub and channel 23
> DEBUG - GeronimoLog.debug(66) | Triggering response
> ERROR - GeronimoLog.error(104) | Failure during invoke: Error sending message (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowub [PID {http://example.com/Test1/
> Pool}Pool-1] calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}): org.apache.ws.security.components.crypto.Merlin cannot create instance
> INFO - GeronimoLog.info(79) | ActivityRecovery: Registering activity 13, failure reason: Error sending message (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowu
> b [PID {http://example.com/Test1/Pool}Pool-1] calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}): org.apache.ws.security.components.crypto.M
> erlin cannot create instance on channel 24
> DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 8551
> DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-2,5,main]: unlock(iid=8551)
> DEBUG - GeronimoLog.debug(66) | Received myrole mex response callback
> DEBUG - GeronimoLog.debug(66) | Handling response for MEX {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {http://example.com/T
> est1/Pool}CanonicServiceForPool1.EventStartMessage(...)}
> DEBUG - GeronimoLog.debug(66) | Starting transaction.
> DEBUG - GeronimoLog.debug(66) | Failure response message: null
> WARN - GeronimoLog.warn(96) | MEX produced a fault {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {http://example.com/Test1/Po
> ol}CanonicServiceForPool1.EventStartMessage(...)}
> org.apache.ode.axis2.OdeFault: Message exchange failure due to: Error sending message (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowub [PID {http://example.co
> m/Test1/Pool}Pool-1] calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}): org.apache.ws.security.components.crypto.Merlin cannot create insta
> nce
>         at org.apache.ode.axis2.util.SoapMessageConverter.createOdeFault(SoapMessageConverter.java:570)
>         at org.apache.ode.axis2.ODEService.onResponse(ODEService.java:282)
>         at org.apache.ode.axis2.ODEService.onAxisMessageExchange(ODEService.java:189)
>         at org.apache.ode.axis2.hooks.ODEMessageReceiver.invokeBusinessLogic(ODEMessageReceiver.java:69)
>         at org.apache.ode.axis2.hooks.ODEMessageReceiver.invokeBusinessLogic(ODEMessageReceiver.java:52)
>         at org.apache.axis2.receivers.AbstractMessageReceiver.receive(AbstractMessageReceiver.java:96)
>         at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:145)
>         at org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
>         at org.apache.axis2.transport.http.AxisServlet.doPost(AxisServlet.java:120)
>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
>         at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
>         at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
>         at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
>         at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>         at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>         at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
>         at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
>         at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:849)
>         at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
>         at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:454)
>         at java.lang.Thread.run(Thread.java:619)
> DEBUG - GeronimoLog.debug(66) | Releasing mex hqejbhcnphr4nogdhmowu8
> DEBUG - GeronimoLog.debug(66) | Comitting transaction.
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs: []
> 
> 
> 
> 
> 


How to get type of a variable in ODE

Posted by Pascal Aho <P....@gmx.net>.
Hello all,

Please help me. The first issue below is the most important. 

I have a question about programming with ODE.

with the following fields:

oprocess.procesScope.variables
oprocess.procesScope.variables.get(key).Type.parts
oprocess.elementTypes
oprocess.messageTypes
oprocess.xsdTypes

I could almost get all informations about the declaration of a variable in BPEL.

But I miss something which is important for my work. 
For example if a part of a variable has an attribut element, which is a complexType defined in an XML Schema, I would like to get the definition of this complexType. How can I get it?

There is a debuginfo class with a field extensibilityElements. I try with this but I always get a null reference back.

Another issue: I work with eclipse Ganymede. I try to extends the future of ODE 1.3.2

When I try to debug the ODE project, eclipse always builds the ODE project and corrupts it although I deactivate build automatically in menu Project and only build the project with buildr.
Is this a known issue with solution or can anyone help me?

Thank for your answer. 

Cheers,

Pascal

-- 
Neu: GMX DSL bis 50.000 kBit/s und 200,- Euro Startguthaben!
http://portal.gmx.net/de/go/dsl02

How to get type of a variable in ODE

Posted by Pascal Aho <P....@gmx.net>.
Hello all,

Please help me. The first issue below is the most important. 

I have a question about programming with ODE.

with the following fields:

oprocess.procesScope.variables
oprocess.procesScope.variables.get(key).Type.parts
oprocess.elementTypes
oprocess.messageTypes
oprocess.xsdTypes

I could almost get all informations about the declaration of a variable in BPEL.

But I miss something which is important for my work. 
For example if a part of a variable has an attribut element, which is a complexType defined in an XML Schema, I would like to get the definition of this complexType. How can I get it?

There is a debuginfo class with a field extensibilityElements. I try with this but I always get a null reference back.

Another issue: I work with eclipse Ganymede. I try to extends the future of ODE 1.3.2

When I try to debug the ODE project, eclipse always builds the ODE project and corrupts it although I deactivate build automatically in menu Project and only build the project with buildr.
Is this a known issue with solution or can anyone help me?

Thank for your answer. 


-------- Original-Nachricht --------
> Datum: Thu, 15 Oct 2009 11:49:35 -0700
> Von: Alexis Midon <mi...@intalio.com>
> An: user@ode.apache.org
> Betreff: Re: Securing web service call with Rampart - "Error in obtaining a 	token"

> On Sun, Oct 11, 2009 at 1:53 PM, Jens Müller <bl...@tessarakt.de> wrote:
> 
> > On 09.10.2009 20:12, Alexis Midon wrote:
> > > I suspect the paths to your key stores to be wrong.
> > >
> > > TestRampartPolicy/secured-services/client.jks  is not available form
> the
> > > classpath of the webapp.
> > >
> > > Try with **processes/**TestRampartPolicy/secured-services/client.jks
> >
> > I put "client.jks" in the .axis2, and put the file client.jks into the
> > classes directory.
> >
> > Before, I had tried several places under the processes directory which
> > obviously was wrong.
> >
> > Having changed that, it works! Thus, many thanks!
> >
> >
> Good news!
> 
> 
> > Btw, when I send process start messages too fast, I at some point get
> > faults.
> >
> 
> it's a known issue with axis2 1.3, we need to upgrade to axis2 1.5 .
> see http://issues.apache.org/jira/browse/ODE-647
> A workaround is suggested afair.
> 
> 
> > > DEBUG - GeronimoLog.debug(66) | SET MEX property activityId = 47
> > > DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> > http://sample03.policy.samples.rampart.apache.org}sample03 --> null
> > > DEBUG - GeronimoLog.debug(66) | Creating invocation check event in
> > 180000ms for mexid hqejbhcnphr4nuvlos1nbb
> > > DEBUG - GeronimoLog.debug(66) | SET MEX property invokeCheckJobId =
> > hqejbhcnphr4nuvlos1nbc
> > > DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: echo
> > > DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
> > service {http://sample03.policy.samples.rampart.apache.org
> > }sample03:sample03HttpSoa
> > > p11Endpoint
> > > DEBUG - GeronimoLog.debug(66) | Create a new ServiceClient for
> > axis_service_for_{http://sample03.policy.samples.rampart.apache.org
> > }sample03#sample03HttpS
> > > oap11Endpoint_hqejbhcnphr4nuvlos1n8u
> > > ERROR - GeronimoLog.error(108) | Error sending message to Axis2 for
> ODE
> > mex {PartnerRoleMex#hqejbhcnphr4nuvlos1nbb [PID {
> > http://example.com/Test1/Pool}Po <http://example.com/Test1/Pool%7DPo>
> > > ol-1] calling null.echo(...)}
> > > org.apache.axis2.AxisFault: Two services cannot have same name.  A
> > service with the axis_service_for_{
> > http://sample03.policy.samples.rampart.apache.org}s
> > > ample03#sample03HttpSoap11Endpoint_hqejbhcnphr4nuvlos1n8u name already
> > exists in the system.
> > >         at
> >
> org.apache.axis2.client.ServiceClient.configureServiceClient(ServiceClient.java:172)
> > >         at
> > org.apache.axis2.client.ServiceClient.<init>(ServiceClient.java:139)
> > >         at
> >
> org.apache.ode.axis2.SoapExternalService.getServiceClient(SoapExternalService.java:281)
> > >         at
> >
> org.apache.ode.axis2.SoapExternalService.invoke(SoapExternalService.java:140)
> > >         at
> >
> org.apache.ode.axis2.MessageExchangeContextImpl.invokePartner(MessageExchangeContextImpl.java:52)
> > >         at
> >
> org.apache.ode.bpel.engine.BpelRuntimeContextImpl.invoke(BpelRuntimeContextImpl.java:781)
> > >         at org.apache.ode.bpel.runtime.INVOKE.run(INVOKE.java:100)
> > >         at sun.reflect.GeneratedMethodAccessor34.invoke(Unknown
> Source)
> > >         at
> >
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> > >         at java.lang.reflect.Method.invoke(Method.java:597)
> > >         at
> > org.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:451)
> > >         at
> org.apache.ode.jacob.vpu.JacobVPU.execute(JacobVPU.java:139)
> > >         at
> >
> org.apache.ode.bpel.engine.BpelRuntimeContextImpl.execute(BpelRuntimeContextImpl.java:875)
> > >         at
> >
> org.apache.ode.bpel.engine.PartnerLinkMyRoleImpl.invokeNewInstance(PartnerLinkMyRoleImpl.java:206)
> > >         at
> >
> org.apache.ode.bpel.engine.BpelProcess.invokeProcess(BpelProcess.java:237)
> > >         at
> >
> org.apache.ode.bpel.engine.BpelProcess.handleWorkEvent(BpelProcess.java:408)
> > >         at
> >
> org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:439)
> > >         at
> >
> org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:441)
> > >         at
> >
> org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:411)
> > >         at
> >
> org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:405)
> > >         at
> >
> org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:218)
> > >         at
> >
> org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:404)
> > >         at
> >
> org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:401)
> > >         at
> > java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> > >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> > >         at
> >
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> > >         at
> >
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> > >         at java.lang.Thread.run(Thread.java:619)
> > > DEBUG - GeronimoLog.debug(66) | replyWithFailure
> > mex=hqejbhcnphr4nuvlos1nbb failureType=COMMUNICATION_ERROR
> description=Error
> > sending message to Axis2 fo
> > > r ODE mex {PartnerRoleMex#hqejbhcnphr4nuvlos1nbb [PID {
> >
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>]
> > calling null.echo(...)} details=null
> >
> >
> > What could be the problem here?
> >
> > Thanks,
> >
> > Jens
> >
> >

-- 
Jetzt kostenlos herunterladen: Internet Explorer 8 und Mozilla Firefox 3.5 -
sicherer, schneller und einfacher! http://portal.gmx.net/de/go/atbrowser

Re: Securing web service call with Rampart - "Error in obtaining a token"

Posted by Alexis Midon <mi...@intalio.com>.
On Sun, Oct 11, 2009 at 1:53 PM, Jens Müller <bl...@tessarakt.de> wrote:

> On 09.10.2009 20:12, Alexis Midon wrote:
> > I suspect the paths to your key stores to be wrong.
> >
> > TestRampartPolicy/secured-services/client.jks  is not available form the
> > classpath of the webapp.
> >
> > Try with **processes/**TestRampartPolicy/secured-services/client.jks
>
> I put "client.jks" in the .axis2, and put the file client.jks into the
> classes directory.
>
> Before, I had tried several places under the processes directory which
> obviously was wrong.
>
> Having changed that, it works! Thus, many thanks!
>
>
Good news!


> Btw, when I send process start messages too fast, I at some point get
> faults.
>

it's a known issue with axis2 1.3, we need to upgrade to axis2 1.5 .
see http://issues.apache.org/jira/browse/ODE-647
A workaround is suggested afair.


> > DEBUG - GeronimoLog.debug(66) | SET MEX property activityId = 47
> > DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> http://sample03.policy.samples.rampart.apache.org}sample03 --> null
> > DEBUG - GeronimoLog.debug(66) | Creating invocation check event in
> 180000ms for mexid hqejbhcnphr4nuvlos1nbb
> > DEBUG - GeronimoLog.debug(66) | SET MEX property invokeCheckJobId =
> hqejbhcnphr4nuvlos1nbc
> > DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: echo
> > DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
> service {http://sample03.policy.samples.rampart.apache.org
> }sample03:sample03HttpSoa
> > p11Endpoint
> > DEBUG - GeronimoLog.debug(66) | Create a new ServiceClient for
> axis_service_for_{http://sample03.policy.samples.rampart.apache.org
> }sample03#sample03HttpS
> > oap11Endpoint_hqejbhcnphr4nuvlos1n8u
> > ERROR - GeronimoLog.error(108) | Error sending message to Axis2 for ODE
> mex {PartnerRoleMex#hqejbhcnphr4nuvlos1nbb [PID {
> http://example.com/Test1/Pool}Po <http://example.com/Test1/Pool%7DPo>
> > ol-1] calling null.echo(...)}
> > org.apache.axis2.AxisFault: Two services cannot have same name.  A
> service with the axis_service_for_{
> http://sample03.policy.samples.rampart.apache.org}s
> > ample03#sample03HttpSoap11Endpoint_hqejbhcnphr4nuvlos1n8u name already
> exists in the system.
> >         at
> org.apache.axis2.client.ServiceClient.configureServiceClient(ServiceClient.java:172)
> >         at
> org.apache.axis2.client.ServiceClient.<init>(ServiceClient.java:139)
> >         at
> org.apache.ode.axis2.SoapExternalService.getServiceClient(SoapExternalService.java:281)
> >         at
> org.apache.ode.axis2.SoapExternalService.invoke(SoapExternalService.java:140)
> >         at
> org.apache.ode.axis2.MessageExchangeContextImpl.invokePartner(MessageExchangeContextImpl.java:52)
> >         at
> org.apache.ode.bpel.engine.BpelRuntimeContextImpl.invoke(BpelRuntimeContextImpl.java:781)
> >         at org.apache.ode.bpel.runtime.INVOKE.run(INVOKE.java:100)
> >         at sun.reflect.GeneratedMethodAccessor34.invoke(Unknown Source)
> >         at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> >         at java.lang.reflect.Method.invoke(Method.java:597)
> >         at
> org.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:451)
> >         at org.apache.ode.jacob.vpu.JacobVPU.execute(JacobVPU.java:139)
> >         at
> org.apache.ode.bpel.engine.BpelRuntimeContextImpl.execute(BpelRuntimeContextImpl.java:875)
> >         at
> org.apache.ode.bpel.engine.PartnerLinkMyRoleImpl.invokeNewInstance(PartnerLinkMyRoleImpl.java:206)
> >         at
> org.apache.ode.bpel.engine.BpelProcess.invokeProcess(BpelProcess.java:237)
> >         at
> org.apache.ode.bpel.engine.BpelProcess.handleWorkEvent(BpelProcess.java:408)
> >         at
> org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:439)
> >         at
> org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:441)
> >         at
> org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:411)
> >         at
> org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:405)
> >         at
> org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:218)
> >         at
> org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:404)
> >         at
> org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:401)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > DEBUG - GeronimoLog.debug(66) | replyWithFailure
> mex=hqejbhcnphr4nuvlos1nbb failureType=COMMUNICATION_ERROR description=Error
> sending message to Axis2 fo
> > r ODE mex {PartnerRoleMex#hqejbhcnphr4nuvlos1nbb [PID {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>]
> calling null.echo(...)} details=null
>
>
> What could be the problem here?
>
> Thanks,
>
> Jens
>
>

Re: Securing web service call with Rampart - "Error in obtaining a token"

Posted by Jens Müller <bl...@tessarakt.de>.
On 09.10.2009 20:12, Alexis Midon wrote:
> I suspect the paths to your key stores to be wrong.
> 
> TestRampartPolicy/secured-services/client.jks  is not available form the
> classpath of the webapp.
> 
> Try with **processes/**TestRampartPolicy/secured-services/client.jks

I put "client.jks" in the .axis2, and put the file client.jks into the
classes directory.

Before, I had tried several places under the processes directory which
obviously was wrong.

Having changed that, it works! Thus, many thanks!

Btw, when I send process start messages too fast, I at some point get
faults.

> DEBUG - GeronimoLog.debug(66) | SET MEX property activityId = 47
> DEBUG - GeronimoLog.debug(66) | Routed: svcQname {http://sample03.policy.samples.rampart.apache.org}sample03 --> null
> DEBUG - GeronimoLog.debug(66) | Creating invocation check event in 180000ms for mexid hqejbhcnphr4nuvlos1nbb
> DEBUG - GeronimoLog.debug(66) | SET MEX property invokeCheckJobId = hqejbhcnphr4nuvlos1nbc
> DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: echo
> DEBUG - GeronimoLog.debug(66) | The service to invoke is the external service {http://sample03.policy.samples.rampart.apache.org}sample03:sample03HttpSoa
> p11Endpoint
> DEBUG - GeronimoLog.debug(66) | Create a new ServiceClient for axis_service_for_{http://sample03.policy.samples.rampart.apache.org}sample03#sample03HttpS
> oap11Endpoint_hqejbhcnphr4nuvlos1n8u
> ERROR - GeronimoLog.error(108) | Error sending message to Axis2 for ODE mex {PartnerRoleMex#hqejbhcnphr4nuvlos1nbb [PID {http://example.com/Test1/Pool}Po
> ol-1] calling null.echo(...)}
> org.apache.axis2.AxisFault: Two services cannot have same name.  A service with the axis_service_for_{http://sample03.policy.samples.rampart.apache.org}s
> ample03#sample03HttpSoap11Endpoint_hqejbhcnphr4nuvlos1n8u name already exists in the system.
>         at org.apache.axis2.client.ServiceClient.configureServiceClient(ServiceClient.java:172)
>         at org.apache.axis2.client.ServiceClient.<init>(ServiceClient.java:139)
>         at org.apache.ode.axis2.SoapExternalService.getServiceClient(SoapExternalService.java:281)
>         at org.apache.ode.axis2.SoapExternalService.invoke(SoapExternalService.java:140)
>         at org.apache.ode.axis2.MessageExchangeContextImpl.invokePartner(MessageExchangeContextImpl.java:52)
>         at org.apache.ode.bpel.engine.BpelRuntimeContextImpl.invoke(BpelRuntimeContextImpl.java:781)
>         at org.apache.ode.bpel.runtime.INVOKE.run(INVOKE.java:100)
>         at sun.reflect.GeneratedMethodAccessor34.invoke(Unknown Source)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>         at java.lang.reflect.Method.invoke(Method.java:597)
>         at org.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:451)
>         at org.apache.ode.jacob.vpu.JacobVPU.execute(JacobVPU.java:139)
>         at org.apache.ode.bpel.engine.BpelRuntimeContextImpl.execute(BpelRuntimeContextImpl.java:875)
>         at org.apache.ode.bpel.engine.PartnerLinkMyRoleImpl.invokeNewInstance(PartnerLinkMyRoleImpl.java:206)
>         at org.apache.ode.bpel.engine.BpelProcess.invokeProcess(BpelProcess.java:237)
>         at org.apache.ode.bpel.engine.BpelProcess.handleWorkEvent(BpelProcess.java:408)
>         at org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:439)
>         at org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:441)
>         at org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:411)
>         at org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:405)
>         at org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:218)
>         at org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:404)
>         at org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:401)
>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
>         at java.lang.Thread.run(Thread.java:619)
> DEBUG - GeronimoLog.debug(66) | replyWithFailure mex=hqejbhcnphr4nuvlos1nbb failureType=COMMUNICATION_ERROR description=Error sending message to Axis2 fo
> r ODE mex {PartnerRoleMex#hqejbhcnphr4nuvlos1nbb [PID {http://example.com/Test1/Pool}Pool-1] calling null.echo(...)} details=null


What could be the problem here?

Thanks,

Jens


Re: Securing web service call with Rampart - "Error in obtaining a token"

Posted by Alexis Midon <mi...@intalio.com>.
I suspect the paths to your key stores to be wrong.

TestRampartPolicy/secured-services/client.jks  is not available form the
classpath of the webapp.

Try with **processes/**TestRampartPolicy/secured-services/client.jks

On Fri, Oct 9, 2009 at 9:02 AM, Jens Müller <bl...@tessarakt.de> wrote:

> On 09.10.2009 17:43, Jens Müller wrote:
> > On 08.10.2009 16:27, Jens Müller wrote:
> >> I have the following config:
> >>
> >> * Apache Tomcat 6.0.20
> >>  * Apache Axis2 1.5
> >>   * Apache Rampart 1.5 RC1 (installed according to
> >>
> http://wso2.org/library/knowledge-base/run-rampart-samples-apache-tomcat)
> >>  * Apache Ode 1.3.3
> >>
> >
> > OK, I changed the sample.axis03 to this:
>
> After providing the password callback handler class, I get this:
>
> Any ideas?
>
> > DEBUG - GeronimoLog.debug(66) | ODE initializing
> > DEBUG - GeronimoLog.debug(66) | BPEL SERVER initializing
> > DEBUG - GeronimoLog.debug(66) | Initializing HTTP connection manager
> > DEBUG - GeronimoLog.debug(66) | http.connection-manager.max-per-host=10
> > DEBUG - GeronimoLog.debug(66) | http.connection-manager.max-total=10
> > DEBUG - GeronimoLog.debug(66) | Process store event:
> {ProcessStoreEvent#ACTVIATED:{http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> }
> > DEBUG - GeronimoLog.debug(66) | Unregister process: serviceId={
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>,
> process=null
> > DEBUG - GeronimoLog.debug(66) | register: {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > DEBUG - GeronimoLog.debug(66) | Registering process {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>with server.
> > DEBUG - GeronimoLog.debug(66) | Register process: serviceId={
> http://example.com/Test1/Pool}CanonicServiceForPool1:canonicPort<http://example.com/Test1/Pool%7DCanonicServiceForPool1:canonicPort>,
> process=BpelProcess[{http:
> > //example.com/Test1/Pool}Pool-1 <http://example.com/Test1/Pool%7DPool-1>
> ]
> > DEBUG - GeronimoLog.debug(66) | Activating {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > DEBUG - GeronimoLog.debug(66) | Create AxisService: service={
> http://example.com/Test1/Pool}CanonicServiceForPool1<http://example.com/Test1/Pool%7DCanonicServiceForPool1>port=canonicPort WSDL=Test1-Pool.wsdl B
> > PEL=Test1-Pool.bpel
> > DEBUG - GeronimoLog.debug(66) | Looking for Axis2 service configuration
> file:
> file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.
> > 2/CanonicServiceForPool1.axis2
> > DEBUG - GeronimoLog.debug(66) | Axis2 service configuration not found:
> file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.2/Canon
> > icServiceForPool1.axis2
> > DEBUG - GeronimoLog.debug(66) | Destroying service {
> http://example.com/Test1/Pool}CanonicServiceForPool1<http://example.com/Test1/Pool%7DCanonicServiceForPool1>port canonicPort
> > DEBUG - GeronimoLog.debug(66) | Couldn't find service {
> http://example.com/Test1/Pool}CanonicServiceForPool1<http://example.com/Test1/Pool%7DCanonicServiceForPool1>port canonicPort to destroy.
> > DEBUG - GeronimoLog.debug(66) | Created Axis2 service {
> http://example.com/Test1/Pool}CanonicServiceForPool1<http://example.com/Test1/Pool%7DCanonicServiceForPool1>
> > DEBUG - GeronimoLog.debug(66) | Activated {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>myrole poolAndPool1PlkVar: EPR is org.apache.ode.bpel.epr.WSAEndpoint@1f9
> > b804
> > DEBUG - GeronimoLog.debug(66) | Activated {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > DEBUG - GeronimoLog.debug(66) | Rehydrating process {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > DEBUG - GeronimoLog.debug(66) | Processing <invoke> element for process {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>:
> partnerlink pool0AndPoolForPortSample03Htt
> > pSoap12EndpointPlkVar --> {
> http://sample03.policy.samples.rampart.apache.org
> }sample03:sample03HttpSoap11Endpoint
> > DEBUG - GeronimoLog.debug(66) | Creating SOAP-bound external service {
> http://sample03.policy.samples.rampart.apache.org}sample03
> > DEBUG - GeronimoLog.debug(66) | Created external service {
> http://sample03.policy.samples.rampart.apache.org}sample03
> > DEBUG - GeronimoLog.debug(66) | Activated {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>partnerrole pool0AndPoolForPortSample03HttpSoap12EndpointPlkVar: EPR is o
> > rg.apache.ode.bpel.epr.WSAEndpoint@128453c
> > DEBUG - GeronimoLog.debug(66) | Creating new process DAO for {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>(guid=hqejbhcnphr4nl24ndvkqn)...
> > DEBUG - GeronimoLog.debug(66) | Found ProcessDAO for {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>with GUID hqejbhcnphr4nl24ndvkqn
> > INFO - GeronimoLog.info(79) | Created new process DAO for {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>(guid=hqejbhcnphr4nl24ndvkqn).
> > INFO - GeronimoLog.info(79) | Registered process {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> .
> > DEBUG - GeronimoLog.debug(66) | (Re)scheduling cron scheduled jobs on
> store event: {ProcessStoreEvent#ACTVIATED:{
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> }
> > INFO - GeronimoLog.info(79) | Cancelling PROCESS CRON jobs for: {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > INFO - GeronimoLog.info(79) | Scheduling PROCESS CRON jobs for: {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > DEBUG - GeronimoLog.debug(66) | BPEL SERVER starting.
> > INFO - GeronimoLog.info(79) | BPEL Server Started.
> > DEBUG - GeronimoLog.debug(66) | No deploy-ext.properties found.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration:
> C:\apache-tomcat-6.0.20\webapps\ode\WEB-INF\conf\schedules.xml
> > DEBUG - GeronimoLog.debug(66) | Initializing Deployment Web Service
> > DEBUG - GeronimoLog.debug(66) | Starting scheduler
> > DEBUG - GeronimoLog.debug(66) | Initializing JCA adapter.
> > INFO - GeronimoLog.info(79) | Poller started.
> > INFO - GeronimoLog.info(79) | Process deployment polling started on path
> C:\apache-tomcat-6.0.20\webapps\ode\WEB-INF\processes.
> > INFO - GeronimoLog.info(79) | ODE Service Engine has been started.
> > DEBUG - GeronimoLog.debug(66) | Process store event:
> {ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> }
> > DEBUG - GeronimoLog.debug(66) | Ignoring store event:
> {ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> }
> > DEBUG - GeronimoLog.debug(66) | (Re)scheduling cron scheduled jobs on
> store event: {ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{
> http://example.com/Test1
> > /Pool}Pool-1}
> > INFO - GeronimoLog.info(79) | Cancelling PROCESS CRON jobs for: {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > INFO - GeronimoLog.info(79) | Scheduling PROCESS CRON jobs for: {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > [INFO] Clustering has been disabled
> > [INFO] Deploying module: addressing-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/addressing-1.5.mar
> > [INFO] Deploying module: jaxws-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/axis2-jaxws-mar-1.5.mar
> > [INFO] Deploying module: script-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/axis2-scripting-1.5.mar
> > [INFO] Deploying module: metadataExchange-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/mex-1.5.mar
> > [INFO] Deploying module: mtompolicy-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/mtompolicy-1.5.mar
> > [INFO] Deploying module: ping-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/ping-1.5.mar
> > [INFO] Deploying module: rahas-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/rahas-1.5.mar
> > [INFO] Deploying module: rampart-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/rampart-1.5.mar
> > [INFO] Deploying module: soapmonitor-1.5 -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/modules/soapmonitor-1.5.mar
> > [INFO] Deploying module: metadataExchange -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/lib/mex-1.5-impl.jar
> > [INFO] Deploying Web service: sample03.aar -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/services/sample03.aar
> > [INFO] Deploying Web service: version-1.5.aar -
> file:/C:/apache-tomcat-6.0.20/webapps/axis2/WEB-INF/services/version-1.5.aar
> > 09.10.2009 17:50:50 org.apache.coyote.http11.Http11Protocol start
> > INFO: Starting Coyote HTTP/1.1 on http-8081
> > 09.10.2009 17:50:51 org.apache.jk.common.ChannelSocket init
> > INFO: JK: ajp13 listening on /0.0.0.0:8109
> > 09.10.2009 17:50:51 org.apache.jk.server.JkMain start
> > INFO: Jk running ID=0 time=0/16  config=null
> > 09.10.2009 17:50:51 org.apache.catalina.startup.Catalina start
> > INFO: Server startup in 10286 ms
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > DEBUG - GeronimoLog.debug(66) | Checking for Service using target
> endpoint address :
> http://localhost:8081/ode/processes/RampartOdeTest/Test1/Pool/Pool1
> > DEBUG - GeronimoLog.debug(66) | Found service in registry from name
> RampartOdeTest/Test1/Pool/Pool1: RampartOdeTest/Test1/Pool/Pool1
> > DEBUG - GeronimoLog.debug(66) | The system is checking for the operation
> using the following WSAAction:
> http://example.com/Test1/Pool/ForPool1/EventStart
> > Message
> > DEBUG - GeronimoLog.debug(66) | Received request message for
> RampartOdeTest/Test1/Pool/Pool1.{
> http://example.com/Test1/Pool}EventStartMessage<http://example.com/Test1/Pool%7DEventStartMessage>
> > DEBUG - GeronimoLog.debug(66) | Starting transaction.
> > DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> http://example.com/Test1/Pool}CanonicServiceForPool1<http://example.com/Test1/Pool%7DCanonicServiceForPool1>--> [BpelProcess[{
> http://example.com/Test1/Pool}Po <http://example.com/Test1/Pool%7DPo>
> > ol-1]]
> > DEBUG - GeronimoLog.debug(66) | ODE routed to operation Operation:
> name=EventStartMessage
> > style=REQUEST_RESPONSE,1
> > Input: name=EventStartMessage
> > Message: name={http://example.com/Test1/Pool}EventStartMessageRequest<http://example.com/Test1/Pool%7DEventStartMessageRequest>
> > Part: name=body
> > elementName={http://example.com/Test1/Pool}EventStartMessageRequest<http://example.com/Test1/Pool%7DEventStartMessageRequest>
> > Output: name=EventStartMessageResponse
> > Message: name={http://example.com/Test1/Pool}EventStartMessageResponse<http://example.com/Test1/Pool%7DEventStartMessageResponse>
> > Part: name=body
> > elementName={http://example.com/Test1/Pool}EventStartMessageResponse<http://example.com/Test1/Pool%7DEventStartMessageResponse>from service {
> http://example.com/Test1/Pool}CanonicServiceForPool1<http://example.com/Test1/Pool%7DCanonicServiceForPool1>
> > DEBUG - GeronimoLog.debug(66) | SET MEX property isTwoWay = true
> > DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX
> {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {
> http://example.com/Test
> > 1/Pool}CanonicServiceForPool1.EventStartMessage(...)}
> > DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0"
> encoding="UTF-8"?>
> > <message><body><EventStartMessageRequest xmlns="
> http://example.com/Test1/Pool" xmlns:pool="http://example.com/Test1/Pool"
> xmlns:soapenv="http://schemas.x
> > mlsoap.org/soap/envelope/">?</EventStartMessageRequest></body></message>
> > DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==> BpelProcess[{
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>
> ]
> > DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX
> {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {
> http://example.com/Test1/Poo
> > l}CanonicServiceForPool1.EventStartMessage(...)}
> > DEBUG - GeronimoLog.debug(66) | Commiting transaction.
> > DEBUG - GeronimoLog.debug(66) | handleWorkEvent: InvokeInternal event for
> mexid hqejbhcnphr4nogdhmowu8
> > DEBUG - GeronimoLog.debug(66) | >>
> handleWorkEvent(jobData={mexid=hqejbhcnphr4nogdhmowu8, pid={
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>,
> type=INVOKE_INTERNAL
> > })
> > DEBUG - GeronimoLog.debug(66) | InvokeInternal event for mexid
> hqejbhcnphr4nogdhmowu8
> > DEBUG - GeronimoLog.debug(66) | GET MEX property
> org.apache.ode.bpel.myRoleSessionId = null
> > DEBUG - GeronimoLog.debug(66) | GET MEX property
> org.apache.ode.bpel.myRoleSessionId = null
> > DEBUG - GeronimoLog.debug(66) | GET MEX property
> org.apache.ode.bpel.partnerRoleSessionId = null
> > DEBUG - GeronimoLog.debug(66) | INPUTMSG:
> poolAndPool1PlkVar.EventStartMessage: MSG RCVD keys=[] mySessionId=null
> partnerSessionId=null
> > DEBUG - GeronimoLog.debug(66) | INPUTMSG:
> poolAndPool1PlkVar.EventStartMessage: routing failed, CREATING NEW INSTANCE
> > DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for
> instance 8551. INDEXED STATE={}
> > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: USING
> CORRELATOR poolAndPool1PlkVar.EventStartMessage
> > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: CHECKING
> for NEW INSTANCE match
> > DEBUG - GeronimoLog.debug(66) | INPUTMSGMATCH: Changing process instance
> state from ready to active
> > DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#9: FOUND
> match for NEW instance mexRef={MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client
> hqejbhcnphr4
> > nogdhmowu7] calling {
> http://example.com/Test1/Pool}CanonicServiceForPool1.EventStartMessage(...)<http://example.com/Test1/Pool%7DCanonicServiceForPool1.EventStartMessage%28...%29>
> }
> > DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER:
> partnerLink={PartnerLinkInstance
> partnerLinkDecl=OPartnerLink#21,scopeInstanceId=8601}, op=echo channel
> > =InvokeResponseChannel#23)
> > DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null
> partnerSessionId=null
> > DEBUG - GeronimoLog.debug(66) | SET MEX property activityId = 47
> > DEBUG - GeronimoLog.debug(66) | Routed: svcQname {
> http://sample03.policy.samples.rampart.apache.org}sample03 --> null
> > DEBUG - GeronimoLog.debug(66) | Creating invocation check event in
> 180000ms for mexid hqejbhcnphr4nogdhmowub
> > DEBUG - GeronimoLog.debug(66) | SET MEX property invokeCheckJobId =
> hqejbhcnphr4nogdhmowuc
> > DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: echo
> > DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
> service {http://sample03.policy.samples.rampart.apache.org
> }sample03:sample03HttpSoa
> > p11Endpoint
> > DEBUG - GeronimoLog.debug(66) | Looking for Axis2 service configuration
> file:
> file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.
> > 2/sample03.axis2
> > DEBUG - GeronimoLog.debug(66) | Configuring service axis_service_for_{
> http://sample03.policy.samples.rampart.apache.org
> }sample03#sample03HttpSoap11Endpoi
> > nt_hqejbhcnphr4nogdhmowud using:
> file:/C:/apache-tomcat-6.0.20/webapps/ode/WEB-INF/processes/RampartOdeTest.2/sample03.axis2
> > DEBUG - GeronimoLog.debug(66) | Create a new ServiceClient for
> axis_service_for_{http://sample03.policy.samples.rampart.apache.org
> }sample03#sample03HttpS
> > oap11Endpoint_hqejbhcnphr4nogdhmowud
> > DEBUG - GeronimoLog.debug(66) | Resolving endpoint reference <?xml
> version="1.0" encoding="UTF-8"?>
> > <service-ref xmlns="http://docs.oasis-open.org/wsbpel/2.0/serviceref"><EndpointReference
> xmlns="http://www.w3.org/2005/08/addressing"><Metadata><ServiceN
> > ame xmlns="http://www.w3.org/2006/05/addressing/wsdl"
> EndpointName="sample03HttpSoap11Endpoint" xmlns:servicens="
> http://sample03.policy.samples.rampart.a
> > pache.org">servicens:sample03</ServiceName></Metadata><Address>
> http://localhost:8081/axis2/services/sample03.sample03HttpSoap11Endpoint/
> </Address></Endpo
> > intReference></service-ref>
> > DEBUG - GeronimoLog.debug(66) | GET MEX property
> org.apache.ode.bpel.partnerRoleSessionId = null
> > DEBUG - GeronimoLog.debug(66) | GET MEX property
> org.apache.ode.bpel.myRoleSessionId = null
> > DEBUG - GeronimoLog.debug(66) | My-Role EPR not specified, SEP will not
> be used.
> > DEBUG - GeronimoLog.debug(66) | Axis2 sending message to
> http://localhost:8081/axis2/services/sample03.sample03HttpSoap11Endpoint/using MEX {PartnerRole
> > Mex#hqejbhcnphr4nogdhmowub [PID {http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>]
> calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}
> > DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
> encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="
> http://schemas.xmlsoap.org/soap/envelope/
> > "><soapenv:Body><echo xmlns="
> http://sample03.policy.samples.rampart.apache.org">
> >   <ns:args0 xmlns:ns="http://sample03.policy.samples.rampart.apache.org
> ">?</ns:args0>
> > </echo></soapenv:Body></soapenv:Envelope>
> > DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr4nogdhmowub
> > DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 8551
> > DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
> using session null
> > DEBUG - GeronimoLog.debug(66) | Sending a message containing wsa
> endpoints in headers for session passing.
> > DEBUG - GeronimoLog.debug(66) | <?xml version='1.0'
> encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="
> http://schemas.xmlsoap.org/soap/envelope/"><soapen
> > v:Header><addr:To xmlns:addr="http://www.w3.org/2005/08/addressing">
> http://localhost:8081/axis2/services/sample03.sample03HttpSoap11Endpoint/
> </addr:To><a
> > ddr:Action xmlns:addr="http://www.w3.org/2005/08/addressing">urn:echo</addr:Action><addr:ReplyTo
> xmlns:addr="http://www.w3.org/2005/08/addressing"><addr:
> > Address>http://www.w3.org/2005/08/addressing/anonymous</addr:Address></addr:ReplyTo><addr:MessageID
> xmlns:addr="http://www.w3.org/2005/08/addressing">uui
> >
> d:hqejbhcnphr4nogdhmowue</addr:MessageID></soapenv:Header><soapenv:Body><echo
> xmlns="http://sample03.policy.samples.rampart.apache.org">
> >   <ns:args0 xmlns:ns="http://sample03.policy.samples.rampart.apache.org
> ">?</ns:args0>
> > </echo></soapenv:Body></soapenv:Envelope>
> > WARN - GeronimoLog.warn(96) | Caught Exception while in
> Loader.getResource. This may be innocuous.
> > java.lang.NullPointerException
> >         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
> >         at
> sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
> >         at
> java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
> >         at
> org.apache.catalina.loader.WebappClassLoader.getResource(WebappClassLoader.java:1124)
> >         at org.apache.ws.security.util.Loader.getResource(Loader.java:94)
> >         at
> org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
> >         at
> org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
> >         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native
> Method)
> >         at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
> >         at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
> >         at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > WARN - GeronimoLog.warn(96) | Caught Exception while in
> Loader.getResource. This may be innocuous.
> > java.lang.NullPointerException
> >         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
> >         at
> sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
> >         at
> java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
> >         at
> org.apache.catalina.loader.WebappClassLoader.getResource(WebappClassLoader.java:1124)
> >         at org.apache.ws.security.util.Loader.getResource(Loader.java:62)
> >         at
> org.apache.ws.security.util.Loader.getResource(Loader.java:102)
> >         at
> org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
> >         at
> org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
> >         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native
> Method)
> >         at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
> >         at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
> >         at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > java.lang.reflect.InvocationTargetException
> >         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native
> Method)
> >         at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
> >         at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
> >         at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > Caused by: java.lang.NullPointerException
> >         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
> >         at
> sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
> >         at
> java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
> >         at java.lang.ClassLoader.getSystemResource(ClassLoader.java:1075)
> >         at org.apache.ws.security.util.Loader.getResource(Loader.java:76)
> >         at
> org.apache.ws.security.util.Loader.getResource(Loader.java:102)
> >         at
> org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
> >         at
> org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
> >         ... 25 more
> > ERROR - GeronimoLog.error(108) | Unable to instantiate (1):
> org.apache.ws.security.components.crypto.Merlin
> > java.lang.reflect.InvocationTargetException
> >         at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native
> Method)
> >         at
> sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
> >         at
> sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
> >         at
> java.lang.reflect.Constructor.newInstance(Constructor.java:513)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:211)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > Caused by: java.lang.NullPointerException
> >         at sun.misc.MetaIndex.mayContain(MetaIndex.java:225)
> >         at
> sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:744)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:168)
> >         at sun.misc.URLClassPath.getResource(URLClassPath.java:220)
> >         at
> java.lang.ClassLoader.getBootstrapResource(ClassLoader.java:1113)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:974)
> >         at java.lang.ClassLoader.getResource(ClassLoader.java:972)
> >         at java.lang.ClassLoader.getSystemResource(ClassLoader.java:1075)
> >         at org.apache.ws.security.util.Loader.getResource(Loader.java:76)
> >         at
> org.apache.ws.security.util.Loader.getResource(Loader.java:102)
> >         at
> org.apache.ws.security.components.crypto.AbstractCrypto.<init>(AbstractCrypto.java:66)
> >         at
> org.apache.ws.security.components.crypto.Merlin.<init>(Merlin.java:65)
> >         ... 25 more
> > java.lang.InstantiationException:
> org.apache.ws.security.components.crypto.Merlin
> >         at java.lang.Class.newInstance0(Class.java:340)
> >         at java.lang.Class.newInstance(Class.java:308)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:219)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > ERROR - GeronimoLog.error(108) | Unable to instantiate (2):
> org.apache.ws.security.components.crypto.Merlin
> > java.lang.InstantiationException:
> org.apache.ws.security.components.crypto.Merlin
> >         at java.lang.Class.newInstance0(Class.java:340)
> >         at java.lang.Class.newInstance(Class.java:308)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:219)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > ERROR - GeronimoLog.error(108) | Error sending message
> (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowub [PID {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>]
> calling or
> > g.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}):
> org.apache.ws.security.components.crypto.Merlin cannot create instance
> > java.lang.RuntimeException:
> org.apache.ws.security.components.crypto.Merlin cannot create instance
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.loadClass(CryptoFactory.java:224)
> >         at
> org.apache.ws.security.components.crypto.CryptoFactory.getInstance(CryptoFactory.java:93)
> >         at
> org.apache.rampart.util.RampartUtil.getSignatureCrypto(RampartUtil.java:235)
> >         at
> org.apache.rampart.builder.BindingBuilder.getSignatureBuider(BindingBuilder.java:294)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignature(AsymmetricBindingBuilder.java:596)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.doSignBeforeEncrypt(AsymmetricBindingBuilder.java:385)
> >         at
> org.apache.rampart.builder.AsymmetricBindingBuilder.build(AsymmetricBindingBuilder.java:95)
> >         at
> org.apache.rampart.MessageBuilder.build(MessageBuilder.java:131)
> >         at
> org.apache.rampart.handler.RampartSender.invoke(RampartSender.java:64)
> >         at org.apache.axis2.engine.Phase.invoke(Phase.java:292)
> >         at org.apache.axis2.engine.AxisEngine.invoke(AxisEngine.java:212)
> >         at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:377)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:374)
> >         at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:211)
> >         at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> >         at
> org.apache.ode.axis2.SoapExternalService$1$1.call(SoapExternalService.java:201)
> >         at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
> >         at java.util.concurrent.FutureTask.run(FutureTask.java:138)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885)
> >         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
> >         at java.lang.Thread.run(Thread.java:619)
> > DEBUG - GeronimoLog.debug(66) | replyWithFailure
> mex=hqejbhcnphr4nogdhmowub failureType=COMMUNICATION_ERROR description=Error
> sending message (mex={Partn
> > erRoleMex#hqejbhcnphr4nogdhmowub [PID {
> http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>]
> calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}):
> org.apache.
> > ws.security.components.crypto.Merlin cannot create instance details=null
> > DEBUG - GeronimoLog.debug(66) | create work event for
> mex=hqejbhcnphr4nogdhmowub
> > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-2,5,main]:
> lock(iid=8551, time=1MICROSECONDS)
> > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-2,5,main]:
> lock(iid=8551, time=1MICROSECONDS)-->GRANTED
> > DEBUG - GeronimoLog.debug(66) | >>
> handleWorkEvent(jobData={mexid=hqejbhcnphr4nogdhmowub, iid=8551,
> type=INVOKE_RESPONSE, channel=23})
> > DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for
> instance 8551. INDEXED STATE={{OScope 'Task-1' id=46}::12=[ACTIVE(...)],
> {OScope 'Task
> > -1' id=46}::11=[ACTIVITYGUARD(...)],
> OSequence#26-sequence-activity-line-16::1=[ACTIVITYGUARD(...)], {OScope
> '__PROCESS_SCOPE:Pool' id=3}::0=[ACTIVE(...)
> > ], OInvoke#47-Task-1::14=[INVOKE(...)],
> OInvoke#47-Task-1::13=[ACTIVITYGUARD(...)], {OScope '__PROCESS_SCOPE:Pool'
> id=3}::0=[SCOPE(...)], {OScope 'Task-1
> > ' id=46}::12=[SCOPE(...)],
> OSequence#26-sequence-activity-line-16::2=[SEQUENCE(self=(OSequence#26-sequence-activity-line-16,TerminationChannel#3,ParentSc
> > opeChannel#5), linkframe=org.apache.ode.bpel.runtime.LinkFrame@123fab3,
> remaining=[{OScope 'Task-1' id=46}, {OAssign : Message_End_Event,
> joinCondition=n
> > ull}, OReply#59-Message_End_Event-1])]}
> > DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 8551
> > DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid
> hqejbhcnphr4nogdhmowub and channel 23
> > DEBUG - GeronimoLog.debug(66) | Triggering response
> > ERROR - GeronimoLog.error(104) | Failure during invoke: Error sending
> message (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowub [PID {
> http://example.com/Test1/
> > Pool}Pool-1] calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}):
> org.apache.ws.security.components.crypto.Merlin cannot create instance
> > INFO - GeronimoLog.info(79) | ActivityRecovery: Registering activity 13,
> failure reason: Error sending message
> (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowu
> > b [PID {http://example.com/Test1/Pool}Pool-1<http://example.com/Test1/Pool%7DPool-1>]
> calling org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}):
> org.apache.ws.security.components.crypto.M
> > erlin cannot create instance on channel 24
> > DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 8551
> > DEBUG - GeronimoLog.debug(66) | Thread[ODEServer-2,5,main]:
> unlock(iid=8551)
> > DEBUG - GeronimoLog.debug(66) | Received myrole mex response callback
> > DEBUG - GeronimoLog.debug(66) | Handling response for MEX
> {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {
> http://example.com/T
> > est1/Pool}CanonicServiceForPool1.EventStartMessage(...)}
> > DEBUG - GeronimoLog.debug(66) | Starting transaction.
> > DEBUG - GeronimoLog.debug(66) | Failure response message: null
> > WARN - GeronimoLog.warn(96) | MEX produced a fault
> {MyRoleMex#hqejbhcnphr4nogdhmowu8 [Client hqejbhcnphr4nogdhmowu7] calling {
> http://example.com/Test1/Po
> > ol}CanonicServiceForPool1.EventStartMessage(...)}
> > org.apache.ode.axis2.OdeFault: Message exchange failure due to: Error
> sending message (mex={PartnerRoleMex#hqejbhcnphr4nogdhmowub [PID {
> http://example.co
> > m/Test1/Pool}Pool-1] calling
> org.apache.ode.bpel.epr.WSAEndpoint@1677979.echo(...)}):
> org.apache.ws.security.components.crypto.Merlin cannot create insta
> > nce
> >         at
> org.apache.ode.axis2.util.SoapMessageConverter.createOdeFault(SoapMessageConverter.java:570)
> >         at
> org.apache.ode.axis2.ODEService.onResponse(ODEService.java:282)
> >         at
> org.apache.ode.axis2.ODEService.onAxisMessageExchange(ODEService.java:189)
> >         at
> org.apache.ode.axis2.hooks.ODEMessageReceiver.invokeBusinessLogic(ODEMessageReceiver.java:69)
> >         at
> org.apache.ode.axis2.hooks.ODEMessageReceiver.invokeBusinessLogic(ODEMessageReceiver.java:52)
> >         at
> org.apache.axis2.receivers.AbstractMessageReceiver.receive(AbstractMessageReceiver.java:96)
> >         at
> org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:145)
> >         at
> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
> >         at
> org.apache.axis2.transport.http.AxisServlet.doPost(AxisServlet.java:120)
> >         at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
> >         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
> >         at
> org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
> >         at
> org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
> >         at
> org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
> >         at
> org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
> >         at
> org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
> >         at
> org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
> >         at
> org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
> >         at
> org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
> >         at
> org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:849)
> >         at
> org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
> >         at
> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:454)
> >         at java.lang.Thread.run(Thread.java:619)
> > DEBUG - GeronimoLog.debug(66) | Releasing mex hqejbhcnphr4nogdhmowu8
> > DEBUG - GeronimoLog.debug(66) | Comitting transaction.
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> > INFO - GeronimoLog.info(79) | Refreshing SYSTEM CRON jobs.
> > INFO - GeronimoLog.info(79) | SYSTEM CRON configuration found cron jobs:
> []
> >
> >
> >
> >
> >
>
>