Hello,
I am creating a simple web service invocation using Apache ODE. The flow is
like this: receive input -> assign -> invoke -> assign -> reply output. When
i am testing the generated wsdl artifact, i am getting a selection failure
error back in the response. Here is what i see in the logs (I have posted
the invoking part and also the deployment part at the beginning).
15:29:43,805 WARN [AxisServlet] No transportReceiver for
org.apache.axis2.transport.http.AxisServletListener found. An instance for
HTTP will be configured automatically. Please update your axis2.xml file!
15:29:43,848 DEBUG [ODEServer] Loading properties
15:29:43,851 DEBUG [ODEServer] Initializing transaction manager
15:29:43,852 DEBUG [ODEServer] Initializing transaction manager using
org.apache.ode.il.EmbeddedGeronimoFactory
15:29:43,878 DEBUG [ODEServer] Creating data source.
15:29:44,489 DEBUG [ODEServer] Starting DAO.
15:29:44,490 INFO [ODEServer] Using DAO Connection Factory class
org.apache.ode.dao.jpa.BPELDAOConnectionFactoryImpl.
15:29:45,263 DEBUG [ODEServer] Initializing BPEL process store.
15:29:45,320 DEBUG [ODEServer] Initializing BPEL server.
15:29:45,320 DEBUG [ODEServer] ODE initializing
15:29:46,227 DEBUG [BpelServerImpl] BPEL SERVER initializing
15:29:46,243 DEBUG [ODEServer] Initializing HTTP connection manager
15:29:46,266 DEBUG [ODEServer] http.connection-manager.max-per-host=10
15:29:46,266 DEBUG [ODEServer] http.connection-manager.max-total=10
15:29:46,271 DEBUG [ODEServer] http.idle.connection.timeout=30000
15:29:46,271 DEBUG [ODEServer] http.idle.connection.check.interval=30000
15:29:48,574 DEBUG [ODEServer] Process store event:
{ProcessStoreEvent#ACTVIATED:{http://composer}Composer-122}
15:29:48,579 TRACE [BpelServerImpl] unregister:
{http://composer}Composer-122
15:29:48,579 DEBUG [BpelEngineImpl] Unregister process:
serviceId={http://composer}Composer-122, process=null
15:29:48,579 DEBUG [BpelServerImpl] register: {http://composer}Composer-122
15:29:48,580 DEBUG [BpelServerImpl] Registering process
{http://composer}Composer-122 with server.
15:29:48,608 DEBUG [BpelEngineImpl] Register process:
serviceId={http://composer}ComposerService:ComposerPort,
process=BpelProcess[{http://composer}Composer-122]
15:29:48,616 DEBUG [BpelProcess] Activating {http://composer}Composer-122
15:29:48,868 DEBUG [ODEAxisService] Create AxisService:
service={http://composer}ComposerService port=ComposerPort
WSDL=ComposerArtifacts.wsdl BPEL=Composer.bpel
15:29:48,887 DEBUG [Axis2WSDLLocator] Get import: import=Directions.wsdl
parent=file:/home/alexg/Tools/apache-tomcat-6.0.37/webapps/ode/WEB-INF/processes/Composer/ComposerArtifacts.wsdl
15:29:48,946 DEBUG [ODEAxisService] Looking for Axis2 service configuration
file:
file:/home/alexg/Tools/apache-tomcat-6.0.37/webapps/ode/WEB-INF/processes/Composer/ComposerService.axis2
15:29:48,947 DEBUG [ODEAxisService] Axis2 service configuration not found:
file:/home/alexg/Tools/apache-tomcat-6.0.37/webapps/ode/WEB-INF/processes/Composer/ComposerService.axis2
15:29:49,146 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml
version="1.0" encoding="UTF-8"?>
<EndpointReference
xmlns="http://www.w3.org/2005/08/addressing";><Metadata><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl";
EndpointName="ComposerPort"
xmlns:servicens="http://composer";>servicens:ComposerService</ServiceName></Metadata><Address>http://localhost:8080/ode/processes/Composer</Address></EndpointReference>
15:29:49,158 DEBUG [BindingContextImpl] Destroying service
{http://composer}ComposerService port ComposerPort
15:29:49,158 DEBUG [BindingContextImpl] Couldn't find service
{http://composer}ComposerService port ComposerPort to destroy.
15:29:49,158 DEBUG [BindingContextImpl] Created Axis2 service
{http://composer}ComposerService
15:29:49,159 DEBUG [BpelProcess] Activated {http://composer}Composer-122
myrole client: EPR is org.apache.ode.bpel.epr.WSAEndpoint@e0928ee
15:29:49,159 DEBUG [BpelProcess] Activated {http://composer}Composer-122
15:29:49,159 DEBUG [NStateLatch] Rehydrating process
{http://composer}Composer-122
15:29:49,327 DEBUG [BpelProcess] Processing <invoke> element for process
{http://composer}Composer-122: partnerlink DirectionsPL -->
{http://www.example.org/Directions/}Directions:DirectionsHTTP
15:29:49,343 DEBUG [BindingContextImpl] Creating HTTP-bound external service
{http://www.example.org/Directions/}Directions
15:29:49,346 DEBUG [ExternalService] new HTTP External service, service
name=[{http://www.example.org/Directions/}Directions]; port
name=[DirectionsHTTP]
15:29:49,352 DEBUG [WSAEndpoint] Constructed a new WSAEndpoint: <?xml
version="1.0" encoding="UTF-8"?>
<EndpointReference
xmlns="http://www.w3.org/2005/08/addressing";><Metadata><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl";
EndpointName="DirectionsHTTP"
xmlns:servicens="http://www.example.org/Directions/";>servicens:Directions</ServiceName></Metadata><Address>http://maps.googleapis.com/maps/api/directions</Address></EndpointReference>
15:29:49,363 DEBUG [BindingContextImpl] Created external service
{http://www.example.org/Directions/}Directions
15:29:49,364 DEBUG [NStateLatch] Activated {http://composer}Composer-122
partnerrole DirectionsPL: EPR is
org.apache.ode.bpel.epr.WSAEndpoint@69333a27
15:29:49,441 DEBUG [BpelProcess] Creating new process DAO for
{http://composer}Composer-122 (guid=14bnrs7l5i6sep4m7k7v5c7txd9lsl7q)...
15:29:49,803 DEBUG [BpelProcess] Found ProcessDAO for
{http://composer}Composer-122 with GUID 14bnrs7l5i6sep4m7k7v5c7txd9lsl7q
15:29:49,804 DEBUG [BpelProcess] Created new process DAO for
{http://composer}Composer-122 (guid=14bnrs7l5i6sep4m7k7v5c7txd9lsl7q).
15:29:49,820 INFO [BpelServerImpl] Registered process
{http://composer}Composer-122.
15:29:49,820 DEBUG [ODEServer] (Re)scheduling cron scheduled jobs on store
event: {ProcessStoreEvent#ACTVIATED:{http://composer}Composer-122}
15:29:49,821 DEBUG [CronScheduler] Cancelling PROCESS CRON jobs for:
{http://composer}Composer-122
15:29:49,821 DEBUG [CronScheduler] Scheduling PROCESS CRON jobs for:
{http://composer}Composer-122
15:29:49,821 DEBUG [BpelServerImpl] BPEL SERVER starting.
15:29:49,844 INFO [BpelServerImpl] BPEL Server Started.
15:29:49,845 DEBUG [ODEServer] No deploy-ext.properties found.
15:29:49,850 INFO [SystemSchedulesConfig] SYSTEM CRON configuration:
/home/alexg/Tools/apache-tomcat-6.0.37/webapps/ode/WEB-INF/conf/schedules.xml
15:29:50,432 DEBUG [ODEServer] Initializing Deployment Web Service
15:29:50,468 DEBUG [ODEServer] Starting scheduler
15:29:50,498 DEBUG [ODEServer] Initializing JCA adapter.
15:29:51,012 INFO [DeploymentPoller] Poller started.
15:29:51,012 INFO [ODEServer] Process deployment polling started on path
/home/alexg/Tools/apache-tomcat-6.0.37/webapps/ode/WEB-INF/processes.
15:29:51,012 INFO [ODEServer] ODE Service Engine has been started.
Nov 24, 2013 3:29:51 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
15:29:51,042 DEBUG [ODEServer] Process store event:
{ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{http://composer}Composer-122}
15:29:51,045 DEBUG [ODEServer] Ignoring store event:
{ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{http://composer}Composer-122}
15:29:51,045 DEBUG [ODEServer] (Re)scheduling cron scheduled jobs on store
event:
{ProcessStoreEvent#SCHEDULE_SETTINGS_CHANGED:{http://composer}Composer-122}
15:29:51,045 DEBUG [CronScheduler] Cancelling PROCESS CRON jobs for:
{http://composer}Composer-122
15:29:51,046 DEBUG [CronScheduler] Scheduling PROCESS CRON jobs for:
{http://composer}Composer-122
15:29:51,047 DEBUG [CronScheduler] Refreshing SYSTEM CRON jobs.
15:29:51,047 DEBUG [SystemSchedulesConfig] SYSTEM CRON configuration found
cron jobs: []
Nov 24, 2013 3:29:51 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Nov 24, 2013 3:29:51 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/71 config=null
Nov 24, 2013 3:29:51 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 11284 ms
15:30:15,414 DEBUG [ODEMessageReceiver] Received request message for
Composer.{http://composer}process
15:30:15,420 DEBUG [WSAEndpoint] Filled transfo map with service:
{http://composer}ComposerService
15:30:15,420 DEBUG [WSAEndpoint] Filled transfo map with port: ComposerPort
15:30:15,424 DEBUG [ODEService] Starting transaction.
15:30:15,427 DEBUG [BpelEngineImpl] Routed: svcQname
{http://composer}ComposerService -->
[BpelProcess[{http://composer}Composer-122]]
15:30:15,444 TRACE [MessageExchangeImpl]
Mex[hqejbhcnphr8s8gt4iqtww].setPortOp(...)
15:30:15,445 DEBUG [ODEService] ODE routed to operation Operation:
name=process
style=REQUEST_RESPONSE,1
Input: name=null
Message: name={http://composer}ComposerRequestMessage
Part: name=origin
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=sensor
typeName={http://www.w3.org/2001/XMLSchema}string
Part: name=destination
typeName={http://www.w3.org/2001/XMLSchema}string
Output: name=null
Message: name={http://composer}ComposerResponseMessage
Part: name=Response
typeName={http://www.w3.org/2001/XMLSchema}string from service
{http://composer}ComposerService
15:30:15,445 DEBUG [MessageExchangeImpl] SET MEX property isTwoWay = true
15:30:15,458 DEBUG [ODEService] Invoking ODE using MEX
{MyRoleMex#hqejbhcnphr8s8gt4iqtww [Client hqejbhcnphr8s8gt4iqtwv] calling
{http://composer}ComposerService.process(...)}
15:30:15,460 DEBUG [ODEService] Message content: <?xml version="1.0"
encoding="UTF-8"?>
<message><origin xmlns:ns0="http://composer";
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";>Iasi</origin><destination
xmlns:ns0="http://composer";
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";>Bacau</destination><sensor
xmlns:ns0="http://composer";
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";
xmlns:xsd="http://www.w3.org/2001/XMLSchema";
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";>false</sensor></message>
15:30:15,465 DEBUG [MyRoleMessageExchangeImpl] invoke() EPR= null ==>
BpelProcess[{http://composer}Composer-122]
15:30:15,525 DEBUG [ODEService] Commiting ODE MEX
{MyRoleMex#hqejbhcnphr8s8gt4iqtww [Client hqejbhcnphr8s8gt4iqtwv] calling
{http://composer}ComposerService.process(...)}
15:30:15,526 DEBUG [ODEService] Commiting transaction.
15:30:15,950 DEBUG [WSAEndpoint] Filled transfo map with service:
{http://composer}ComposerService
15:30:15,950 DEBUG [WSAEndpoint] Filled transfo map with port: ComposerPort
15:30:15,983 TRACE [BpelEngineImpl] [JOB] onScheduledJob
hqejbhcnphr8s8gt4iqtwx[0]: JobDetails( instanceId: null mexId:
hqejbhcnphr8s8gt4iqtww processId: {http://composer}Composer-122 type:
INVOKE_INTERNAL channel: null correlatorId: null correlationKeySet: null
retryCount: null inMem: false detailsExt: {})null
15:30:15,984 DEBUG [BpelEngineImpl] handleJobDetails: InvokeInternal event
for mexid hqejbhcnphr8s8gt4iqtww
15:30:16,050 DEBUG [BpelProcess] >> handleJobDetails(jobData=JobDetails(
instanceId: null mexId: hqejbhcnphr8s8gt4iqtww processId:
{http://composer}Composer-122 type: INVOKE_INTERNAL channel: null
correlatorId: null correlationKeySet: null retryCount: null inMem: false
detailsExt: {}))
15:30:16,050 DEBUG [BpelProcess] InvokeInternal event for mexid
hqejbhcnphr8s8gt4iqtww
15:30:16,059 TRACE [BpelProcess] >> {PartnerLinkRole-client.ComposerProvider
on
{http://composer}ComposerService:ComposerPort}:inputMsgRcvd(messageExchange={MyRoleMex#hqejbhcnphr8s8gt4iqtww
[Client hqejbhcnphr8s8gt4iqtwv] calling
{http://composer}ComposerService.process(...)})
15:30:16,059 TRACE [MessageExchangeImpl]
Mex[hqejbhcnphr8s8gt4iqtww].setPortOp(...)
15:30:16,061 TRACE [MessageExchangeImpl]
Mex[hqejbhcnphr8s8gt4iqtww].setPattern(REQUEST_RESPONSE)
15:30:16,147 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
15:30:16,147 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
15:30:16,147 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
15:30:16,147 DEBUG [BpelProcess] INPUTMSG: client.process: MSG RCVD keys=[]
mySessionId=null partnerSessionId=null
15:30:16,257 DEBUG [BpelProcess] INPUTMSG: client.process: routing failed,
CREATING NEW INSTANCE
15:30:16,398 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 6651. INDEXED STATE={}
15:30:16,421 DEBUG [InstanceLockManager] Thread[ODEServer-1,5,main]:
lock(iid=6651, time=1MICROSECONDS)
15:30:16,422 DEBUG [InstanceLockManager] Thread[ODEServer-1,5,main]:
lock(iid=6651, time=1MICROSECONDS)-->GRANTED
15:30:16,428 TRACE [BpelProcess] >>
createScopeInstance(parentScopeId=<null>,scope={OScope
'__PROCESS_SCOPE:Composer' id=3})
15:30:16,527 TRACE [BpelProcess] >>
initializeEndpointReferences(parentScopeId=6701,partnerLinks=[OPartnerLink#22,
OPartnerLink#21])
15:30:16,562 TRACE [BpelProcess] >>
select(pickResponseChannel=PickResponseChannel#9,timeout=<null>,createInstance=true,selectors=[Lorg.apache.ode.bpel.runtime.Selector;@71f72fd7)
15:30:16,564 DEBUG [BpelProcess] SELECT: PickResponseChannel#9: USING
CORRELATOR client.process
15:30:16,572 TRACE [IMAManager] >>
findConflict(selectors=[Lorg.apache.ode.bpel.runtime.Selector;@71f72fd7)
15:30:16,573 TRACE [IMAManager] >>
register(pickResponseChannel=9,selectors=[Lorg.apache.ode.bpel.runtime.Selector;@71f72fd7)
15:30:16,574 DEBUG [BpelProcess] SELECT: PickResponseChannel#9: CHECKING for
NEW INSTANCE match
15:30:16,574 DEBUG [BpelProcess] INPUTMSGMATCH: Changing process instance
state from ready to active
15:30:16,576 DEBUG [BpelProcess] SELECT: PickResponseChannel#9: FOUND match
for NEW instance mexRef={MyRoleMex#hqejbhcnphr8s8gt4iqtww [Client
hqejbhcnphr8s8gt4iqtwv] calling
{http://composer}ComposerService.process(...)}
15:30:16,589 TRACE [IMAManager] >> cancel(pickResponseChannel=9)
15:30:16,595 TRACE [IMAManager] >>
process(partnerLinkInstance={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#21,scopeInstanceId=6701},operationName=process,messageExchangeId=<null>,mexRef=hqejbhcnphr8s8gt4iqtww)
15:30:16,730 TRACE [BpelProcess] >>
createScopeInstance(parentScopeId=6701,scope={OCatch faultName=null,
faultVariable=null})
15:30:16,731 TRACE [BpelProcess] >>
initializeEndpointReferences(parentScopeId=6702,partnerLinks=[])
15:30:16,754 DEBUG [BpelProcess] ProcessImpl completed with fault
'{http://docs.oasis-open.org/wsbpel/2.0/process/executable}selectionFailure'
15:30:16,756 TRACE [IMAManager] >> releaseAll()
15:30:16,757 TRACE [MessageExchangeImpl]
Mex[hqejbhcnphr8s8gt4iqtww].setPortOp(...)
15:30:16,759 DEBUG [MessageExchangeContextImpl] Processing an async reply
from service {http://composer}ComposerService
15:30:17,683 DEBUG [InstanceLockManager] Thread[ODEServer-1,5,main]:
unlock(iid=6651)
15:30:17,683 DEBUG [MyRoleMessageExchangeImpl] Received myrole mex response
callback
15:30:17,685 DEBUG [MyRoleMessageExchangeImpl] Received myrole mex response
callback
15:30:17,687 DEBUG [ODEService] Handling response for MEX
{MyRoleMex#hqejbhcnphr8s8gt4iqtww [Client hqejbhcnphr8s8gt4iqtwv] calling
{http://composer}ComposerService.process(...)}
15:30:17,687 DEBUG [ODEService] Starting transaction.
15:30:17,704 TRACE [MessageExchangeImpl]
Mex[hqejbhcnphr8s8gt4iqtww].setPortOp(...)
15:30:17,705 DEBUG [ODEService] Fault response message:
{http://docs.oasis-open.org/wsbpel/2.0/process/executable}selectionFailure
15:30:17,756 DEBUG [ODEService] Returning fault: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";><soapenv:Body><soapenv:Fault><faultcode>soapenv:Server</faultcode><faultstring
xmlns:axis2ns1="http://docs.oasis-open.org/wsbpel/2.0/process/executable";>axis2ns1:selectionFailure</faultstring><detail
/></soapenv:Fault></soapenv:Body></soapenv:Envelope>
15:30:17,756 DEBUG [MyRoleMessageExchangeImpl] Releasing mex
hqejbhcnphr8s8gt4iqtww
15:30:17,756 DEBUG [ODEService] Comitting transaction.
15:30:17,756 DEBUG [ODEMessageReceiver] Reply for
Composer.{http://composer}process
15:30:17,757 DEBUG [ODEMessageReceiver] Reply message <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";><soapenv:Body><soapenv:Fault><faultcode>soapenv:Server</faultcode><faultstring
xmlns:axis2ns1="http://docs.oasis-open.org/wsbpel/2.0/process/executable";>axis2ns1:selectionFailure</faultstring><detail
/></soapenv:Fault></soapenv:Body></soapenv:Envelope>
Here is the link to my source code, it's an archived eclipse BPEL project.
https://www.dropbox.com/s/hq2kwejxx3dcjve/Composer.tar.gz I would appreciate
if someone can take a look over this.
--
View this message in context:
http://apache-ode.996305.n3.nabble.com/Invoking-web-service-using-Apache-ODE-tp16972.html
Sent from the Apache Ode User mailing list archive at Nabble.com.
