ode-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Anup Chandran <anup.chand...@3ds.com>
Subject Re: Invoking InstanceManagement API
Date Mon, 10 Sep 2007 14:38:39 GMT

Alex,

Breaks at earlier point now. I used the latest and rebuilt the code. Looks
like the latest codebase has some other issue, so i cannot move forward. Let
me know if i can selectively patch on 1.1 RC4 to resolve the PMAPI issues.

The Latest traces are :


DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="http://s
chemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineering.cbp.jpo">
<username>Test Everything</username><password>1234</password><type>CBP
Workflow</type><name>CBP-ECR00091</name><rev>-</r
ev><vault>eService Sample</vault><policy>CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv:Envelope>
09:07:45,773 DEBUG [ExternalService] Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="htt
p://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineering.cbp.
jpo"><username>Test Everything</username><password>1234</password><type>CBP
Workflow</type><name>CBP-ECR00091</name><rev
>-</rev><vault>eService Sample</vault><policy>CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv:Envelop
e>
DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
using session null
09:07:45,803 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.
09:07:45,803 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.xm
lsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:80
80/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/addressin
g/anonymous</addr:Address></addr:ReplyTo><addr:MessageID
xmlns:addr="http://www.w3.org/2005/08/addressing">uuid:hqejbhcn
phr2ksbul6v7m9</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.engineer
ing.cbp.jpo"><username>Test
Everything</username><password>1234</password><type>CBP
Workflow</type><name>CBP-ECR00091</n
ame><rev>-</rev><vault>eService Sample</vault><policy>CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></soapenv
:Envelope>
09:07:45,813 DEBUG [SessionOutHandler] <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="http://sch
emas.xmlsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://local
host: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/ad
dressing/anonymous</addr:Address></addr:ReplyTo><addr:MessageID
xmlns:addr="http://www.w3.org/2005/08/addressing">uuid:h
qejbhcnphr2ksbul6v7m9</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.e
ngineering.cbp.jpo"><username>Test
Everything</username><password>1234</password><type>CBP
Workflow</type><name>CBP-ECR0
0091</name><rev>-</rev><vault>eService Sample</vault><policy>CBP
Workflow</policy></axis2ns1:createBus></soapenv:Body></
soapenv:Envelope>
[Mon Sep 10 09:07:56 EDT 2007] PLM: createBus: start
[Mon Sep 10 09:07:56 EDT 2007] PLM: initContext user<<Test Everything>>
pass<<1234>>
[Mon Sep 10 09:07:57 EDT 2007] PLM: createBus: initContext OK
[Mon Sep 10 09:07:57 EDT 2007] PLM: createBus: creating BO
[Mon Sep 10 09:08:03 EDT 2007] PLM: createBus: Created BO
[Mon Sep 10 09:08:03 EDT 2007] PLM: createBus: id = 15863.19604.41264.41167
[Mon Sep 10 09:08:05 EDT 2007] PLM: createBus: returning:
15863.19604.41264.41167
FATAL - GeronimoLog.fatal(120) | Internal Error
java.lang.NullPointerException
        at
org.apache.ode.bpel.engine.PartnerLinkPartnerRoleImpl.createPartnerRoleMex(PartnerLinkPartnerRoleImpl.java:67
)
        at
org.apache.ode.bpel.engine.BpelProcess.createPartnerRoleMex(BpelProcess.java:882)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:536)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:513)
        at
org.apache.ode.bpel.engine.Contexts.execTransaction(Contexts.java:100)
        at
org.apache.ode.bpel.engine.BpelServerImpl$TransactedCallable.call(BpelServerImpl.java:779)
        at
org.apache.ode.bpel.engine.BpelServerImpl$ServerCallable.call(BpelServerImpl.java:760)
        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)
09:08:05,341 FATAL [BpelServerImpl] Internal Error
java.lang.NullPointerException
        at
org.apache.ode.bpel.engine.PartnerLinkPartnerRoleImpl.createPartnerRoleMex(PartnerLinkPartnerRoleImpl.java:67
)
        at
org.apache.ode.bpel.engine.BpelProcess.createPartnerRoleMex(BpelProcess.java:882)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:536)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:513)
        at
org.apache.ode.bpel.engine.Contexts.execTransaction(Contexts.java:100)
        at
org.apache.ode.bpel.engine.BpelServerImpl$TransactedCallable.call(BpelServerImpl.java:779)
        at
org.apache.ode.bpel.engine.BpelServerImpl$ServerCallable.call(BpelServerImpl.java:760)
        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)
ERROR - GeronimoLog.error(108) | Error executing reply transaction; reply
will be lost.
org.apache.ode.bpel.iapi.BpelEngineException:
java.util.concurrent.ExecutionException: java.lang.NullPointerException
        at
org.apache.ode.bpel.engine.BpelServerImpl.getMessageExchange(BpelServerImpl.java:555)
        at
org.apache.ode.axis2.ExternalService.reply(ExternalService.java:282)
        at
org.apache.ode.axis2.ExternalService.invoke(ExternalService.java:145)
        at
org.apache.ode.axis2.MessageExchangeContextImpl.invokePartnerUnreliable(MessageExchangeContextImpl.java:67)
        at
org.apache.ode.bpel.engine.PartnerLinkPartnerRoleImpl$UnreliableInvoker.run(PartnerLinkPartnerRoleImpl.java:3
40)
        at
org.apache.ode.bpel.engine.BpelProcess$ProcessRunnable.run(BpelProcess.java:1102)
        at
org.apache.ode.bpel.engine.BpelServerImpl$ServerRunnable.run(BpelServerImpl.java:739)
        at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
        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)
Caused by: java.util.concurrent.ExecutionException:
java.lang.NullPointerException
        at
java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:205)
        at java.util.concurrent.FutureTask.get(FutureTask.java:80)
        at
org.apache.ode.bpel.engine.BpelServerImpl.getMessageExchange(BpelServerImpl.java:551)
        ... 12 more
Caused by: java.lang.NullPointerException
        at
org.apache.ode.bpel.engine.PartnerLinkPartnerRoleImpl.createPartnerRoleMex(PartnerLinkPartnerRoleImpl.java:67
)
        at
org.apache.ode.bpel.engine.BpelProcess.createPartnerRoleMex(BpelProcess.java:882)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:536)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:513)
        at
org.apache.ode.bpel.engine.Contexts.execTransaction(Contexts.java:100)
        at
org.apache.ode.bpel.engine.BpelServerImpl$TransactedCallable.call(BpelServerImpl.java:779)
        at
org.apache.ode.bpel.engine.BpelServerImpl$ServerCallable.call(BpelServerImpl.java:760)
        ... 5 more
09:08:05,361 ERROR [ExternalService] Error executing reply transaction;
reply will be lost.
org.apache.ode.bpel.iapi.BpelEngineException:
java.util.concurrent.ExecutionException: java.lang.NullPointerException
        at
org.apache.ode.bpel.engine.BpelServerImpl.getMessageExchange(BpelServerImpl.java:555)
        at
org.apache.ode.axis2.ExternalService.reply(ExternalService.java:282)
        at
org.apache.ode.axis2.ExternalService.invoke(ExternalService.java:145)
        at
org.apache.ode.axis2.MessageExchangeContextImpl.invokePartnerUnreliable(MessageExchangeContextImpl.java:67)
        at
org.apache.ode.bpel.engine.PartnerLinkPartnerRoleImpl$UnreliableInvoker.run(PartnerLinkPartnerRoleImpl.java:3
40)
        at
org.apache.ode.bpel.engine.BpelProcess$ProcessRunnable.run(BpelProcess.java:1102)
        at
org.apache.ode.bpel.engine.BpelServerImpl$ServerRunnable.run(BpelServerImpl.java:739)
        at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
        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)
Caused by: java.util.concurrent.ExecutionException:
java.lang.NullPointerException
        at
java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:205)
        at java.util.concurrent.FutureTask.get(FutureTask.java:80)
        at
org.apache.ode.bpel.engine.BpelServerImpl.getMessageExchange(BpelServerImpl.java:551)
        ... 12 more
Caused by: java.lang.NullPointerException
        at
org.apache.ode.bpel.engine.PartnerLinkPartnerRoleImpl.createPartnerRoleMex(PartnerLinkPartnerRoleImpl.java:67
)
        at
org.apache.ode.bpel.engine.BpelProcess.createPartnerRoleMex(BpelProcess.java:882)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:536)
        at
org.apache.ode.bpel.engine.BpelServerImpl$3.call(BpelServerImpl.java:513)
        at
org.apache.ode.bpel.engine.Contexts.execTransaction(Contexts.java:100)
        at
org.apache.ode.bpel.engine.BpelServerImpl$TransactedCallable.call(BpelServerImpl.java:779)
        at
org.apache.ode.bpel.engine.BpelServerImpl$ServerCallable.call(BpelServerImpl.java:760)
        ... 5 more
DEBUG - GeronimoLog.debug(66) | enqueue: for instance
{http://eclipse.org/bpel/sample}ECRProcess-1#53: org.apache.ode.bp
el.engine.BpelServerImpl$TransactedRunnable@19f360
09:08:05,371 DEBUG [BpelInstanceWorker] enqueue: for instance
{http://eclipse.org/bpel/sample}ECRProcess-1#53: org.apach
e.ode.bpel.engine.BpelServerImpl$TransactedRunnable@19f360
DEBUG - GeronimoLog.debug(66) | enqueuRunnable for process
{http://eclipse.org/bpel/sample}ECRProcess-1: {BpelInstanceWo
rker for {http://eclipse.org/bpel/sample}ECRProcess-1#53}
09:08:05,371 DEBUG [BpelProcess] enqueuRunnable for process
{http://eclipse.org/bpel/sample}ECRProcess-1: {BpelInstanceW
orker for {http://eclipse.org/bpel/sample}ECRProcess-1#53}
DEBUG - GeronimoLog.debug(66) | Starting worker thread
Thread[pool-2-thread-4,5,main] for instance IID {http://eclipse.o
rg/bpel/sample}ECRProcess-1#53
09:08:05,371 DEBUG [BpelInstanceWorker] Starting worker thread
Thread[pool-2-thread-4,5,main] for instance IID {http://e
clipse.org/bpel/sample}ECRProcess-1#53
DEBUG - GeronimoLog.debug(66) | Doing work for instance
{http://eclipse.org/bpel/sample}ECRProcess-1#53 in thread Thread
[pool-2-thread-4,5,main]
09:08:05,371 DEBUG [BpelInstanceWorker] Doing work for instance
{http://eclipse.org/bpel/sample}ECRProcess-1#53 in threa
d Thread[pool-2-thread-4,5,main]
DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance
53. INDEXED STATE={}
09:08:05,411 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 53. INDEXED STATE={}
DEBUG - GeronimoLog.debug(66) | CACHE MISS: Loading state to resume instance
53 from database
09:08:05,411 DEBUG [BpelRuntimeContextImpl] CACHE MISS: Loading state to
resume instance 53 from database
DEBUG - GeronimoLog.debug(66) | <invoke> response for mexid
hqejbhcnphr2ksbul6v7m8 and channel 29
09:08:05,572 DEBUG [BpelRuntimeContextImpl] <invoke> response for mexid
hqejbhcnphr2ksbul6v7m8 and channel 29
DEBUG - GeronimoLog.debug(66) | Triggering response
09:08:05,582 DEBUG [BpelRuntimeContextImpl] Triggering response
INFO - GeronimoLog.info(79) | ActivityRecovery: Registering activity 17,
failure reason: No Response on channel 30
09:08:05,622 INFO  [BpelRuntimeContextImpl] ActivityRecovery: Registering
activity 17, failure reason: No Response on ch
annel 30
DEBUG - GeronimoLog.debug(66) | CACHE SAVE: #2 for instance 53
09:08:05,702 DEBUG [BpelRuntimeContextImpl] CACHE SAVE: #2 for instance 53
DEBUG - GeronimoLog.debug(66) | Finished work for instance
{http://eclipse.org/bpel/sample}ECRProcess-1#53 in thread Thr
ead[pool-2-thread-4,5,main]
09:08:05,952 DEBUG [BpelInstanceWorker] Finished work for instance
{http://eclipse.org/bpel/sample}ECRProcess-1#53 in th
read Thread[pool-2-thread-4,5,main]
DEBUG - GeronimoLog.debug(66) | Worker thread Thread[pool-2-thread-4,5,main]
for instance IID 53 ran out of work.
09:08:05,952 DEBUG [BpelInstanceWorker] Worker thread
Thread[pool-2-thread-4,5,main] for instance IID 53 ran out of work


When i revert to 1.1 RC4.


emas.xmlsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://local
host: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/ad
dressing/anonymous</addr:Address></addr:ReplyTo><addr:MessageID
xmlns:addr="http://www.w3.org/2005/08/addressing">uuid:h
qejbhcnphr2kqt5iknrce</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns1:createBus
xmlns:axis2ns1="http://client.e
ngineering.cbp.jpo"><username xmlns="http://eclipse.org/bpel/sample">Test
Everything</username><password xmlns="http://e
clipse.org/bpel/sample">1234</password><type
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</type><name xmlns="http
://eclipse.org/bpel/sample">CBP-ECR00090</name><rev
xmlns="http://eclipse.org/bpel/sample">-</rev><vault xmlns="http://e
clipse.org/bpel/sample">eService Sample</vault><policy
xmlns="http://eclipse.org/bpel/sample">CBP Workflow</policy></axi
s2ns1:createBus></soapenv:Body></soapenv:Envelope>
[Sun Sep 09 21:49:48 EDT 2007] PLM: createBus: start
[Sun Sep 09 21:49:48 EDT 2007] PLM: initContext user<<Test Everything>>
pass<<1234>>
[Sun Sep 09 21:49:49 EDT 2007] PLM: createBus: initContext OK
[Sun Sep 09 21:49:49 EDT 2007] PLM: createBus: creating BO
[Sun Sep 09 21:49:50 EDT 2007] PLM: createBus: Created BO
[Sun Sep 09 21:49:50 EDT 2007] PLM: createBus: id = 15863.19604.20924.26305
[Sun Sep 09 21:49:50 EDT 2007] PLM: createBus: returning:
15863.19604.20924.26305
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2kqt5iknrcd].setPortOp(...)
21:49:50,675 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2kqt5iknrcd].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Received response for MEX
{PartnerRoleMex#hqejbhcnphr2kqt5iknrcd [PID {http://eclipse.or
g/bpel/sample}ECRProcess-1] calling null.createBus(...)}
21:49:50,675 DEBUG [ExternalService] Received response for MEX
{PartnerRoleMex#hqejbhcnphr2kqt5iknrcd [PID {http://eclip
se.org/bpel/sample}ECRProcess-1] 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://sche
mas.xmlsoap.org/soap/envelope/" xmlns:xsd="http://www.w3.org/2001/XMLSchema"
xmlns:xsi="http://www.w3.org/2001/XMLSchema
-instance">15863.19604.20924.26305</createBusReturn></message>
21:49:50,675 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://sche
mas.xmlsoap.org/soap/envelope/" xmlns:xsd="http://www.w3.org/2001/XMLSchema"
xmlns:xsi="http://www.w3.org/2001/XMLSchema
-instance">15863.19604.20924.26305</createBusReturn></message>
DEBUG - GeronimoLog.debug(66) | reply mex=hqejbhcnphr2kqt5iknrcd
21:49:50,675 DEBUG [PartnerRoleMessageExchangeImpl] reply
mex=hqejbhcnphr2kqt5iknrcd
DEBUG - GeronimoLog.debug(66) | create work event for
mex=hqejbhcnphr2kqt5iknrcd
21:49:50,675 DEBUG [PartnerRoleMessageExchangeImpl] create work event for
mex=hqejbhcnphr2kqt5iknrcd
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-1,5,main]: lock(iid=54,
time=1MICROSECONDS)
21:49:50,746 DEBUG [InstanceLockManager] Thread[pool-2-thread-1,5,main]:
lock(iid=54, time=1MICROSECONDS)
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-1,5,main]: lock(iid=54,
time=1MICROSECONDS)-->GRANTED
21:49:50,746 DEBUG [InstanceLockManager] Thread[pool-2-thread-1,5,main]:
lock(iid=54, time=1MICROSECONDS)-->GRANTED
DEBUG - GeronimoLog.debug(66) | >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2kqt5iknrcd,
channel=
29, iid=54})
21:49:50,756 DEBUG [BpelProcess] >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2kqt5iknrcd,
channel
=29, iid=54})
DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance
54. INDEXED STATE={OSequence#45-main::1=[ACT
IVITYGUARD(...)],
OSequence#47-HiddenSequence::6=[SEQUENCE(self=(OSequence#47-HiddenSequence,TerminationChannel#9,Parent
ScopeChannel#11), linkframe=org.apache.ode.bpel.runtime.LinkFrame@1a71e88,
remaining=[{OScope 'CBPWorkflowPersistence' i
d=91}, {OAssign : InitializeAndGetIID, joinCondition=null}, {OScope
'GetInstanceInfo' id=104}, {OAssign : SystemOut, joi
nCondition=null}, {OAssign : AssignInstanceInfoToSetAttribute,
joinCondition=null}, {OScope 'UpdateCBPWorkflow' id=123},
 {OScope 'callbackClient' id=130}])], {OScope 'Design Change Proposal'
id=46}::4=[SCOPE(...)], OSequence#47-HiddenSequen
ce::5=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=46}::3=[ACTIVITYGUARD(...)], {OScope 'CBPWorkflowPersist
ence' id=91}::16=[ACTIVE(...)],
OSequence#45-main::2=[SEQUENCE(self=(OSequence#45-main,TerminationChannel#3,ParentScopeC
hannel#5), linkframe=org.apache.ode.bpel.runtime.LinkFrame@1a71e88,
remaining=[{OScope 'Design Change Proposal' id=46}])
], {OScope 'CBPWorkflowPersistence' id=91}::15=[ACTIVITYGUARD(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=3}::0=[ACTI
VE(...)], {OScope 'Design Change Proposal' id=46}::4=[ACTIVE(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=3}::0=[SCOPE
(...)], {OScope 'CBPWorkflowPersistence' id=91}::16=[SCOPE(...)],
OInvoke#92-CBPWorkflowPersistence::18=[INVOKE(...)], O
Invoke#92-CBPWorkflowPersistence::17=[ACTIVITYGUARD(...)]}
21:49:50,806 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 54. INDEXED STATE={OSequence#45-
main::1=[ACTIVITYGUARD(...)],
OSequence#47-HiddenSequence::6=[SEQUENCE(self=(OSequence#47-HiddenSequence,TerminationChan
nel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1a71e88, remaining=[{OScope
'CBPWorkflowPe
rsistence' id=91}, {OAssign : InitializeAndGetIID, joinCondition=null},
{OScope 'GetInstanceInfo' id=104}, {OAssign : Sy
stemOut, joinCondition=null}, {OAssign : AssignInstanceInfoToSetAttribute,
joinCondition=null}, {OScope 'UpdateCBPWorkfl
ow' id=123}, {OScope 'callbackClient' id=130}])], {OScope 'Design Change
Proposal' id=46}::4=[SCOPE(...)], OSequence#47-
HiddenSequence::5=[ACTIVITYGUARD(...)], {OScope 'Design Change Proposal'
id=46}::3=[ACTIVITYGUARD(...)], {OScope 'CBPWor
kflowPersistence' id=91}::16=[ACTIVE(...)],
OSequence#45-main::2=[SEQUENCE(self=(OSequence#45-main,TerminationChannel#3,
ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@1a71e88, remaining=[{OScope
'Design Change Propos
al' id=46}])], {OScope 'CBPWorkflowPersistence'
id=91}::15=[ACTIVITYGUARD(...)], {OScope '__PROCESS_SCOPE:ECRProcess' id
=3}::0=[ACTIVE(...)], {OScope 'Design Change Proposal'
id=46}::4=[ACTIVE(...)], {OScope '__PROCESS_SCOPE:ECRProcess' id=
3}::0=[SCOPE(...)], {OScope 'CBPWorkflowPersistence'
id=91}::16=[SCOPE(...)], OInvoke#92-CBPWorkflowPersistence::18=[INV
OKE(...)], OInvoke#92-CBPWorkflowPersistence::17=[ACTIVITYGUARD(...)]}
DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 54
21:49:50,806 DEBUG [BpelProcess] InvokeResponse event for iid 54
DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid
hqejbhcnphr2kqt5iknrcd and channel 29
21:49:50,806 DEBUG [BpelRuntimeContextImpl] Invoking message response for
mexid hqejbhcnphr2kqt5iknrcd and channel 29
DEBUG - GeronimoLog.debug(66) | Triggering response
21:49:50,806 DEBUG [BpelRuntimeContextImpl] Triggering response
DEBUG - GeronimoLog.trace(54) | >>
createScopeInstance(parentScopeId=56,scope={OScope 'GetInstanceInfo'
id=104})
21:49:50,976 DEBUG [BpelProcess] >>
createScopeInstance(parentScopeId=56,scope={OScope 'GetInstanceInfo'
id=104})
DEBUG - GeronimoLog.trace(54) | >>
initializeEndpointReferences(parentScopeId=104,partnerLinks=[])
21:49:50,996 DEBUG [BpelProcess] >>
initializeEndpointReferences(parentScopeId=104,partnerLinks=[])
DEBUG - GeronimoLog.debug(66) | INVOKING PARTNER:
partnerLink={PartnerLinkInstance partnerLinkDecl=OPartnerLink#43,scope
InstanceId=55}, op=getInstanceInfo channel=InvokeResponseChannel#40)
21:49:51,006 DEBUG [BpelProcess] INVOKING PARTNER:
partnerLink={PartnerLinkInstance partnerLinkDecl=OPartnerLink#43,scop
eInstanceId=55}, op=getInstanceInfo channel=InvokeResponseChannel#40)
DEBUG - GeronimoLog.debug(66) | INVOKE PARTNER (SEP): sessionId=null
partnerSessionId=null
21:49:51,006 DEBUG [BpelRuntimeContextImpl] INVOKE PARTNER (SEP):
sessionId=null partnerSessionId=null
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2kqt5iknrcg].setPortOp(...)
21:49:51,006 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2kqt5iknrcg].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | Routed: svcQname
{http://www.apache.org/ode/pmapi}InstanceManagementService --> null
21:49:51,006 DEBUG [BpelEngineImpl] Routed: svcQname
{http://www.apache.org/ode/pmapi}InstanceManagementService --> null

DEBUG - GeronimoLog.debug(66) | Invoking a partner operation:
getInstanceInfo
21:49:51,006 DEBUG [MessageExchangeContextImpl] Invoking a partner
operation: getInstanceInfo
DEBUG - GeronimoLog.debug(66) | The service to invoke is the external
service org.apache.ode.axis2.ExternalService@1b911
d2
21:49:51,006 DEBUG [MessageExchangeContextImpl] The service to invoke is the
external service org.apache.ode.axis2.Exter
nalService@1b911d2
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/0
8/addressing"><Metadata><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="InstanceManagementP
ort"
xmlns:servicens="http://www.apache.org/ode/pmapi">servicens:InstanceManagementService</ServiceName></Metadata><Addr
ess>http://localhost:8080/ode/processes/InstanceManagement</Address></EndpointReference></service-ref>
21:49:51,006 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/0
8/addressing"><Metadata><ServiceName
xmlns="http://www.w3.org/2006/05/addressing/wsdl"
EndpointName="InstanceManagementP
ort"
xmlns:servicens="http://www.apache.org/ode/pmapi">servicens:InstanceManagementService</ServiceName></Metadata><Addr
ess>http://localhost:8080/ode/processes/InstanceManagement</Address></EndpointReference></service-ref>
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
21:49:51,006 DEBUG [MessageExchangeImpl] GET MEX property
org.apache.ode.bpel.partnerRoleSessionId = null
DEBUG - GeronimoLog.debug(66) | GET MEX property
org.apache.ode.bpel.myRoleSessionId = null
21:49:51,006 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.
21:49:51,006 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 ME
X {PartnerRoleMex#hqejbhcnphr2kqt5iknrcg [PID
{http://eclipse.org/bpel/sample}ECRProcess-1] calling org.apache.ode.bpel.
epr.WSAEndpoint@1412a45.getInstanceInfo(...)}
21:49:51,006 DEBUG [ExternalService] Axis2 sending message to
http://localhost:8080/ode/processes/InstanceManagement usi
ng MEX {PartnerRoleMex#hqejbhcnphr2kqt5iknrcg [PID
{http://eclipse.org/bpel/sample}ECRProcess-1] calling org.apache.ode.
bpel.epr.WSAEndpoint@1412a45.getInstanceInfo(...)}
DEBUG - GeronimoLog.debug(66) | Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="http://s
chemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org/ode/pma
pi"><iid>54</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
21:49:51,016 DEBUG [ExternalService] Message: <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="htt
p://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org/od
e/pmapi"><iid>54</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.debug(66) | replyAsync mex=hqejbhcnphr2kqt5iknrcg
21:49:51,016 DEBUG [PartnerRoleMessageExchangeImpl] replyAsync
mex=hqejbhcnphr2kqt5iknrcg
DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 54
21:49:51,016 DEBUG [BpelRuntimeContextImpl] Setting execution state on
instance 54
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-1,5,main]:
unlock(iid=54)
21:49:51,146 DEBUG [InstanceLockManager] Thread[pool-2-thread-1,5,main]:
unlock(iid=54)
DEBUG - GeronimoLog.debug(66) | Sending stateful TO epr in message header
using session null
21:49:51,146 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.
21:49:51,156 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.xm
lsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://localhost:80
80/ode/processes/InstanceManagement</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></addr:Actio
n><addr:ReplyTo
xmlns:addr="http://www.w3.org/2005/08/addressing"><addr:Address>http://www.w3.org/2005/08/addressing/ano
nymous</addr:Address></addr:ReplyTo><addr:MessageID
xmlns:addr="http://www.w3.org/2005/08/addressing">uuid:hqejbhcnphr2k
qt5iknrch</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apache.org
/ode/pmapi"><iid>54</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
21:49:51,156 DEBUG [SessionOutHandler] <?xml version='1.0'
encoding='utf-8'?><soapenv:Envelope xmlns:soapenv="http://sch
emas.xmlsoap.org/soap/envelope/"><soapenv:Header><addr:To
xmlns:addr="http://www.w3.org/2005/08/addressing">http://local
host:8080/ode/processes/InstanceManagement</addr:To><addr:Action
xmlns:addr="http://www.w3.org/2005/08/addressing"></add
r:Action><addr:ReplyTo
xmlns:addr="http://www.w3.org/2005/08/addressing"><addr:Address>http://www.w3.org/2005/08/address
ing/anonymous</addr:Address></addr:ReplyTo><addr:MessageID
xmlns:addr="http://www.w3.org/2005/08/addressing">uuid:hqejbh
cnphr2kqt5iknrch</addr:MessageID></soapenv:Header><soapenv:Body><axis2ns2:getInstanceInfo
xmlns:axis2ns2="http://www.apa
che.org/ode/pmapi"><iid>54</iid></axis2ns2:getInstanceInfo></soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.debug(66) | The system is checking for the operation
using the following WSAAction: urn:anonOutInOp
21:49:51,176 DEBUG [ODEAxisDispatcher] The system is checking for the
operation using the following WSAAction: urn:anonO
utInOp
DEBUG - GeronimoLog.debug(66) | Checking for Operation using SOAP message
body's first child's local name : getInstanceI
nfo
21:49:51,186 DEBUG [ODEAxisDispatcher] Checking for Operation using SOAP
message body's first child's local name : getIn
stanceInfo
DEBUG - GeronimoLog.debug(66) | Found operation
org.apache.axis2.description.InOutAxisOperation@1dd37fe
21:49:51,186 DEBUG [ODEAxisDispatcher] Found operation
org.apache.axis2.description.InOutAxisOperation@1dd37fe
DEBUG - GeronimoLog.debug(66) | Found a header in incoming message, checking
if there are endpoints there.
21:49:51,186 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}getInstanc
eInfo
21:49:51,196 DEBUG [ManagementService] Received mgmt message for
InstanceManagement.{http://www.apache.org/ode/pmapi}get
InstanceInfo
DEBUG - GeronimoLog.debug(66) | Extracting param class java.lang.Long from
<iid>54</iid>
21:49:51,206 DEBUG [ManagementService] Extracting param class java.lang.Long
from <iid>54</iid>
DEBUG - GeronimoLog.debug(66) | Reply mgmt for
InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInfo
21:49:51,437 DEBUG [ManagementService] Reply mgmt for
InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInf
o
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><ns:instance-info
xmlns:ns="http://www.apache.org/ode/pmapi/t
ypes/2006/08/02/"><ns:iid>54</ns:iid><ns:pid>{http://eclipse.org/bpel/sample}ECRProcess-1</ns:pid><ns:process-name
xmlns
:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</ns:process-name><ns:root-scope
siid="55" status="ACTIVE" name="__P
ROCESS_SCOPE:ECRProcess" modelId="3"
/><ns:status>ACTIVE</ns:status><ns:dt-started>2007-09-09T21:49:45.708-04:00</ns:dt-
started><ns:dt-last-active>2007-09-09T21:49:51.016-04:00</ns:dt-last-active><ns:event-info
/></ns:instance-info></soapen
v:Body></soapenv:Envelope>
21:49:51,437 DEBUG [ManagementService] Reply mgmt message <?xml
version='1.0' encoding='utf-8'?><soapenv:Envelope xmlns:
soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><ns:instance-info
xmlns:ns="http://www.apache.org/ode/
pmapi/types/2006/08/02/"><ns:iid>54</ns:iid><ns:pid>{http://eclipse.org/bpel/sample}ECRProcess-1</ns:pid><ns:process-nam
e
xmlns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</ns:process-name><ns:root-scope
siid="55" status="ACTIVE" na
me="__PROCESS_SCOPE:ECRProcess" modelId="3"
/><ns:status>ACTIVE</ns:status><ns:dt-started>2007-09-09T21:49:45.708-04:00<
/ns:dt-started><ns:dt-last-active>2007-09-09T21:49:51.016-04:00</ns:dt-last-active><ns:event-info
/></ns:instance-info><
/soapenv:Body></soapenv:Envelope>
DEBUG - GeronimoLog.trace(54) | Mex[hqejbhcnphr2kqt5iknrcg].setPortOp(...)
21:49:51,457 DEBUG [MessageExchangeImpl]
Mex[hqejbhcnphr2kqt5iknrcg].setPortOp(...)
DEBUG - GeronimoLog.debug(66) | replyWithFailure mex=hqejbhcnphr2kqt5iknrcg
failureType=FORMAT_ERROR description=SOAP bo
dy does not contain expected part wrapper: service
{http://www.apache.org/ode/pmapi}InstanceManagementService port Insta
nceManagementPort wrapper
{http://www.apache.org/ode/pmapi}getInstanceInfoResponse details=null
21:49:51,457 DEBUG [PartnerRoleMessageExchangeImpl] replyWithFailure
mex=hqejbhcnphr2kqt5iknrcg failureType=FORMAT_ERROR
 description=SOAP body does not contain expected part wrapper: service
{http://www.apache.org/ode/pmapi}InstanceManageme
ntService port InstanceManagementPort wrapper
{http://www.apache.org/ode/pmapi}getInstanceInfoResponse details=null
DEBUG - GeronimoLog.debug(66) | create work event for
mex=hqejbhcnphr2kqt5iknrcg
21:49:51,457 DEBUG [PartnerRoleMessageExchangeImpl] create work event for
mex=hqejbhcnphr2kqt5iknrcg
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-1,5,main]: lock(iid=54,
time=1MICROSECONDS)
21:49:51,487 DEBUG [InstanceLockManager] Thread[pool-2-thread-1,5,main]:
lock(iid=54, time=1MICROSECONDS)
DEBUG - GeronimoLog.debug(66) | Thread[pool-2-thread-1,5,main]: lock(iid=54,
time=1MICROSECONDS)-->GRANTED
21:49:51,487 DEBUG [InstanceLockManager] Thread[pool-2-thread-1,5,main]:
lock(iid=54, time=1MICROSECONDS)-->GRANTED
DEBUG - GeronimoLog.debug(66) | >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2kqt5iknrcg,
channel=
40, iid=54})
21:49:51,497 DEBUG [BpelProcess] >>
handleWorkEvent(jobData={type=INVOKE_RESPONSE, mexid=hqejbhcnphr2kqt5iknrcg,
channel
=40, iid=54})
DEBUG - GeronimoLog.debug(66) | BpelRuntimeContextImpl created for instance
54. INDEXED STATE={{OScope 'GetInstanceInfo'
 id=104}::22=[SCOPE(...)], {OScope 'GetInstanceInfo'
id=104}::22=[ACTIVE(...)], OSequence#45-main::2=[SEQUENCE(self=(OSe
quence#45-main,TerminationChannel#3,ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@c677d5, remai
ning=[{OScope 'Design Change Proposal' id=46}])],
OSequence#47-HiddenSequence::6=[SEQUENCE(self=(OSequence#47-HiddenSequ
ence,TerminationChannel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@c677d5, remaining=[{OS
cope 'GetInstanceInfo' id=104}, {OAssign : SystemOut, joinCondition=null},
{OAssign : AssignInstanceInfoToSetAttribute,
joinCondition=null}, {OScope 'UpdateCBPWorkflow' id=123}, {OScope
'callbackClient' id=130}])], {OScope '__PROCESS_SCOPE:
ECRProcess' id=3}::0=[SCOPE(...)],
OInvoke#105-GetInstanceInfo::24=[INVOKE(...)],
OSequence#45-main::1=[ACTIVITYGUARD(..
.)], {OScope 'Design Change Proposal' id=46}::4=[ACTIVE(...)], {OScope
'__PROCESS_SCOPE:ECRProcess' id=3}::0=[ACTIVE(...
)], {OScope 'GetInstanceInfo' id=104}::21=[ACTIVITYGUARD(...)], {OScope
'Design Change Proposal' id=46}::3=[ACTIVITYGUAR
D(...)], OSequence#47-HiddenSequence::5=[ACTIVITYGUARD(...)], {OScope
'Design Change Proposal' id=46}::4=[SCOPE(...)], O
Invoke#105-GetInstanceInfo::23=[ACTIVITYGUARD(...)]}
21:49:51,517 DEBUG [BpelRuntimeContextImpl] BpelRuntimeContextImpl created
for instance 54. INDEXED STATE={{OScope 'GetI
nstanceInfo' id=104}::22=[SCOPE(...)], {OScope 'GetInstanceInfo'
id=104}::22=[ACTIVE(...)], OSequence#45-main::2=[SEQUEN
CE(self=(OSequence#45-main,TerminationChannel#3,ParentScopeChannel#5),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@c
677d5, remaining=[{OScope 'Design Change Proposal' id=46}])],
OSequence#47-HiddenSequence::6=[SEQUENCE(self=(OSequence#4
7-HiddenSequence,TerminationChannel#9,ParentScopeChannel#11),
linkframe=org.apache.ode.bpel.runtime.LinkFrame@c677d5, re
maining=[{OScope 'GetInstanceInfo' id=104}, {OAssign : SystemOut,
joinCondition=null}, {OAssign : AssignInstanceInfoToSe
tAttribute, joinCondition=null}, {OScope 'UpdateCBPWorkflow' id=123},
{OScope 'callbackClient' id=130}])], {OScope '__PR
OCESS_SCOPE:ECRProcess' id=3}::0=[SCOPE(...)],
OInvoke#105-GetInstanceInfo::24=[INVOKE(...)], OSequence#45-main::1=[ACTI
VITYGUARD(...)], {OScope 'Design Change Proposal' id=46}::4=[ACTIVE(...)],
{OScope '__PROCESS_SCOPE:ECRProcess' id=3}::0
=[ACTIVE(...)], {OScope 'GetInstanceInfo' id=104}::21=[ACTIVITYGUARD(...)],
{OScope 'Design Change Proposal' id=46}::3=[
ACTIVITYGUARD(...)], OSequence#47-HiddenSequence::5=[ACTIVITYGUARD(...)],
{OScope 'Design Change Proposal' id=46}::4=[SC
OPE(...)], OInvoke#105-GetInstanceInfo::23=[ACTIVITYGUARD(...)]}
DEBUG - GeronimoLog.debug(66) | InvokeResponse event for iid 54
21:49:51,517 DEBUG [BpelProcess] InvokeResponse event for iid 54
DEBUG - GeronimoLog.debug(66) | Invoking message response for mexid
hqejbhcnphr2kqt5iknrcg and channel 40
21:49:51,517 DEBUG [BpelRuntimeContextImpl] Invoking message response for
mexid hqejbhcnphr2kqt5iknrcg and channel 40
DEBUG - GeronimoLog.debug(66) | Triggering response
21:49:51,527 DEBUG [BpelRuntimeContextImpl] Triggering response
INFO - GeronimoLog.info(79) | ActivityRecovery: Registering activity 23,
failure reason: SOAP body does not contain expe
cted part wrapper: service
{http://www.apache.org/ode/pmapi}InstanceManagementService port
InstanceManagementPort wrappe
r {http://www.apache.org/ode/pmapi}getInstanceInfoResponse on channel 41
21:49:51,557 INFO  [BpelRuntimeContextImpl] ActivityRecovery: Registering
activity 23, failure reason: SOAP body does no
t contain expected part wrapper: service
{http://www.apache.org/ode/pmapi}InstanceManagementService port
InstanceManagem
entPort wrapper {http://www.apache.org/ode/pmapi}getInstanceInfoResponse on
channel 41
DEBUG - GeronimoLog.debug(66) | Setting execution state on instance 54








Alex Boisvert wrote:
> 
> Now fixed in the 1.1 branch (r573763) if you want to test it out.
> 
> On 9/7/07, Alex Boisvert <boisvert@intalio.com> wrote:
>>
>> Hi Anup,
>>
>> Looks like a bug... I'm amazed we never found this out earlier.  The
>> PM-API responses are not properly wrapped with a top element named
>> operationName+"Response".  I'll get on it.
>>
>> alex
>>
>>
>> On 9/7/07, Anup Chandran <anup.chandran@3ds.com> wrote:
>> >
>> >
>> > Hi All,
>> >
>> > My process is trying to invoke the getInstanceInfo in the pmapi. I see
>> > these
>> > traces on the console. I do see that it fetched the instanceInfo but i
>> > also
>> > see a replyWithFailure as shown below. Activities after the invoke does
>> > not
>> > get triggered. i can't figure out what is the issue with
>> getInstanceInfo
>> > invoke. It complains about the reply message format not being correct.
>> > I'm
>> > using ODE 1.1 RC4.
>> >
>> > Here are the traces...
>> >
>> > DEBUG - GeronimoLog.debug(66) | Reply mgmt for
>> >
>> InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInfo<http://www.apache.org/ode/pmapi%7DgetInstanceInfo>
>> > 15:55:15,069 DEBUG [ManagementService] Reply mgmt for
>> >
>> InstanceManagement.{http://www.apache.org/ode/pmapi}getInstanceInf<http://www.apache.org/ode/pmapi%7DgetInstanceInf>
>> > o
>> > 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><ns:instance-info
>> >
>> > xmlns:ns="http://www.apache.org/ode/pmapi/t
>> >
>> ypes/2006/08/02/"><ns:iid>1453</ns:iid><ns:pid>{http://eclipse.org/bpel/sample}ECRProcess-20<http://eclipse.org/bpel/sample%7DECRProcess-20>
>> > </ns:pid><ns:process-name
>> > xm
>> >
>> lns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</ns:process-name><ns:root-scope
>> >
>> > siid="1454" status="ACTIVE" name
>> > ="__PROCESS_SCOPE:ECRProcess" modelId="3"
>> >
>> /><ns:status>ACTIVE</ns:status><ns:dt-started>2007-09-07T15:55:13.717-04:00</n
>> >
>> > s:dt-started><ns:dt-last-active>2007-09-07T15:55:14.888-04
>> > :00</ns:dt-last-active><ns:event-info
>> > /></ns:instance-info></s
>> > oapenv:Body></soapenv:Envelope>
>> > 15:55:15,069 DEBUG [ManagementService] Reply mgmt message <?xml
>> > version='1.0' encoding='utf-8'?><soapenv:Envelope xmlns:
>> >
>> soapenv="http://schemas.xmlsoap.org/soap/envelope/"><soapenv:Body><ns:instance-info
>> >
>> > xmlns:ns="http://www.apache.org/ode/
>> >
>> pmapi/types/2006/08/02/"><ns:iid>1453</ns:iid><ns:pid>{http://eclipse.org/bpel/sample}ECRProcess-20<http://eclipse.org/bpel/sample%7DECRProcess-20>
>> > </ns:pid><ns:process-
>> > name
>> >
>> xmlns:sam="http://eclipse.org/bpel/sample">sam:ECRProcess</ns:process-name><ns:root-scope
>> >
>> > siid="1454" status="ACTIV
>> > E" name="__PROCESS_SCOPE:ECRProcess" modelId="3"
>> > /><ns:status>ACTIVE</ns:status><ns:dt-started>2007-09-07T15:55:13.717-0
>> > 4:00</ns:dt-started><ns:dt-last-active>2007-09-07T15:55:14.888-04
>> > :00</ns:dt-last-active><ns:event-info
>> > /></ns:instance-i
>> > nfo></soapenv:Body></soapenv:Envelope>
>> > DEBUG - GeronimoLog.trace (54) |
>> > Mex[hqejbhcnphr2kj7p6au94n].setPortOp(...)
>> > 15:55:15,079 DEBUG [MessageExchangeImpl]
>> > Mex[hqejbhcnphr2kj7p6au94n].setPortOp(...)
>> > DEBUG - GeronimoLog.debug(66) | replyWithFailure
>> > mex=hqejbhcnphr2kj7p6au94n
>> > failureType=FORMAT_ERROR description=SOAP body does not contain
>> expected
>> > part wrapper: service
>> > {http://www.apache.org/ode/pmapi}InstanceManagementService
>> > <http://www.apache.org/ode/pmapi%7DInstanceManagementService> port
>> > InstanceManagementPort wrapper
>> >
>> {http://www.apache.org/ode/pmapi}getInstanceInfoResponse<http://www.apache.org/ode/pmapi%7DgetInstanceInfoResponse>details=null
>> > 15:55:15,079 DEBUG [PartnerRoleMessageExchangeImpl] replyWithFailure
>> > mex=hqejbhcnphr2kj7p6au94n failureType=FORMAT_ERROR
>> > description=SOAP body does not contain expected part wrapper: service
>> > {http://www.apache.org/ode/pmapi}InstanceManagementService
>> > <http://www.apache.org/ode/pmapi%7DInstanceManagementService> port
>> > InstanceManagementPort wrapper
>> >
>> {http://www.apache.org/ode/pmapi}getInstanceInfoResponse<http://www.apache.org/ode/pmapi%7DgetInstanceInfoResponse>details=null
>> > DEBUG - GeronimoLog.debug(66) | create work event for
>> > mex=hqejbhcnphr2kj7p6au94n
>> > --
>> > View this message in context:
>> http://www.nabble.com/Invoking-InstanceManagement-API-tf4403521.html#a12562422
>> >
>> > Sent from the Apache Ode User mailing list archive at Nabble.com.
>> >
>> >
>>
> 
> 

-- 
View this message in context: http://www.nabble.com/Invoking-InstanceManagement-API-tf4403521.html#a12594080
Sent from the Apache Ode User mailing list archive at Nabble.com.


Mime
View raw message