Hi Alex, Sorry for the late response, I'm trying to look into this tonight...
Thanks, Tammo On Sun, Nov 24, 2013 at 2:36 PM, alexgifei <[email protected]> wrote: > 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. > -- Tammo van Lessen - http://www.taval.de
