ode-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From nileshlr <Nilesh_R...@3dplmsoftware.com>
Subject Re: calling external web service function fails, when using string array as input to function.
Date Thu, 29 Nov 2007 14:41:30 GMT

Hi Alex,
I restarted all the exercise again,Now I am facing another error I am giving
the error log :


[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[0] :
ECR-0000307
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[1] : Test
Everything
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[2] : 1234
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[3] : CBP
Workflow
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[4] : -
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[5] : eService
Sample
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[6] : CBP
Workflow
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[7] :
60019.35929.25984.39839
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[8] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[9] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[10] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[11] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[12] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[13] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: start args[14] : 
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: Got locator
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeProcess: got the portType
[Thu Nov 29 19:50:53 IST 2007] PLM: invokeWorkflow: returning: 
DEBUG - GeronimoLog.debug(66) | The system is checking for the operation
using the following WSAAction: 
19:50:53,815 DEBUG [ODEAxisDispatcher] The system is checking for the
operation using the following WSAAction: 
DEBUG - GeronimoLog.debug(66) | Checking for Operation using SOAP message
body's first child's local name : ECRRequestMsg
19:50:53,815 DEBUG [ODEAxisDispatcher] Checking for Operation using SOAP
message body's first child's local name : ECRRequestMsg
DEBUG - GeronimoLog.debug(66) | Received one-way message for
ecrProcess.{http://eclipse.org/bpel/sample}receiveIssue
19:50:53,815 DEBUG [ODEMessageReceiver] Received one-way message for
ecrProcess.{http://eclipse.org/bpel/sample}receiveIssue
DEBUG - GeronimoLog.debug(66) | Starting transaction.
19:50:53,815 DEBUG [ODEService] Starting transaction.
DEBUG - GeronimoLog.debug(66) | Routed: svcQname
{http://eclipse.org/bpel/sample}ECRProcessInvokeService -->
BpelProcess[{http://eclipse.org/bpel/sample}ECRProcess-247]
19:50:53,815 DEBUG [BpelEngineImpl] Routed: svcQname
{http://eclipse.org/bpel/sample}ECRProcessInvokeService -->
BpelProcess[{http://eclipse.org/bpel/sample}ECRProcess-247]
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxv1].setPortOp(...)
19:50:53,831 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv1].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | ODE routed to operation Operation:
name=receiveIssue
style=ONE_WAY,0
Input: name=RequestMessage
Message: name={http://eclipse.org/bpel/sample}ECRProcessRequestMessage
Part: name=payload
elementName={http://eclipse.org/bpel/sample}ECRRequestMsg from service
{http://eclipse.org/bpel/sample}ECRProcessInvokeService
19:50:53,831 DEBUG [ODEService] ODE routed to operation Operation:
name=receiveIssue
style=ONE_WAY,0
Input: name=RequestMessage
Message: name={http://eclipse.org/bpel/sample}ECRProcessRequestMessage
Part: name=payload
elementName={http://eclipse.org/bpel/sample}ECRRequestMsg from service
{http://eclipse.org/bpel/sample}ECRProcessInvokeService
DEBUG - GeronimoLog.debug(66) | Invoking ODE using MEX
{MyRoleMex#hqejbhcnphr2sa4ul6wxv1 [Client hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)}
19:50:53,846 DEBUG [ODEService] Invoking ODE using MEX
{MyRoleMex#hqejbhcnphr2sa4ul6wxv1 [Client hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)}
DEBUG - GeronimoLog.debug(66) | Message content:  <?xml version="1.0"
encoding="UTF-8"?>
<message><payload><ECRRequestMsg xmlns="http://eclipse.org/bpel/sample"
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"><username
xmlns="http://eclipse.org/bpel/sample">Test Everything</username><password
xmlns="http://eclipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name
xmlns="http://eclipse.org/bpel/sample">CBP-ECR-0000307</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><policy
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</policy><vault
xmlns="http://eclipse.org/bpel/sample">eService Sample</vault><invokerOID
xmlns="http://eclipse.org/bpel/sample">60019.35929.25984.39839</invokerOID></ECRRequestMsg></payload></message>
19:50:53,846 DEBUG [ODEService] Message content:  <?xml version="1.0"
encoding="UTF-8"?>
<message><payload><ECRRequestMsg xmlns="http://eclipse.org/bpel/sample"
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"><username
xmlns="http://eclipse.org/bpel/sample">Test Everything</username><password
xmlns="http://eclipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name
xmlns="http://eclipse.org/bpel/sample">CBP-ECR-0000307</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><policy
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</policy><vault
xmlns="http://eclipse.org/bpel/sample">eService Sample</vault><invokerOID
xmlns="http://eclipse.org/bpel/sample">60019.35929.25984.39839</invokerOID></ECRRequestMsg></payload></message>
DEBUG - GeronimoLog.debug(66) | Routed: svcQname
{http://eclipse.org/bpel/sample}ECRProcessInvokeService -->
BpelProcess[{http://eclipse.org/bpel/sample}ECRProcess-247]
19:50:53,862 DEBUG [BpelEngineImpl] Routed: svcQname
{http://eclipse.org/bpel/sample}ECRProcessInvokeService -->
BpelProcess[{http://eclipse.org/bpel/sample}ECRProcess-247]
DEBUG - GeronimoLog.debug(66) | invoke() EPR= null ==>
BpelProcess[{http://eclipse.org/bpel/sample}ECRProcess-247]
19:50:53,862 DEBUG [MyRoleMessageExchangeImpl] invoke() EPR= null ==>
BpelProcess[{http://eclipse.org/bpel/sample}ECRProcess-247]
DEBUG - GeronimoLog.debug(66) | Commiting ODE MEX
{MyRoleMex#hqejbhcnphr2sa4ul6wxv1 [Client hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)}
19:50:53,862 DEBUG [ODEService] Commiting ODE MEX
{MyRoleMex#hqejbhcnphr2sa4ul6wxv1 [Client hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)}
DEBUG - GeronimoLog.debug(66) | Commiting transaction.
19:50:53,862 DEBUG [ODEService] Commiting transaction.
DEBUG - GeronimoLog.debug(66) | >>
handleWorkEvent(jobData={type=INVOKE_INTERNAL, mexid=hqejbhcnphr2sa4ul6wxv1,
pid={http://eclipse.org/bpel/sample}ECRProcess-247})
19:50:54,081 DEBUG [BpelProcess] >>
handleWorkEvent(jobData={type=INVOKE_INTERNAL, mexid=hqejbhcnphr2sa4ul6wxv1,
pid={http://eclipse.org/bpel/sample}ECRProcess-247})
DEBUG - GeronimoLog.debug(66) | InvokeInternal event for mexid
hqejbhcnphr2sa4ul6wxv1
19:50:54,081 DEBUG [BpelProcess] InvokeInternal event for mexid
hqejbhcnphr2sa4ul6wxv1
DEBUG - GeronimoLog.trace(54) | >>
{PartnerLinkRole-client.ECRProcessProvider on
{http://eclipse.org/bpel/sample}ECRProcessInvokeService:ECRProcessPort}:inputMsgRcvd(messageExchange={MyRoleMex#hqejbhcnphr2sa4ul6wxv1
[Client hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)})
19:50:54,456 DEBUG [BpelProcess] >>
{PartnerLinkRole-client.ECRProcessProvider on
{http://eclipse.org/bpel/sample}ECRProcessInvokeService:ECRProcessPort}:inputMsgRcvd(messageExchange={MyRoleMex#hqejbhcnphr2sa4ul6wxv1
[Client hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)})
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxv1].setPortOp(...)
19:50:54,456 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv1].setPortOp(...)
DEBUG - GeronimoLog.trace(54) |
Mex[hqejbhcnphr2sa4ul6wxv1].setPattern(REQUEST_ONLY)
19:50:54,456 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv1].setPattern(REQUEST_ONLY)
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
19:50:55,003 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
19:50:55,003 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
19:50:55,003 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | INPUTMSG: 93.receiveIssue: MSG RCVD keys=[]
mySessionId=null partnerSessionId=null
19:50:55,018 DEBUG [BpelProcess] INPUTMSG: 93.receiveIssue: MSG RCVD keys=[]
mySessionId=null partnerSessionId=null
DEBUG - GeronimoLog.debug(66) | INPUTMSG: 93.receiveIssue: routing failed,
CREATING NEW INSTANCE
19:50:55,018 DEBUG [BpelProcess] INPUTMSG: 93.receiveIssue: routing failed,
CREATING NEW INSTANCE
DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance
61914. INDEXED STATE={}
19:50:55,096 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 61914. INDEXED STATE={}
DEBUG - GeronimoLog.trace(54) | >>
createScopeInstance(parentScopeId=<null>,scope={OScope
'__PROCESS_SCOPE:ECRProcess' id=15})
19:50:55,221 DEBUG [BpelProcess] >>
createScopeInstance(parentScopeId=<null>,scope={OScope
'__PROCESS_SCOPE:ECRProcess' id=15})
DEBUG - GeronimoLog.trace(54) | >>
initializeEndpointReferences(parentScopeId=61915,partnerLinks=[OPartnerLink#95,
OPartnerLink#98, OPartnerLink#99, OPartnerLink#97, OPartnerLink#93,
OPartnerLink#96, OPartnerLink#94])
19:50:55,315 DEBUG [BpelProcess] >>
initializeEndpointReferences(parentScopeId=61915,partnerLinks=[OPartnerLink#95,
OPartnerLink#98, OPartnerLink#99, OPartnerLink#97, OPartnerLink#93,
OPartnerLink#96, OPartnerLink#94])
DEBUG - GeronimoLog.trace(54) | >>
createScopeInstance(parentScopeId=61915,scope={OScope 'Design Change
Proposal' id=101})
19:50:56,909 DEBUG [BpelProcess] >>
createScopeInstance(parentScopeId=61915,scope={OScope 'Design Change
Proposal' id=101})
DEBUG - GeronimoLog.trace(54) | >>
initializeEndpointReferences(parentScopeId=61916,partnerLinks=[])
19:50:57,003 DEBUG [BpelProcess] >>
initializeEndpointReferences(parentScopeId=61916,partnerLinks=[])
DEBUG - GeronimoLog.trace(54) | >>
select(pickResponseChannel=PickResponseChannel#15,timeout=<null>,createInstance=true,selectors=[Lorg.apache.ode.bpel.runtime.Selector;@1fa63fa)
19:50:57,096 DEBUG [BpelProcess] >>
select(pickResponseChannel=PickResponseChannel#15,timeout=<null>,createInstance=true,selectors=[Lorg.apache.ode.bpel.runtime.Selector;@1fa63fa)
DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#15: USING
CORRELATOR 93.receiveIssue
19:50:57,096 DEBUG [BpelProcess] SELECT: PickResponseChannel#15: USING
CORRELATOR 93.receiveIssue
DEBUG - GeronimoLog.trace(54) | >>
findConflict(selectors=[Lorg.apache.ode.bpel.runtime.Selector;@1fa63fa)
19:50:57,440 DEBUG [OutstandingRequestManager] >>
findConflict(selectors=[Lorg.apache.ode.bpel.runtime.Selector;@1fa63fa)
DEBUG - GeronimoLog.trace(54) | >>
register(pickResponseChannel=15,selectors=[Lorg.apache.ode.bpel.runtime.Selector;@1fa63fa)
19:50:57,440 DEBUG [OutstandingRequestManager] >>
register(pickResponseChannel=15,selectors=[Lorg.apache.ode.bpel.runtime.Selector;@1fa63fa)
DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#15: CHECKING for
NEW INSTANCE match
19:50:57,440 DEBUG [BpelProcess] SELECT: PickResponseChannel#15: CHECKING
for NEW INSTANCE match
DEBUG - GeronimoLog.debug(66) | INPUTMSGMATCH: Changing process instance
state from ready to active
19:50:57,440 DEBUG [BpelProcess] INPUTMSGMATCH: Changing process instance
state from ready to active
DEBUG - GeronimoLog.trace(54) | >>
associate(pickResponseChannel=15,mexRef=hqejbhcnphr2sa4ul6wxv1)
19:50:57,440 DEBUG [OutstandingRequestManager] >>
associate(pickResponseChannel=15,mexRef=hqejbhcnphr2sa4ul6wxv1)
DEBUG - GeronimoLog.debug(66) | SELECT: PickResponseChannel#15: FOUND match
for NEW instance mexRef={MyRoleMex#hqejbhcnphr2sa4ul6wxv1 [Client
hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)}
19:50:57,440 DEBUG [BpelProcess] SELECT: PickResponseChannel#15: FOUND match
for NEW instance mexRef={MyRoleMex#hqejbhcnphr2sa4ul6wxv1 [Client
hqejbhcnphr2sa4ul6wxv0] calling
{http://eclipse.org/bpel/sample}ECRProcessInvokeService.receiveIssue(...)}
DEBUG - GeronimoLog.trace(54) | >>
createScopeInstance(parentScopeId=61916,scope={OScope
'CBPWorkflowPersistence' id=155})
19:51:00,237 DEBUG [BpelProcess] >>
createScopeInstance(parentScopeId=61916,scope={OScope
'CBPWorkflowPersistence' id=155})
DEBUG - GeronimoLog.trace(54) | >>
initializeEndpointReferences(parentScopeId=61917,partnerLinks=[])
19:51:00,346 DEBUG [BpelProcess] >>
initializeEndpointReferences(parentScopeId=61917,partnerLinks=[])
DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER:
partnerLink={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#94,scopeInstanceId=61915}, op=createBus
channel=InvokeResponseChannel#29)
19:51:00,487 DEBUG [BpelProcess] INVOKING PARTNER:
partnerLink={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#94,scopeInstanceId=61915}, op=createBus
channel=InvokeResponseChannel#29)
DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null
partnerSessionId=null
19:51:00,487 DEBUG [BpelRuntimeContextImpl] INVOKE PARTNER (SEP):
sessionId=null partnerSessionId=null
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxv5].setPortOp(...)
19:51:00,487 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv5].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Routed: svcQname
{http://client.engineering.cbp.jpo}ECRService --> null
19:51:00,487 DEBUG [BpelEngineImpl] Routed: svcQname
{http://client.engineering.cbp.jpo}ECRService --> null
DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: createBus
19:51:00,503 DEBUG [MessageExchangeContextImpl] Invoking a partner
operation: createBus
DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
service org.apache.ode.axis2.ExternalService@ae811c
19:51:00,503 DEBUG [MessageExchangeContextImpl] The service to invoke is the
external service org.apache.ode.axis2.ExternalService@ae811c
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><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="JpoCbpEngineeringECR"
xmlns:servicens="http://client.engineering.cbp.jpo">servicens:ECRService</ServiceName></Metadata><Address>http://localhost:8080/ematrix/services/JpoCbpEngineeringECR</Address></EndpointReference></service-ref>
19:51:00,503 DEBUG [EndpointReferenceContextImpl] 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><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="JpoCbpEngineeringECR"
xmlns:servicens="http://client.engineering.cbp.jpo">servicens:ECRService</ServiceName></Metadata><Address>http://localhost:8080/ematrix/services/JpoCbpEngineeringECR</Address></EndpointReference></service-ref>
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
19:51:00,503 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
19:51:00,503 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | My-Role EPR not specified, SEP will not be
used.
19:51:00,503 DEBUG [ExternalService] My-Role EPR not specified, SEP will not
be used.
DEBUG - GeronimoLog.debug(66) | Axis2 sending message to
http://localhost:8080/ematrix/services/JpoCbpEngineeringECR using MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv5 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@fea5ee.createBus(...)}
19:51:00,518 DEBUG [ExternalService] Axis2 sending message to
http://localhost:8080/ematrix/services/JpoCbpEngineeringECR using MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv5 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@fea5ee.createBus(...)}
DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineering.cbp.jpo"><username
xmlns="http://eclipse.org/bpel/sample">Test Everything</username><password
xmlns="http://eclipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name
xmlns="http://eclipse.org/bpel/sample">CBP-ECR-0000307</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><vault
xmlns="http://eclipse.org/bpel/sample">eService Sample</vault><policy
xmlns="http://eclipse.org/bpel/sample">CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv:Envelope>
19:51:00,534 DEBUG [ExternalService] Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineering.cbp.jpo"><username
xmlns="http://eclipse.org/bpel/sample">Test Everything</username><password
xmlns="http://eclipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name
xmlns="http://eclipse.org/bpel/sample">CBP-ECR-0000307</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><vault
xmlns="http://eclipse.org/bpel/sample">eService Sample</vault><policy
xmlns="http://eclipse.org/bpel/sample">CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr2sa4ul6wxv5
19:51:00,565 DEBUG [PartnerRoleMessageExchangeImpl] replyAsync
mex=hqejbhcnphr2sa4ul6wxv5
DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 61914
19:51:00,565 DEBUG [BpelRuntimeContextImpl] Setting execution state on
instance 61914
DEBUG - GeronimoLog.debug(66) | Releasing mex hqejbhcnphr2sa4ul6wxv1
19:51:00,690 DEBUG [MessageExchangeImpl] Releasing mex
hqejbhcnphr2sa4ul6wxv1
DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
using session null
19:51:01,784 DEBUG [SessionOutHandler] 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.
19:51:01,784 DEBUG [SessionOutHandler] 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/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8080/ematrix/services/JpoCbpEngineeringECR</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></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">uuid:hqejbhcnphr2sa4ul6wxv6</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineering.cbp.jpo"><username
xmlns="http://eclipse.org/bpel/sample">Test Everything</username><password
xmlns="http://eclipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name
xmlns="http://eclipse.org/bpel/sample">CBP-ECR-0000307</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><vault
xmlns="http://eclipse.org/bpel/sample">eService Sample</vault><policy
xmlns="http://eclipse.org/bpel/sample">CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv:Envelope>
19:51:01,784 DEBUG [SessionOutHandler] <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8080/ematrix/services/JpoCbpEngineeringECR</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></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">uuid:hqejbhcnphr2sa4ul6wxv6</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineering.cbp.jpo"><username
xmlns="http://eclipse.org/bpel/sample">Test Everything</username><password
xmlns="http://eclipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name
xmlns="http://eclipse.org/bpel/sample">CBP-ECR-0000307</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><vault
xmlns="http://eclipse.org/bpel/sample">eService Sample</vault><policy
xmlns="http://eclipse.org/bpel/sample">CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv:Envelope>
[Thu Nov 29 19:51:02 IST 2007] PLM: createBus: start
[Thu Nov 29 19:51:02 IST 2007] PLM: initContext user<<Test Everything>>
pass<<1234>>
[Thu Nov 29 19:51:02 IST 2007] PLM: createBus: initContext OK
[Thu Nov 29 19:51:02 IST 2007] PLM: createBus: creating BO
[Thu Nov 29 19:51:03 IST 2007] PLM: createBus: Created BO
[Thu Nov 29 19:51:03 IST 2007] PLM: createBus: id = 60000.336.27784.21346
[Thu Nov 29 19:51:04 IST 2007] PLM: createBus: returning:
60000.336.27784.21346
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxv5].setPortOp(...)
19:51:04,081 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv5].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Received response for MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv5 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling null.createBus(...)}
19:51:04,081 DEBUG [ExternalService] Received response for MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv5 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling null.createBus(...)}
DEBUG - GeronimoLog.debug(66) | RESPONSE (NORMAL): <?xml version="1.0"
encoding="UTF-8"?>
<message><createBusReturn xsi:type="xsd:string"
xmlns:ns1="http://client.engineering.cbp.jpo"
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">60000.336.27784.21346</createBusReturn></message>
19:51:04,081 DEBUG [ExternalService] RESPONSE (NORMAL): <?xml version="1.0"
encoding="UTF-8"?>
<message><createBusReturn xsi:type="xsd:string"
xmlns:ns1="http://client.engineering.cbp.jpo"
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">60000.336.27784.21346</createBusReturn></message>
DEBUG - GeronimoLog.debug(66) | reply mex=hqejbhcnphr2sa4ul6wxv5
19:51:04,081 DEBUG [PartnerRoleMessageExchangeImpl] reply
mex=hqejbhcnphr2sa4ul6wxv5
DEBUG - GeronimoLog.debug(66) | create work event for
mex=hqejbhcnphr2sa4ul6wxv5
19:51:04,081 DEBUG [PartnerRoleMessageExchangeImpl] create work event for
mex=hqejbhcnphr2sa4ul6wxv5
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)
19:51:04,112 DEBUG [InstanceLockManager] Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)-->GRANTED
19:51:04,112 DEBUG [InstanceLockManager] Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)-->GRANTED
DEBUG - GeronimoLog.debug(66) | >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2sa4ul6wxv5,
channel=29, iid=61914})
19:51:04,112 DEBUG [BpelProcess] >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2sa4ul6wxv5,
channel=29, iid=61914})
DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance
61914. INDEXED STATE={{OScope 'Design Change Proposal'
id=101}::3=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=101}::4=[ACTIVE(...)], {OScope 'CBPWorkflowPersistence'
id=155}::16=[ACTIVE(...)], OSequence#102-Assign ECR To
Coordinator::6=[SEQUENCE(self=(OSequence#102-Assign ECR To
Coordinator,TerminationChannel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1195d3e, remaining=[{OScope
'CBPWorkflowPersistence' id=155}, {OAssign : InitializeAndGetIID,
joinCondition=null}, {OScope 'GetInstanceInfo' id=168}, {OAssign :
AssignInstanceInfoToSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow' id=212}, {OAssign : AssignToPromoteCBPWF,
joinCondition=null}, {OScope 'PromoteCBPWorkflow' id=248}, {OAssign :
AssignConnectInput, joinCondition=null}, {OScope 'ConnectECRToCBPProcess'
id=288}, {OAssign : AssignCreateCoordinatorTask, joinCondition=null},
{OScope 'CreateCordinatorTask' id=324}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToTask' id=363}, {OAssign : PrepareGetOID,
joinCondition=null}, {OScope 'GetOIDForCoordinator' id=405}, {OAssign :
ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofCoordinatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=468}, {OScope 'ConnectCBPWorkflowTaskToCoordinator'
id=475}, {OAssign : AssignToPromoteECRToSubmit, joinCondition=null}, {OScope
'PromoteECRToSubmit' id=511}, {OAssign : SendCoordNotification,
joinCondition=null}, {OScope 'NotifyECRCoordinator' id=547}, {OAssign :
AssignToPromoteCBPWFT, joinCondition=null}, {OScope 'PromoteCBPWorkflow'
id=583}, OPickReceive#590-receiveCoordTaskCompletion, {OAssign :
AssignToPromoteECRToEvaluate, joinCondition=null}, {OScope
'PromoteECRToEvaluate' id=622}, {OAssign : AssignCreateEvaluatorTask,
joinCondition=null}, {OScope 'CreateEvaluatorTask' id=658}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToEvaluatorTask' id=697}, {OAssign :
PrepareGetEvaluatorOID, joinCondition=null}, {OScope 'GetOIDForCoordinator'
id=739}, {OAssign : ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofEvaluatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=802}, {OScope 'ConnectCBPWorkflowTaskToEvaluator'
id=809}, {OAssign : SendCoordNotification, joinCondition=null}, {OScope
'NotifyECREvaluator' id=845}, {OAssign : AssignToPromoteCBPWFT,
joinCondition=null}, {OScope 'PromoteCBPWorkflow' id=881},
OPickReceive#888-receiveEvaluatorTaskCompletion, {OAssign :
AssignToPromoteECRToReview, joinCondition=null}, {OScope
'PromoteECRToReview' id=920}, {OScope 'callbackClient' id=927}])],
OSequence#100-ECR Process::2=[SEQUENCE(self=(OSequence#100-ECR
Process,TerminationChannel#3,ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1195d3e, remaining=[{OScope
'Design Change Proposal' id=101}])], {OScope '__PROCESS_SCOPE:ECRProcess'
id=15}::0=[SCOPE(...)],
OInvoke#156-CBPWorkflowPersistence::18=[INVOKE(...)], OSequence#102-Assign
ECR To Coordinator::5=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=101}::4=[SCOPE(...)],
OInvoke#156-CBPWorkflowPersistence::17=[ACTIVITYGUARD(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=15}::0=[ACTIVE(...)], {OScope
'CBPWorkflowPersistence' id=155}::16=[SCOPE(...)], {OScope
'CBPWorkflowPersistence' id=155}::15=[ACTIVITYGUARD(...)], OSequence#100-ECR
Process::1=[ACTIVITYGUARD(...)]}
19:51:04,159 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 61914. INDEXED STATE={{OScope 'Design Change Proposal'
id=101}::3=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=101}::4=[ACTIVE(...)], {OScope 'CBPWorkflowPersistence'
id=155}::16=[ACTIVE(...)], OSequence#102-Assign ECR To
Coordinator::6=[SEQUENCE(self=(OSequence#102-Assign ECR To
Coordinator,TerminationChannel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1195d3e, remaining=[{OScope
'CBPWorkflowPersistence' id=155}, {OAssign : InitializeAndGetIID,
joinCondition=null}, {OScope 'GetInstanceInfo' id=168}, {OAssign :
AssignInstanceInfoToSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow' id=212}, {OAssign : AssignToPromoteCBPWF,
joinCondition=null}, {OScope 'PromoteCBPWorkflow' id=248}, {OAssign :
AssignConnectInput, joinCondition=null}, {OScope 'ConnectECRToCBPProcess'
id=288}, {OAssign : AssignCreateCoordinatorTask, joinCondition=null},
{OScope 'CreateCordinatorTask' id=324}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToTask' id=363}, {OAssign : PrepareGetOID,
joinCondition=null}, {OScope 'GetOIDForCoordinator' id=405}, {OAssign :
ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofCoordinatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=468}, {OScope 'ConnectCBPWorkflowTaskToCoordinator'
id=475}, {OAssign : AssignToPromoteECRToSubmit, joinCondition=null}, {OScope
'PromoteECRToSubmit' id=511}, {OAssign : SendCoordNotification,
joinCondition=null}, {OScope 'NotifyECRCoordinator' id=547}, {OAssign :
AssignToPromoteCBPWFT, joinCondition=null}, {OScope 'PromoteCBPWorkflow'
id=583}, OPickReceive#590-receiveCoordTaskCompletion, {OAssign :
AssignToPromoteECRToEvaluate, joinCondition=null}, {OScope
'PromoteECRToEvaluate' id=622}, {OAssign : AssignCreateEvaluatorTask,
joinCondition=null}, {OScope 'CreateEvaluatorTask' id=658}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToEvaluatorTask' id=697}, {OAssign :
PrepareGetEvaluatorOID, joinCondition=null}, {OScope 'GetOIDForCoordinator'
id=739}, {OAssign : ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofEvaluatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=802}, {OScope 'ConnectCBPWorkflowTaskToEvaluator'
id=809}, {OAssign : SendCoordNotification, joinCondition=null}, {OScope
'NotifyECREvaluator' id=845}, {OAssign : AssignToPromoteCBPWFT,
joinCondition=null}, {OScope 'PromoteCBPWorkflow' id=881},
OPickReceive#888-receiveEvaluatorTaskCompletion, {OAssign :
AssignToPromoteECRToReview, joinCondition=null}, {OScope
'PromoteECRToReview' id=920}, {OScope 'callbackClient' id=927}])],
OSequence#100-ECR Process::2=[SEQUENCE(self=(OSequence#100-ECR
Process,TerminationChannel#3,ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1195d3e, remaining=[{OScope
'Design Change Proposal' id=101}])], {OScope '__PROCESS_SCOPE:ECRProcess'
id=15}::0=[SCOPE(...)],
OInvoke#156-CBPWorkflowPersistence::18=[INVOKE(...)], OSequence#102-Assign
ECR To Coordinator::5=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=101}::4=[SCOPE(...)],
OInvoke#156-CBPWorkflowPersistence::17=[ACTIVITYGUARD(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=15}::0=[ACTIVE(...)], {OScope
'CBPWorkflowPersistence' id=155}::16=[SCOPE(...)], {OScope
'CBPWorkflowPersistence' id=155}::15=[ACTIVITYGUARD(...)], OSequence#100-ECR
Process::1=[ACTIVITYGUARD(...)]}
DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 61914
19:51:04,159 DEBUG [BpelProcess] InvokeResponse event for iid 61914
DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid
hqejbhcnphr2sa4ul6wxv5 and channel 29
19:51:04,159 DEBUG [BpelRuntimeContextImpl] Invoking message response for
mexid hqejbhcnphr2sa4ul6wxv5 and channel 29
DEBUG - GeronimoLog.debug(66) | Triggering response
19:51:04,159 DEBUG [BpelRuntimeContextImpl] Triggering response
DEBUG - GeronimoLog.trace(54) | >>
createScopeInstance(parentScopeId=61916,scope={OScope 'GetInstanceInfo'
id=168})
19:51:04,972 DEBUG [BpelProcess] >>
createScopeInstance(parentScopeId=61916,scope={OScope 'GetInstanceInfo'
id=168})
DEBUG - GeronimoLog.trace(54) | >>
initializeEndpointReferences(parentScopeId=61971,partnerLinks=[])
19:51:05,034 DEBUG [BpelProcess] >>
initializeEndpointReferences(parentScopeId=61971,partnerLinks=[])
DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER:
partnerLink={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#95,scopeInstanceId=61915}, op=getInstanceInfo
channel=InvokeResponseChannel#40)
19:51:05,190 DEBUG [BpelProcess] INVOKING PARTNER:
partnerLink={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#95,scopeInstanceId=61915}, op=getInstanceInfo
channel=InvokeResponseChannel#40)
DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null
partnerSessionId=null
19:51:05,190 DEBUG [BpelRuntimeContextImpl] INVOKE PARTNER (SEP):
sessionId=null partnerSessionId=null
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxv8].setPortOp(...)
19:51:05,190 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv8].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Routed: svcQname
{http://www.apache.org/ode/pmapi}InstanceManagementService --> null
19:51:05,190 DEBUG [BpelEngineImpl] Routed: svcQname
{http://www.apache.org/ode/pmapi}InstanceManagementService --> null
DEBUG - GeronimoLog.debug(66) | Invoking a partner operation:
getInstanceInfo
19:51:05,190 DEBUG [MessageExchangeContextImpl] Invoking a partner
operation: getInstanceInfo
DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
service org.apache.ode.axis2.ExternalService@6bc081
19:51:05,190 DEBUG [MessageExchangeContextImpl] The service to invoke is the
external service org.apache.ode.axis2.ExternalService@6bc081
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><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="InstanceManagementPort"
xmlns:servicens="http://www.apache.org/ode/pmapi">servicens:InstanceManagementService</ServiceName></Metadata><Address>http://localhost:8080/ode/processes/InstanceManagement</Address></EndpointReference></service-ref>
19:51:05,190 DEBUG [EndpointReferenceContextImpl] 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><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="InstanceManagementPort"
xmlns:servicens="http://www.apache.org/ode/pmapi">servicens:InstanceManagementService</ServiceName></Metadata><Address>http://localhost:8080/ode/processes/InstanceManagement</Address></EndpointReference></service-ref>
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
19:51:05,190 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
19:51:05,190 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | My-Role EPR not specified, SEP will not be
used.
19:51:05,190 DEBUG [ExternalService] My-Role EPR not specified, SEP will not
be used.
DEBUG - GeronimoLog.debug(66) | Axis2 sending message to
http://localhost:8080/ode/processes/InstanceManagement using MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv8 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@2b8387.getInstanceInfo(...)}
19:51:05,190 DEBUG [ExternalService] Axis2 sending message to
http://localhost:8080/ode/processes/InstanceManagement using MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv8 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@2b8387.getInstanceInfo(...)}
DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org/ode/pmapi"><iid>61914</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
19:51:05,190 DEBUG [ExternalService] Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org/ode/pmapi"><iid>61914</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr2sa4ul6wxv8
19:51:05,190 DEBUG [PartnerRoleMessageExchangeImpl] replyAsync
mex=hqejbhcnphr2sa4ul6wxv8
DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 61914
19:51:05,190 DEBUG [BpelRuntimeContextImpl] Setting execution state on
instance 61914
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-78,5,main]:
unlock(iid=61914)
19:51:05,268 DEBUG [InstanceLockManager] Thread[pool-2-thread-78,5,main]:
unlock(iid=61914)
DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
using session null
19:51:05,284 DEBUG [SessionOutHandler] 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.
19:51:05,300 DEBUG [SessionOutHandler] 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/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8080/ode/processes/InstanceManagement</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></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">uuid:hqejbhcnphr2sa4ul6wxv9</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org/ode/pmapi"><iid>61914</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
19:51:05,300 DEBUG [SessionOutHandler] <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8080/ode/processes/InstanceManagement</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></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">uuid:hqejbhcnphr2sa4ul6wxv9</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org/ode/pmapi"><iid>61914</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.debug(66) | The system is checking for the operation
using the following WSAAction: urn:anonOutInOp
19:51:05,300 DEBUG [ODEAxisDispatcher] The system is checking for the
operation using the following WSAAction: urn:anonOutInOp
DEBUG - GeronimoLog.debug(66) | Checking for Operation using SOAP message
body's first child's local name : getInstanceInfo
19:51:05,300 DEBUG [ODEAxisDispatcher] Checking for Operation using SOAP
message body's first child's local name : getInstanceInfo
DEBUG - GeronimoLog.debug(66) | Found operation
org.apache.axis2.description.InOutAxisOperation@19311da
19:51:05,300 DEBUG [ODEAxisDispatcher] Found operation
org.apache.axis2.description.InOutAxisOperation@19311da
DEBUG - GeronimoLog.debug(66) | Found a header in incoming message, checking
if there are endpoints there.
19:51:05,300 DEBUG [SessionInHandler] Found a header in incoming message,
checking if there are endpoints there.
DEBUG - GeronimoLog.debug(66) | Received mgmt message for
InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInfo
19:51:05,315 DEBUG [ManagementService] Received mgmt message for
InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInfo
DEBUG - GeronimoLog.debug(66) | Extracting param class java.lang.Long from
<iid>61914</iid>
19:51:05,315 DEBUG [ManagementService] Extracting param class java.lang.Long
from <iid>61914</iid>
DEBUG - GeronimoLog.debug(66) | Reply mgmt for
InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInfo
19:51:06,003 DEBUG [ManagementService] Reply mgmt for
InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInfo
DEBUG - GeronimoLog.debug(66) | Reply mgmt message <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns3:getInstanceInfoResponse
xmlns:axis2ns3="http://www.apache.org/ode/pmapi"><instance-info><ns:iid
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">61914</ns:iid><ns:pid
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">{http://eclipse.org/bpel/sample}ECRProcess-247</ns:pid><ns:process-name
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</ns:process-name><ns:root-scope
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/" siid="61915"
status="ACTIVE" name="__PROCESS_SCOPE:ECRProcess" modelId="15" /><ns:status
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">ACTIVE</ns:status><ns:dt-started
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:50:55.018+05:30</ns:dt-started><ns:dt-last-active
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:51:05.190+05:30</ns:dt-last-active><ns:event-info
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
/></instance-info></axis2ns3:getInstanceInfoResponse></soapenv:Body></soapenv:Envelope>
19:51:06,003 DEBUG [ManagementService] Reply mgmt message <?xml
version='1.0' encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns3:getInstanceInfoResponse
xmlns:axis2ns3="http://www.apache.org/ode/pmapi"><instance-info><ns:iid
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">61914</ns:iid><ns:pid
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">{http://eclipse.org/bpel/sample}ECRProcess-247</ns:pid><ns:process-name
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</ns:process-name><ns:root-scope
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/" siid="61915"
status="ACTIVE" name="__PROCESS_SCOPE:ECRProcess" modelId="15" /><ns:status
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">ACTIVE</ns:status><ns:dt-started
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:50:55.018+05:30</ns:dt-started><ns:dt-last-active
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:51:05.190+05:30</ns:dt-last-active><ns:event-info
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
/></instance-info></axis2ns3:getInstanceInfoResponse></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxv8].setPortOp(...)
19:51:06,018 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxv8].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Received response for MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv8 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
null.getInstanceInfo(...)}
19:51:06,018 DEBUG [ExternalService] Received response for MEX
{PartnerRoleMex#hqejbhcnphr2sa4ul6wxv8 [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
null.getInstanceInfo(...)}
DEBUG - GeronimoLog.debug(66) | RESPONSE (NORMAL): <?xml version="1.0"
encoding="UTF-8"?>
<message><instance-info xmlns:axis2ns3="http://www.apache.org/ode/pmapi"
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><iid
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">61914</iid><pid
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">{http://eclipse.org/bpel/sample}ECRProcess-247</pid><process-name
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</process-name><root-scope
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/" modelId="15"
name="__PROCESS_SCOPE:ECRProcess" siid="61915" status="ACTIVE"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"/><status
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">ACTIVE</status><dt-started
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:50:55.018+05:30</dt-started><dt-last-active
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:51:05.190+05:30</dt-last-active><event-info
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"/></instance-info></message>
19:51:06,018 DEBUG [ExternalService] RESPONSE (NORMAL): <?xml version="1.0"
encoding="UTF-8"?>
<message><instance-info xmlns:axis2ns3="http://www.apache.org/ode/pmapi"
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><iid
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">61914</iid><pid
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">{http://eclipse.org/bpel/sample}ECRProcess-247</pid><process-name
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</process-name><root-scope
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/" modelId="15"
name="__PROCESS_SCOPE:ECRProcess" siid="61915" status="ACTIVE"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"/><status
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">ACTIVE</status><dt-started
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:50:55.018+05:30</dt-started><dt-last-active
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/">2007-11-29T19:51:05.190+05:30</dt-last-active><event-info
xmlns="http://www.apache.org/ode/pmapi/types/2006/08/02/"
xmlns:ns="http://www.apache.org/ode/pmapi/types/2006/08/02/"/></instance-info></message>
DEBUG - GeronimoLog.debug(66) | reply mex=hqejbhcnphr2sa4ul6wxv8
19:51:06,018 DEBUG [PartnerRoleMessageExchangeImpl] reply
mex=hqejbhcnphr2sa4ul6wxv8
DEBUG - GeronimoLog.debug(66) | create work event for
mex=hqejbhcnphr2sa4ul6wxv8
19:51:06,018 DEBUG [PartnerRoleMessageExchangeImpl] create work event for
mex=hqejbhcnphr2sa4ul6wxv8
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)
19:51:06,018 DEBUG [InstanceLockManager] Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)-->GRANTED
19:51:06,034 DEBUG [InstanceLockManager] Thread[pool-2-thread-78,5,main]:
lock(iid=61914, time=1MICROSECONDS)-->GRANTED
DEBUG - GeronimoLog.debug(66) | >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2sa4ul6wxv8,
channel=40, iid=61914})
19:51:06,034 DEBUG [BpelProcess] >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2sa4ul6wxv8,
channel=40, iid=61914})
DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance
61914. INDEXED STATE={OInvoke#169-GetInstanceInfo::23=[ACTIVITYGUARD(...)],
OInvoke#169-GetInstanceInfo::24=[INVOKE(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=15}::0=[ACTIVE(...)], {OScope
'GetInstanceInfo' id=168}::21=[ACTIVITYGUARD(...)], {OScope 'Design Change
Proposal' id=101}::3=[ACTIVITYGUARD(...)], {OScope 'GetInstanceInfo'
id=168}::22=[ACTIVE(...)], {OScope 'GetInstanceInfo'
id=168}::22=[SCOPE(...)], OSequence#102-Assign ECR To
Coordinator::5=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=101}::4=[SCOPE(...)], OSequence#100-ECR Process::1=[ACTIVITYGUARD(...)],
OSequence#102-Assign ECR To
Coordinator::6=[SEQUENCE(self=(OSequence#102-Assign ECR To
Coordinator,TerminationChannel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1255044, remaining=[{OScope
'GetInstanceInfo' id=168}, {OAssign : AssignInstanceInfoToSetAttribute,
joinCondition=null}, {OScope 'UpdateCBPWorkflow' id=212}, {OAssign :
AssignToPromoteCBPWF, joinCondition=null}, {OScope 'PromoteCBPWorkflow'
id=248}, {OAssign : AssignConnectInput, joinCondition=null}, {OScope
'ConnectECRToCBPProcess' id=288}, {OAssign : AssignCreateCoordinatorTask,
joinCondition=null}, {OScope 'CreateCordinatorTask' id=324}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToTask' id=363}, {OAssign : PrepareGetOID,
joinCondition=null}, {OScope 'GetOIDForCoordinator' id=405}, {OAssign :
ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofCoordinatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=468}, {OScope 'ConnectCBPWorkflowTaskToCoordinator'
id=475}, {OAssign : AssignToPromoteECRToSubmit, joinCondition=null}, {OScope
'PromoteECRToSubmit' id=511}, {OAssign : SendCoordNotification,
joinCondition=null}, {OScope 'NotifyECRCoordinator' id=547}, {OAssign :
AssignToPromoteCBPWFT, joinCondition=null}, {OScope 'PromoteCBPWorkflow'
id=583}, OPickReceive#590-receiveCoordTaskCompletion, {OAssign :
AssignToPromoteECRToEvaluate, joinCondition=null}, {OScope
'PromoteECRToEvaluate' id=622}, {OAssign : AssignCreateEvaluatorTask,
joinCondition=null}, {OScope 'CreateEvaluatorTask' id=658}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToEvaluatorTask' id=697}, {OAssign :
PrepareGetEvaluatorOID, joinCondition=null}, {OScope 'GetOIDForCoordinator'
id=739}, {OAssign : ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofEvaluatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=802}, {OScope 'ConnectCBPWorkflowTaskToEvaluator'
id=809}, {OAssign : SendCoordNotification, joinCondition=null}, {OScope
'NotifyECREvaluator' id=845}, {OAssign : AssignToPromoteCBPWFT,
joinCondition=null}, {OScope 'PromoteCBPWorkflow' id=881},
OPickReceive#888-receiveEvaluatorTaskCompletion, {OAssign :
AssignToPromoteECRToReview, joinCondition=null}, {OScope
'PromoteECRToReview' id=920}, {OScope 'callbackClient' id=927}])],
OSequence#100-ECR Process::2=[SEQUENCE(self=(OSequence#100-ECR
Process,TerminationChannel#3,ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1255044, remaining=[{OScope
'Design Change Proposal' id=101}])], {OScope 'Design Change Proposal'
id=101}::4=[ACTIVE(...)], {OScope '__PROCESS_SCOPE:ECRProcess'
id=15}::0=[SCOPE(...)]}
19:51:06,034 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 61914. INDEXED
STATE={OInvoke#169-GetInstanceInfo::23=[ACTIVITYGUARD(...)],
OInvoke#169-GetInstanceInfo::24=[INVOKE(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=15}::0=[ACTIVE(...)], {OScope
'GetInstanceInfo' id=168}::21=[ACTIVITYGUARD(...)], {OScope 'Design Change
Proposal' id=101}::3=[ACTIVITYGUARD(...)], {OScope 'GetInstanceInfo'
id=168}::22=[ACTIVE(...)], {OScope 'GetInstanceInfo'
id=168}::22=[SCOPE(...)], OSequence#102-Assign ECR To
Coordinator::5=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=101}::4=[SCOPE(...)], OSequence#100-ECR Process::1=[ACTIVITYGUARD(...)],
OSequence#102-Assign ECR To
Coordinator::6=[SEQUENCE(self=(OSequence#102-Assign ECR To
Coordinator,TerminationChannel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1255044, remaining=[{OScope
'GetInstanceInfo' id=168}, {OAssign : AssignInstanceInfoToSetAttribute,
joinCondition=null}, {OScope 'UpdateCBPWorkflow' id=212}, {OAssign :
AssignToPromoteCBPWF, joinCondition=null}, {OScope 'PromoteCBPWorkflow'
id=248}, {OAssign : AssignConnectInput, joinCondition=null}, {OScope
'ConnectECRToCBPProcess' id=288}, {OAssign : AssignCreateCoordinatorTask,
joinCondition=null}, {OScope 'CreateCordinatorTask' id=324}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToTask' id=363}, {OAssign : PrepareGetOID,
joinCondition=null}, {OScope 'GetOIDForCoordinator' id=405}, {OAssign :
ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofCoordinatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=468}, {OScope 'ConnectCBPWorkflowTaskToCoordinator'
id=475}, {OAssign : AssignToPromoteECRToSubmit, joinCondition=null}, {OScope
'PromoteECRToSubmit' id=511}, {OAssign : SendCoordNotification,
joinCondition=null}, {OScope 'NotifyECRCoordinator' id=547}, {OAssign :
AssignToPromoteCBPWFT, joinCondition=null}, {OScope 'PromoteCBPWorkflow'
id=583}, OPickReceive#590-receiveCoordTaskCompletion, {OAssign :
AssignToPromoteECRToEvaluate, joinCondition=null}, {OScope
'PromoteECRToEvaluate' id=622}, {OAssign : AssignCreateEvaluatorTask,
joinCondition=null}, {OScope 'CreateEvaluatorTask' id=658}, {OAssign :
AssignToLinkTaskToProcess, joinCondition=null}, {OScope
'ConnectCBPWorkflowToEvaluatorTask' id=697}, {OAssign :
PrepareGetEvaluatorOID, joinCondition=null}, {OScope 'GetOIDForCoordinator'
id=739}, {OAssign : ExtractOID, joinCondition=null}, {OAssign :
PrepareAssignmentofEvaluatorTask, joinCondition=null}, {OAssign :
AssignCorrelationIDForSetAttribute, joinCondition=null}, {OScope
'UpdateCBPWorkflow1' id=802}, {OScope 'ConnectCBPWorkflowTaskToEvaluator'
id=809}, {OAssign : SendCoordNotification, joinCondition=null}, {OScope
'NotifyECREvaluator' id=845}, {OAssign : AssignToPromoteCBPWFT,
joinCondition=null}, {OScope 'PromoteCBPWorkflow' id=881},
OPickReceive#888-receiveEvaluatorTaskCompletion, {OAssign :
AssignToPromoteECRToReview, joinCondition=null}, {OScope
'PromoteECRToReview' id=920}, {OScope 'callbackClient' id=927}])],
OSequence#100-ECR Process::2=[SEQUENCE(self=(OSequence#100-ECR
Process,TerminationChannel#3,ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1255044, remaining=[{OScope
'Design Change Proposal' id=101}])], {OScope 'Design Change Proposal'
id=101}::4=[ACTIVE(...)], {OScope '__PROCESS_SCOPE:ECRProcess'
id=15}::0=[SCOPE(...)]}
DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 61914
19:51:06,034 DEBUG [BpelProcess] InvokeResponse event for iid 61914
DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid
hqejbhcnphr2sa4ul6wxv8 and channel 40
19:51:06,034 DEBUG [BpelRuntimeContextImpl] Invoking message response for
mexid hqejbhcnphr2sa4ul6wxv8 and channel 40
DEBUG - GeronimoLog.debug(66) | Triggering response
19:51:06,050 DEBUG [BpelRuntimeContextImpl] Triggering response
DEBUG - GeronimoLog.trace(54) | >>
createScopeInstance(parentScopeId=61916,scope={OScope 'UpdateCBPWorkflow'
id=212})
19:51:08,034 DEBUG [BpelProcess] >>
createScopeInstance(parentScopeId=61916,scope={OScope 'UpdateCBPWorkflow'
id=212})
DEBUG - GeronimoLog.trace(54) | >>
initializeEndpointReferences(parentScopeId=61991,partnerLinks=[])
19:51:08,065 DEBUG [BpelProcess] >>
initializeEndpointReferences(parentScopeId=61991,partnerLinks=[])
DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER:
partnerLink={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#96,scopeInstanceId=61915}, op=setAttribute
channel=InvokeResponseChannel#51)
19:51:08,159 DEBUG [BpelProcess] INVOKING PARTNER:
partnerLink={PartnerLinkInstance
partnerLinkDecl=OPartnerLink#96,scopeInstanceId=61915}, op=setAttribute
channel=InvokeResponseChannel#51)
DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null
partnerSessionId=null
19:51:08,159 DEBUG [BpelRuntimeContextImpl] INVOKE PARTNER (SEP):
sessionId=null partnerSessionId=null
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2sa4ul6wxvb].setPortOp(...)
19:51:08,159 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2sa4ul6wxvb].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Routed: svcQname
{http://client.engineering.cbp.jpo}CBPWorkflowService --> null
19:51:08,159 DEBUG [BpelEngineImpl] Routed: svcQname
{http://client.engineering.cbp.jpo}CBPWorkflowService --> null
DEBUG - GeronimoLog.debug(66) | Invoking a partner operation: setAttribute
19:51:08,159 DEBUG [MessageExchangeContextImpl] Invoking a partner
operation: setAttribute
DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
service org.apache.ode.axis2.ExternalService@6ebe50
19:51:08,159 DEBUG [MessageExchangeContextImpl] The service to invoke is the
external service org.apache.ode.axis2.ExternalService@6ebe50
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><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="JpoCbpEngineeringCBPWorkflow"
xmlns:servicens="http://client.engineering.cbp.jpo">servicens:CBPWorkflowService</ServiceName></Metadata><Address>http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow</Address></EndpointReference></service-ref>
19:51:08,159 DEBUG [EndpointReferenceContextImpl] 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><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="JpoCbpEngineeringCBPWorkflow"
xmlns:servicens="http://client.engineering.cbp.jpo">servicens:CBPWorkflowService</ServiceName></Metadata><Address>http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow</Address></EndpointReference></service-ref>
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
19:51:08,175 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
19:51:08,175 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | My-Role EPR not specified, SEP will not be
used.
19:51:08,175 DEBUG [ExternalService] My-Role EPR not specified, SEP will not
be used.
DEBUG - GeronimoLog.debug(66) | Axis2 sending message to
http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow using
MEX {PartnerRoleMex#hqejbhcnphr2sa4ul6wxvb [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@90b1e8.setAttribute(...)}
19:51:08,175 DEBUG [ExternalService] Axis2 sending message to
http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow using
MEX {PartnerRoleMex#hqejbhcnphr2sa4ul6wxvb [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@90b1e8.setAttribute(...)}
DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns4:setAttribute
xmlns:axis2ns4="http://engineering.cbp.jpo"
xmlns:tns1="http://engineering.cbp.jpo">
                   <tns1:in0 xmlns:ns1="http://client.engineering.cbp.jpo"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xmlns:xsd="http://www.w3.org/2001/XMLSchema"
xsi:type="xsd:string">60000.336.27784.21346</tns1:in0> 
                   <tns1:in1><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Instance
ID</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
ID</tns1:item></tns1:in1> 
                   <tns1:in2><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">61914</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">{http://eclipse.org/bpel/sample}ECRProcess-247</tns1:item></tns1:in2> 
	        </axis2ns4:setAttribute></soapenv:Body></soapenv:Envelope>
19:51:08,175 DEBUG [ExternalService] Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns4:setAttribute
xmlns:axis2ns4="http://engineering.cbp.jpo"
xmlns:tns1="http://engineering.cbp.jpo">
                   <tns1:in0 xmlns:ns1="http://client.engineering.cbp.jpo"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xmlns:xsd="http://www.w3.org/2001/XMLSchema"
xsi:type="xsd:string">60000.336.27784.21346</tns1:in0> 
                   <tns1:in1><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Instance
ID</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
ID</tns1:item></tns1:in1> 
                   <tns1:in2><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">61914</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">{http://eclipse.org/bpel/sample}ECRProcess-247</tns1:item></tns1:in2> 
	        </axis2ns4:setAttribute></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr2sa4ul6wxvb
19:51:08,175 DEBUG [PartnerRoleMessageExchangeImpl] replyAsync
mex=hqejbhcnphr2sa4ul6wxvb
DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 61914
19:51:08,175 DEBUG [BpelRuntimeContextImpl] Setting execution state on
instance 61914
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-78,5,main]:
unlock(iid=61914)
19:51:08,237 DEBUG [InstanceLockManager] Thread[pool-2-thread-78,5,main]:
unlock(iid=61914)
DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
using session null
19:51:08,253 DEBUG [SessionOutHandler] 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.
19:51:08,253 DEBUG [SessionOutHandler] 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/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></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">uuid:hqejbhcnphr2sa4ul6wxvc</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns4:setAttribute
xmlns:axis2ns4="http://engineering.cbp.jpo"
xmlns:tns1="http://engineering.cbp.jpo">
                   <tns1:in0 xmlns:ns1="http://client.engineering.cbp.jpo"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xmlns:xsd="http://www.w3.org/2001/XMLSchema"
xsi:type="xsd:string">60000.336.27784.21346</tns1:in0> 
                   <tns1:in1><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Instance
ID</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
ID</tns1:item></tns1:in1> 
                   <tns1:in2><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">61914</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">{http://eclipse.org/bpel/sample}ECRProcess-247</tns1:item></tns1:in2> 
	        </axis2ns4:setAttribute></soapenv:Body></soapenv:Envelope>
19:51:08,253 DEBUG [SessionOutHandler] <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope
xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></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">uuid:hqejbhcnphr2sa4ul6wxvc</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns4:setAttribute
xmlns:axis2ns4="http://engineering.cbp.jpo"
xmlns:tns1="http://engineering.cbp.jpo">
                   <tns1:in0 xmlns:ns1="http://client.engineering.cbp.jpo"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xmlns:xsd="http://www.w3.org/2001/XMLSchema"
xsi:type="xsd:string">60000.336.27784.21346</tns1:in0> 
                   <tns1:in1><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Instance
ID</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
ID</tns1:item></tns1:in1> 
                   <tns1:in2><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">61914</tns1:item><tns1:item
xmlns:tns1="http://client.engineering.cbp.jpo">{http://eclipse.org/bpel/sample}ECRProcess-247</tns1:item></tns1:in2> 
	        </axis2ns4:setAttribute></soapenv:Body></soapenv:Envelope>
ERROR - GeronimoLog.error(108) | Error sending message
(mex={PartnerRoleMex#hqejbhcnphr2sa4ul6wxvb [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@90b1e8.setAttribute(...)}):
org.xml.sax.SAXException: Found character data inside an array element while
deserializing
org.apache.axis2.AxisFault: org.xml.sax.SAXException: Found character data
inside an array element while deserializing
	at
org.apache.axis2.util.Utils.getInboundFaultFromMessageContext(Utils.java:486)
	at
org.apache.axis2.description.OutInAxisOperationClient.handleResponse(OutInAxisOperation.java:343)
	at
org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:389)
	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.ExternalService$1$1.call(ExternalService.java:150)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
19:51:08,347 ERROR [ExternalService] Error sending message
(mex={PartnerRoleMex#hqejbhcnphr2sa4ul6wxvb [PID
{http://eclipse.org/bpel/sample}ECRProcess-247] calling
org.apache.ode.bpel.epr.WSAEndpoint@90b1e8.setAttribute(...)}):
org.xml.sax.SAXException: Found character data inside an array element while
deserializing
org.apache.axis2.AxisFault: org.xml.sax.SAXException: Found character data
inside an array element while deserializing
	at
org.apache.axis2.util.Utils.getInboundFaultFromMessageContext(Utils.java:486)
	at
org.apache.axis2.description.OutInAxisOperationClient.handleResponse(OutInAxisOperation.java:343)
	at
org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:389)
	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.ExternalService$1$1.call(ExternalService.java:150)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)


Here is my new Wsdl file:- 

<?xml version="1.0" encoding="UTF-8"?>
<wsdl:definitions 
targetNamespace="http://client.engineering.cbp.jpo"
xmlns:apachesoap="http://xml.apache.org/xml-soap" 
xmlns:impl="http://client.engineering.cbp.jpo" 
xmlns:intf="http://client.engineering.cbp.jpo" 
xmlns:tns1="http://engineering.cbp.jpo" 
xmlns:wsdl="http://schemas.xmlsoap.org/wsdl/" 
xmlns:wsdlsoap="http://schemas.xmlsoap.org/wsdl/soap/" 
xmlns:xsd="http://www.w3.org/2001/XMLSchema" 
xmlns:plnk="http://docs.oasis-open.org/wsbpel/2.0/plnktype">

<!--WSDL created by Apache Axis version: 1.4
Built on Apr 22, 2006 (06:55:48 PDT)-->
 <wsdl:types>
  <schema elementFormDefault="qualified"
targetNamespace="http://engineering.cbp.jpo"
xmlns="http://www.w3.org/2001/XMLSchema">
   <element name="setAttribute">
    <complexType>
     <sequence>
      <element name="in0" type="xsd:string"/>
      <element name="in1" type="impl:ArrayList"/>
      <element name="in2" type="impl:ArrayList"/>
     </sequence>
    </complexType>
   </element>
   <complexType name="ArrayList">
    <sequence>
     <element name="item" type="xsd:string"/>
    </sequence>
   </complexType>
   <element name="setAttributeResponse">
    <complexType/>
   </element>
   <element name="getObjectID">
    <complexType>
     <sequence>
      <element name="in0" type="xsd:string"/>
      <element name="in1" type="xsd:string"/>
      <element name="in2" type="xsd:string"/>
      <element name="in3" type="xsd:string"/>
      <element name="in4" type="xsd:string"/>
     </sequence>
    </complexType>
   </element>
   <element name="getObjectIDResponse">
    <complexType>
     <sequence>
      <element name="getObjectIDReturn" type="xsd:string"/>
     </sequence>
    </complexType>
   </element>
   <element name="invokeProcess">
    <complexType>
     <sequence>
      <element maxOccurs="unbounded" name="in0" type="xsd:string"/>
     </sequence>
    </complexType>
   </element>
   <element name="invokeProcessResponse">
    <complexType>
     <sequence>
      <element name="invokeProcessReturn" type="xsd:string"/>
     </sequence>
    </complexType>
   </element>
   <element name="boPromoteDemote">
    <complexType>
     <sequence>
      <element name="in0" type="xsd:string"/>
      <element name="in1" type="xsd:string"/>
      <element name="in2" type="xsd:string"/>
      <element name="in3" type="xsd:boolean"/>
     </sequence>
    </complexType>
   </element>
   <element name="boPromoteDemoteResponse">
    <complexType>
     <sequence>
      <element name="boPromoteDemoteReturn" type="xsd:string"/>
     </sequence>
    </complexType>
   </element>
  </schema>
 </wsdl:types>

   <wsdl:message name="setAttributeResponse">

      <wsdl:part element="tns1:setAttributeResponse" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="invokeProcessRequest">

      <wsdl:part element="tns1:invokeProcess" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="boPromoteDemoteRequest">

      <wsdl:part element="tns1:boPromoteDemote" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="setAttributeRequest">

      <wsdl:part element="tns1:setAttribute" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="getObjectIDResponse">

      <wsdl:part element="tns1:getObjectIDResponse" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="boPromoteDemoteResponse">

      <wsdl:part element="tns1:boPromoteDemoteResponse" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="getObjectIDRequest">

      <wsdl:part element="tns1:getObjectID" name="parameters"/>

   </wsdl:message>

   <wsdl:message name="invokeProcessResponse">

      <wsdl:part element="tns1:invokeProcessResponse" name="parameters"/>

   </wsdl:message>

   <wsdl:portType name="CBPWorkflow">

      <wsdl:operation name="setAttribute">

         <wsdl:input message="impl:setAttributeRequest"
name="setAttributeRequest"/>

         <wsdl:output message="impl:setAttributeResponse"
name="setAttributeResponse"/>

      </wsdl:operation>

      <wsdl:operation name="getObjectID">

         <wsdl:input message="impl:getObjectIDRequest"
name="getObjectIDRequest"/>

         <wsdl:output message="impl:getObjectIDResponse"
name="getObjectIDResponse"/>

      </wsdl:operation>

      <wsdl:operation name="invokeProcess">

         <wsdl:input message="impl:invokeProcessRequest"
name="invokeProcessRequest"/>

         <wsdl:output message="impl:invokeProcessResponse"
name="invokeProcessResponse"/>

      </wsdl:operation>

      <wsdl:operation name="boPromoteDemote">

         <wsdl:input message="impl:boPromoteDemoteRequest"
name="boPromoteDemoteRequest"/>

         <wsdl:output message="impl:boPromoteDemoteResponse"
name="boPromoteDemoteResponse"/>

      </wsdl:operation>

   </wsdl:portType>

   <wsdl:binding name="JpoCbpEngineeringCBPWorkflowSoapBinding"
type="impl:CBPWorkflow">

      <wsdlsoap:binding style="document"
transport="http://schemas.xmlsoap.org/soap/http"/>

      <wsdl:operation name="setAttribute">

         <wsdlsoap:operation soapAction=""/>

         <wsdl:input name="setAttributeRequest">

            <wsdlsoap:body use="literal"/>

         </wsdl:input>

         <wsdl:output name="setAttributeResponse">

            <wsdlsoap:body use="literal"/>

         </wsdl:output>

      </wsdl:operation>

      <wsdl:operation name="getObjectID">

         <wsdlsoap:operation soapAction=""/>

         <wsdl:input name="getObjectIDRequest">

            <wsdlsoap:body use="literal"/>

         </wsdl:input>

         <wsdl:output name="getObjectIDResponse">

            <wsdlsoap:body use="literal"/>

         </wsdl:output>

      </wsdl:operation>

      <wsdl:operation name="invokeProcess">

         <wsdlsoap:operation soapAction=""/>

         <wsdl:input name="invokeProcessRequest">

            <wsdlsoap:body use="literal"/>

         </wsdl:input>

         <wsdl:output name="invokeProcessResponse">

            <wsdlsoap:body use="literal"/>

         </wsdl:output>

      </wsdl:operation>

      <wsdl:operation name="boPromoteDemote">

         <wsdlsoap:operation soapAction=""/>

         <wsdl:input name="boPromoteDemoteRequest">

            <wsdlsoap:body use="literal"/>

         </wsdl:input>

         <wsdl:output name="boPromoteDemoteResponse">

            <wsdlsoap:body use="literal"/>

         </wsdl:output>

      </wsdl:operation>

   </wsdl:binding>

   <wsdl:service name="CBPWorkflowService">

      <wsdl:port binding="impl:JpoCbpEngineeringCBPWorkflowSoapBinding"
name="JpoCbpEngineeringCBPWorkflow">

         <wsdlsoap:address
location="http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow"/>

      </wsdl:port>

   </wsdl:service>

<!-- ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
     Partner Link -  Defines the role in the interaction of the service with
external partner (in this case the Process)
         
     ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
-->      

	<plnk:partnerLinkType name="CBPWorkflow">
		<plnk:role name="me" portType="impl:CBPWorkflow" />
		<plnk:role name="you"
			portType="impl:CBPWorkflow" />
	</plnk:partnerLinkType>


</wsdl:definitions>


I think issue is with maping of array structure with the class I had written
in web Service.Can anybody help me in this or any link and document on this
will be great help.

From
Nilesh Rane

Alex Boisvert wrote:
> 
> Hi Nilesh,
> 
> I would recommend using an XML editor to load the XML document that you
> are
> sending from Ode and perform validation against your schema(s).   This
> will
> help you narrow down where the mismatch(es) is (are).
> 
> alex
> 
> On 11/14/07, nileshlr <nileshlr@rediff.com> wrote:
>>
>>
>> Hi Alex,
>> Thanks for reply.I did the changes you suggested but getting same
>> error.Here
>> I am attaching log:
>>
>> DEBUG - GeronimoLog.debug(66) | Axis2 sending message to
>> http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow using
>> MEX {PartnerRoleMex#hqejbhcnphr2qu7o05xwgr [PID
>> {http://eclipse.org/bpel/sample}ECRProcess-212] calling
>> org.apache.ode.bpel.epr.WSAEndpoint@3b0d43.setAttribute(...)}
>> 17:22:51,455 DEBUG [ExternalService] Axis2 sending message to
>> http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow using
>> MEX {PartnerRoleMex#hqejbhcnphr2qu7o05xwgr [PID
>> {http://eclipse.org/bpel/sample}ECRProcess-212] calling
>> org.apache.ode.bpel.epr.WSAEndpoint@3b0d43.setAttribute(...)}
>> DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
>> encoding='utf-8'?><soapenv:Envelope
>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
>> "><soapenv:Body><axis2ns8:setAttribute
>> xmlns:axis2ns8="http://engineering.cbp.jpo"
>> xmlns:tns1="http://engineering.cbp.jpo">
>>                    <tns1:in0
>> xmlns:ns1="http://client.engineering.cbp.jpo"
>> xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>> xmlns:xsd="http://www.w3.org/2001/XMLSchema"
>> xsi:type="xsd:string">60000.336.28909.27891</tns1:in0>
>>                    <tns1:in1><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Instance
>> ID</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
>> ID</tns1:item></tns1:in1>
>>                    <tns1:in2><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">50911</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">{
>> http://eclipse.org/bpel/sample}ECRProcess-212</tns1:item></tns1:in2>
>>                
>> </axis2ns8:setAttribute></soapenv:Body></soapenv:Envelope>
>> 17:22:51,455 DEBUG [ExternalService] Message: <?xml version='1.0'
>> encoding='utf-8'?><soapenv:Envelope
>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
>> "><soapenv:Body><axis2ns8:setAttribute
>> xmlns:axis2ns8="http://engineering.cbp.jpo"
>> xmlns:tns1="http://engineering.cbp.jpo">
>>                    <tns1:in0
>> xmlns:ns1="http://client.engineering.cbp.jpo"
>> xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>> xmlns:xsd="http://www.w3.org/2001/XMLSchema"
>> xsi:type="xsd:string">60000.336.28909.27891</tns1:in0>
>>                    <tns1:in1><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Instance
>> ID</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
>> ID</tns1:item></tns1:in1>
>>                    <tns1:in2><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">50911</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">{
>> http://eclipse.org/bpel/sample}ECRProcess-212</tns1:item></tns1:in2>
>>                
>> </axis2ns8:setAttribute></soapenv:Body></soapenv:Envelope>
>> DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr2qu7o05xwgr
>> 17:22:51,455 DEBUG [PartnerRoleMessageExchangeImpl] replyAsync
>> mex=hqejbhcnphr2qu7o05xwgr
>> DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 50911
>> 17:22:51,455 DEBUG [BpelRuntimeContextImpl] Setting execution state on
>> instance 50911
>> DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-276,5,main]:
>> unlock(iid=50911)
>> 17:22:51,502 DEBUG [InstanceLockManager]
>> Thread[pool-2-thread-276,5,main]:
>> unlock(iid=50911)
>> DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
>> using session null
>> 17:22:51,518 DEBUG [SessionOutHandler] 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.
>> 17:22:51,518 DEBUG [SessionOutHandler] 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/
>> "><soapenv:Header><addr:To
>> xmlns:addr="http://www.w3.org/2005/08/addressing">
>> http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow
>> </addr:To><addr:Action
>> xmlns:addr="http://www.w3.org/2005/08/addressing
>> "></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
>> ">uuid:hqejbhcnphr2qu7o05xwgs</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns8:setAttribute
>> xmlns:axis2ns8="http://engineering.cbp.jpo"
>> xmlns:tns1="http://engineering.cbp.jpo">
>>                    <tns1:in0
>> xmlns:ns1="http://client.engineering.cbp.jpo"
>> xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>> xmlns:xsd="http://www.w3.org/2001/XMLSchema"
>> xsi:type="xsd:string">60000.336.28909.27891</tns1:in0>
>>                    <tns1:in1><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Instance
>> ID</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
>> ID</tns1:item></tns1:in1>
>>                    <tns1:in2><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">50911</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">{
>> http://eclipse.org/bpel/sample}ECRProcess-212</tns1:item></tns1:in2>
>>                
>> </axis2ns8:setAttribute></soapenv:Body></soapenv:Envelope>
>> 17:22:51,518 DEBUG [SessionOutHandler] <?xml version='1.0'
>> encoding='utf-8'?><soapenv:Envelope
>> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/
>> "><soapenv:Header><addr:To
>> xmlns:addr="http://www.w3.org/2005/08/addressing">
>> http://localhost:8080/ematrix/services/JpoCbpEngineeringCBPWorkflow
>> </addr:To><addr:Action
>> xmlns:addr="http://www.w3.org/2005/08/addressing
>> "></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
>> ">uuid:hqejbhcnphr2qu7o05xwgs</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns8:setAttribute
>> xmlns:axis2ns8="http://engineering.cbp.jpo"
>> xmlns:tns1="http://engineering.cbp.jpo">
>>                    <tns1:in0
>> xmlns:ns1="http://client.engineering.cbp.jpo"
>> xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
>> xmlns:xsd="http://www.w3.org/2001/XMLSchema"
>> xsi:type="xsd:string">60000.336.28909.27891</tns1:in0>
>>                    <tns1:in1><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Instance
>> ID</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">Process Instance
>> ID</tns1:item></tns1:in1>
>>                    <tns1:in2><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">50911</tns1:item><tns1:item
>> xmlns:tns1="http://client.engineering.cbp.jpo">{
>> http://eclipse.org/bpel/sample}ECRProcess-212</tns1:item></tns1:in2>
>>                
>> </axis2ns8:setAttribute></soapenv:Body></soapenv:Envelope>
>> ERROR - GeronimoLog.error(108) | Error sending message
>> (mex={PartnerRoleMex#hqejbhcnphr2qu7o05xwgr [PID
>> {http://eclipse.org/bpel/sample}ECRProcess-212] calling
>> org.apache.ode.bpel.epr.WSAEndpoint@3b0d43.setAttribute(...)}):
>> org.xml.sax.SAXException: Invalid element in
>> jpo.cbp.engineering.ArrayOfItems - item
>> org.apache.axis2.AxisFault: org.xml.sax.SAXException: Invalid element in
>> jpo.cbp.engineering.ArrayOfItems - item
>>         at
>> org.apache.axis2.util.Utils.getInboundFaultFromMessageContext(Utils.java
>> :486)
>>         at
>> org.apache.axis2.description.OutInAxisOperationClient.handleResponse(
>> OutInAxisOperation.java:343)
>>         at
>> org.apache.axis2.description.OutInAxisOperationClient.send(
>> OutInAxisOperation.java:389)
>>         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.ExternalService$1$1.call(
>> ExternalService.java:150)
>>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java
>> :269)
>>         at java.util.concurrent.FutureTask.run(FutureTask.java:123)
>>         at
>> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(
>> ThreadPoolExecutor.java:650)
>>         at
>> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java
>> :675)
>>         at java.lang.Thread.run(Thread.java:595)
>> 17:22:51,534 ERROR [ExternalService] Error sending message
>> (mex={PartnerRoleMex#hqejbhcnphr2qu7o05xwgr [PID
>> {http://eclipse.org/bpel/sample}ECRProcess-212] calling
>> org.apache.ode.bpel.epr.WSAEndpoint@3b0d43.setAttribute(...)}):
>> org.xml.sax.SAXException: Invalid element in
>> jpo.cbp.engineering.ArrayOfItems - item
>> org.apache.axis2.AxisFault: org.xml.sax.SAXException: Invalid element in
>> jpo.cbp.engineering.ArrayOfItems - item
>>         at
>> org.apache.axis2.util.Utils.getInboundFaultFromMessageContext(Utils.java
>> :486)
>>         at
>> org.apache.axis2.description.OutInAxisOperationClient.handleResponse(
>> OutInAxisOperation.java:343)
>>         at
>> org.apache.axis2.description.OutInAxisOperationClient.send(
>> OutInAxisOperation.java:389)
>>         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.ExternalService$1$1.call(
>> ExternalService.java:150)
>>         at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java
>> :269)
>>         at java.util.concurrent.FutureTask.run(FutureTask.java:123)
>>         at
>> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(
>> ThreadPoolExecutor.java:650)
>>         at
>> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java
>> :675)
>>         at java.lang.Thread.run(Thread.java:595)
>>
> 
> 

-- 
View this message in context: http://www.nabble.com/calling-external-web-service-function-fails%2C-when-using-string-array-as-input-to-function.-tf4770975.html#a14026937
Sent from the Apache Ode User mailing list archive at Nabble.com.


Mime
View raw message