fault never reaches catchall
meghanai_99 Mar 8, 2007 4:57 PMHello,
I wanted to test fault and compensation handlers on jBPM-BPEL so I added 'catchAll' on outer scope and I call 'Throw' at the end of service flow. From the server log it looks like it doesn't fine my fault handler.
Here is my BPEL -
<?xml version="1.0" encoding="UTF-8"?> <!-- BPEL Process Definition Edited using ActiveBPEL(tm) Designer Version 2.1.0 (http://www.active-endpoints.com) --> <process xmlns="http://schemas.xmlsoap.org/ws/2003/03/business-process/" xmlns:bpws="http://schemas.xmlsoap.org/ws/2003/03/business-process/" xmlns:ns1="http://www.eclipse.org/alf/schema/EventBase/1" xmlns:ns2="ALFExampleServiceFlow" xmlns:ns3="http://www.eclipse.org/alf/logging" xmlns:xsd="http://www.w3.org/2001/XMLSchema" name="SimpleCompensate" suppressJoinFailure="yes" targetNamespace="http://SimpleCompensate"> <partnerLinks> <partnerLink myRole="ALFServiceFlow" name="ALFExampleServiceFlowPL" partnerLinkType="ns2:ALFExampleServiceFlowPL"/> <partnerLink name="CommonLoggingPL" partnerLinkType="ns2:CommonLoggingPL" partnerRole="CommonLogging"/> </partnerLinks> <variables> <variable messageType="ns1:EventNotice" name="EventNotice"/> <variable messageType="ns3:logStringRequest" name="logStringRequest"/> <variable messageType="ns3:logStringResponse" name="logStringResponse"/> </variables> <sequence> <receive createInstance="yes" operation="EventNotice" partnerLink="ALFExampleServiceFlowPL" portType="ns1:ALFServiceFlow" variable="EventNotice"/> <scope variableAccessSerializable="no"> <faultHandlers> <catchAll> <sequence> <assign> <copy> <from expression=""SimpleCompensate - In Fault Handler""/> <to part="parameters" query="/ns3:logString/ns3:logMessage" variable="logStringRequest"/> </copy> </assign> <invoke inputVariable="logStringRequest" operation="logString" outputVariable="logStringResponse" partnerLink="CommonLoggingPL" portType="ns3:ALFLoggingService"/> <compensate/> </sequence> </catchAll> </faultHandlers> <compensationHandler> <sequence> <assign> <copy> <from expression=""SimpleCompensate - Outer Compensation - should not run""/> <to part="parameters" query="/ns3:logString/ns3:logMessage" variable="logStringRequest"/> </copy> </assign> <invoke inputVariable="logStringRequest" operation="logString" outputVariable="logStringResponse" partnerLink="CommonLoggingPL" portType="ns3:ALFLoggingService"/> </sequence> </compensationHandler> <sequence> <assign> <copy> <from part="EventNotice" query="/EventNotice/ns1:Base/ns1:EventControl/ns1:EmEventId" variable="EventNotice"/> <to part="parameters" query="/ns3:logString/ns3:eventId" variable="logStringRequest"/> </copy> <copy> <from part="EventNotice" query="/EventNotice/ns1:Base/ns1:EventControl/ns1:ServiceFlowId" variable="EventNotice"/> <to part="parameters" query="/ns3:logString/ns3:serviceFlowId" variable="logStringRequest"/> </copy> <copy> <from expression=""SimpleCompensate - Outer Work""/> <to part="parameters" query="/ns3:logString/ns3:logMessage" variable="logStringRequest"/> </copy> <copy> <from expression=""INFO""/> <to part="parameters" query="/ns3:logString/ns3:logLevel" variable="logStringRequest"/> </copy> </assign> <invoke inputVariable="logStringRequest" operation="logString" outputVariable="logStringResponse" partnerLink="CommonLoggingPL" portType="ns3:ALFLoggingService"/> <scope variableAccessSerializable="no"> <compensationHandler> <sequence> <assign> <copy> <from expression=""SimpleCompensate - Inner Compensation""/> <to part="parameters" query="/ns3:logString/ns3:logMessage" variable="logStringRequest"/> </copy> </assign> <invoke inputVariable="logStringRequest" operation="logString" outputVariable="logStringResponse" partnerLink="CommonLoggingPL" portType="ns3:ALFLoggingService"/> </sequence> </compensationHandler> <sequence> <assign> <copy> <from expression=""SimpleCompensate - Inner Work""/> <to part="parameters" query="/ns3:logString/ns3:logMessage" variable="logStringRequest"/> </copy> </assign> <invoke inputVariable="logStringRequest" operation="logString" outputVariable="logStringResponse" partnerLink="CommonLoggingPL" portType="ns3:ALFLoggingService"/> </sequence> </scope> <throw faultName="SimpleCompensateExampleFault"/> </sequence> </scope> </sequence> </process>
Here is the server log
2007-03-08 13:28:05,560 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'Receive(606f26)' for 'Token(/primary)' 2007-03-08 13:28:05,560 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(Receive-StructuredActivity.End)' for 'Token(/primary)' 2007-03-08 13:28:05,560 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Scope(121572b)' for 'Token(/primary)' 2007-03-08 13:28:05,560 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'performingPrimaryActivity' on 'Scope(121572b)' for 'Token(/primary/null)' 2007-03-08 13:28:05,560 DEBUG [org.jbpm.context.exe.VariableContainer] create variable 's:instance' in 'TokenVariableMap826a5f' with value 'org.jbpm.bpel.graph.exe.ScopeInstance@15b26b[name=<null>,token=/primary/null,state=performingPrimaryActivity,id=0]' 2007-03-08 13:28:05,570 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.CompositeActivity - this operation breaks == 2007-03-08 13:28:05,570 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'StructuredActivity$Begin(127751c)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,570 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'StructuredActivity$Begin(127751c)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,570 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(StructuredActivity.Begin-Assign)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,570 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Assign(11452f4)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,570 DEBUG [org.jbpm.bpel.graph.basic.Assign] copying: token=Token(/primary/null/primary), value=[ns:EmEventId: null] 2007-03-08 13:28:05,570 DEBUG [org.jbpm.bpel.graph.basic.Assign] copying: token=Token(/primary/null/primary), value=[ns:ServiceFlowId: null] 2007-03-08 13:28:05,580 DEBUG [org.jbpm.bpel.graph.basic.Assign] copying: token=Token(/primary/null/primary), value=SimpleCompensate - Outer Work 2007-03-08 13:28:05,580 DEBUG [org.jbpm.bpel.graph.basic.Assign] copying: token=Token(/primary/null/primary), value=INFO 2007-03-08 13:28:05,580 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'Assign(11452f4)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,580 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(Assign-StructuredActivity.End)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,580 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Invoke(111a76c)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:05,580 DEBUG [org.jbpm.bpel.integration.jms.IntegrationControl] initialized partner reference: instance=org.jbpm.bpel.integration.exe.PartnerLinkInstance@113e259[name=CommonLoggingPL,id=268], reference=org.jbpm.bpel.endpointref.wsa.WsaEndpointReference@1123d8b[address=<null>,portType={http://www.eclipse.org/alf/logging}ALFLoggingService] 2007-03-08 13:28:05,580 DEBUG [org.jbpm.bpel.integration.catalog.URLCatalog] found candidate service: name={http://www.eclipse.org/alf/logging}ALFLoggingService, definitionURI=file:/D:/jboss-4.0.5.ejb3/build/output/jboss-4.0.5.GA-ejb3/server/default/tmp/deploy/tmp10243simplecompensate-exp.war/WEB-INF/wsdl/WSDL/ALFLoggingService.wsdl, portType={http://www.eclipse.org/alf/logging}ALFLoggingService 2007-03-08 13:28:05,580 DEBUG [org.jbpm.bpel.endpointref.SoapEndpointReference] found candidate port: name=ALFLoggingService, address=http://localhost:8085/ALFEventManager/services/ALFLoggingService, service={http://www.eclipse.org/alf/logging}ALFLoggingService, portType={http://www.eclipse.org/alf/logging}ALFLoggingService 2007-03-08 13:28:05,580 DEBUG [org.jbpm.bpel.integration.jms.IntegrationControl] selected partner port: instance=org.jbpm.bpel.integration.exe.PartnerLinkInstance@113e259[name=CommonLoggingPL,id=268], port=ALFLoggingService 2007-03-08 13:28:05,630 DEBUG [javax.xml.soap.FactoryLoader] Load from Service API META-INF/services/javax.xml.soap.MessageFactory: org.jboss.ws.core.soap.MessageFactoryImpl 2007-03-08 13:28:05,630 DEBUG [org.jboss.ws.core.soap.SOAPConnectionImpl] Get locator for: http://localhost:8085/ALFEventManager/services/ALFLoggingService 2007-03-08 13:28:05,630 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] connect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@1cf1934 2007-03-08 13:28:05,630 TRACE [jbossws.SOAPMessage] Remoting meta data: {HEADER={SOAPAction="", Content-Type=text/xml; charset=UTF-8}, NoThrowOnError=true} 2007-03-08 13:28:05,630 TRACE [jbossws.SOAPMessage] Outgoing SOAPMessage <env:Envelope xmlns:env='http://schemas.xmlsoap.org/soap/envelope/'> <env:Header/> <env:Body> <defaultNS:logString xmlns:defaultNS='http://www.eclipse.org/alf/logging'> <ns3:eventId xmlns:ns='http://www.eclipse.org/alf/schema/EventBase/1' xmlns:ns3='http://www.eclipse.org/alf/logging' xmlns:soapenv='http://schemas.xmlsoap.org/soap/envelope/'>34</ns3:eventId> <ns3:serviceFlowId xmlns:ns='http://www.eclipse.org/alf/schema/EventBase/1' xmlns:ns3='http://www.eclipse.org/alf/logging' xmlns:soapenv='http://schemas.xmlsoap.org/soap/envelope/'>354345</ns3:serviceFlowId> <ns3:logMessage xmlns:ns3='http://www.eclipse.org/alf/logging'>SimpleCompensate - Outer Work</ns3:logMessage> <ns3:logLevel xmlns:ns3='http://www.eclipse.org/alf/logging'>INFO</ns3:logLevel> </defaultNS:logString> </env:Body> </env:Envelope> 2007-03-08 13:28:05,630 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with SOAPAction : "" 2007-03-08 13:28:05,630 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with Content-Type : text/xml; charset=UTF-8 2007-03-08 13:28:07,162 DEBUG [org.jboss.ws.core.soap.SOAPMessageUnMarshaller] getMimeHeaders from: {ResponseCodeMessage=OK, null=[HTTP/1.1 200 OK], Date=[Thu, 08 Mar 2007 21:28:07 GMT], Content-Type=[text/xml;charset=utf-8], Server=[Apache-Coyote/1.1], HEADER={SOAPAction="", Content-Type=text/xml; charset=UTF-8}, Transfer-Encoding=[chunked], NoThrowOnError=true, ResponseCode=200} 2007-03-08 13:28:07,162 DEBUG [org.jboss.ws.core.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8] 2007-03-08 13:28:07,162 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] disconnect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@1cf1934 2007-03-08 13:28:07,162 DEBUG [org.jboss.remoting.InvokerRegistry] destroying client for locator: InvokerLocator [http://127.0.0.1:8085/ALFEventManager/services/ALFLoggingService?datatype=SOAPMessage], invoker:org.jboss.remoting.transport.http.HTTPClientInvoker@1cf1934, remaining list:{} 2007-03-08 13:28:07,162 DEBUG [org.jboss.remoting.InvokerRegistry] destroying client for locator: InvokerLocator [http://127.0.0.1:8085/ALFEventManager/services/ALFLoggingService?datatype=SOAPMessage], invoker:null, remaining list:{} 2007-03-08 13:28:07,162 TRACE [jbossws.SOAPMessage] Incoming Response SOAPMessage <soapenv:Envelope 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'> <soapenv:Header> <Dummy soapenv:actor='http://schemas.xmlsoap.org/soap/actor/next' soapenv:mustUnderstand='0' xmlns=''/> </soapenv:Header> <soapenv:Body> <logStringResponse xmlns='http://www.eclipse.org/alf/logging'/> </soapenv:Body> </soapenv:Envelope> 2007-03-08 13:28:07,162 DEBUG [org.jbpm.bpel.integration.jms.JmsIntegrationService] invoked: partnerLink=CommonLoggingPL, operation=logString, output={parameters=[defaultNS:logStringResponse: null]} 2007-03-08 13:28:07,162 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'Invoke(111a76c)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(Invoke-StructuredActivity.End)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Scope(21be6)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'performingPrimaryActivity' on 'Scope(21be6)' for 'Token(/primary/null/primary/null)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.context.exe.VariableContainer] create variable 's:instance' in 'TokenVariableMap1245c4f' with value 'org.jbpm.bpel.graph.exe.ScopeInstance@28785c[name=<null>,token=/primary/null/primary/null,state=performingPrimaryActivity,id=0]' 2007-03-08 13:28:07,172 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.CompositeActivity - this operation breaks == 2007-03-08 13:28:07,172 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.CompositeActivity - this operation breaks == 2007-03-08 13:28:07,172 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'StructuredActivity$Begin(9112ad)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'StructuredActivity$Begin(9112ad)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(StructuredActivity.Begin-Assign)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,172 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Assign(8a0152)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,182 DEBUG [org.jbpm.bpel.graph.basic.Assign] copying: token=Token(/primary/null/primary/null/primary), value=SimpleCompensate - Inner Work 2007-03-08 13:28:07,182 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'Assign(8a0152)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,182 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(Assign-StructuredActivity.End)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,182 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Invoke(1f8a98)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,182 DEBUG [javax.xml.soap.FactoryLoader] Load from Service API META-INF/services/javax.xml.soap.MessageFactory: org.jboss.ws.core.soap.MessageFactoryImpl 2007-03-08 13:28:07,222 DEBUG [org.jboss.ws.core.soap.SOAPConnectionImpl] Get locator for: http://localhost:8085/ALFEventManager/services/ALFLoggingService 2007-03-08 13:28:07,222 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] connect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@11c7571 2007-03-08 13:28:07,222 TRACE [jbossws.SOAPMessage] Remoting meta data: {HEADER={SOAPAction="", Content-Type=text/xml; charset=UTF-8}, NoThrowOnError=true} 2007-03-08 13:28:07,222 TRACE [jbossws.SOAPMessage] Outgoing SOAPMessage <env:Envelope xmlns:env='http://schemas.xmlsoap.org/soap/envelope/'> <env:Header/> <env:Body> <defaultNS:logString xmlns:defaultNS='http://www.eclipse.org/alf/logging'> <ns3:eventId xmlns:ns='http://www.eclipse.org/alf/schema/EventBase/1' xmlns:ns3='http://www.eclipse.org/alf/logging' xmlns:soapenv='http://schemas.xmlsoap.org/soap/envelope/'>34</ns3:eventId> <ns3:serviceFlowId xmlns:ns='http://www.eclipse.org/alf/schema/EventBase/1' xmlns:ns3='http://www.eclipse.org/alf/logging' xmlns:soapenv='http://schemas.xmlsoap.org/soap/envelope/'>354345</ns3:serviceFlowId> <ns3:logMessage xmlns:ns3='http://www.eclipse.org/alf/logging'>SimpleCompensate - Inner Work</ns3:logMessage> <ns3:logLevel xmlns:ns3='http://www.eclipse.org/alf/logging'>INFO</ns3:logLevel> </defaultNS:logString> </env:Body> </env:Envelope> 2007-03-08 13:28:07,222 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with SOAPAction : "" 2007-03-08 13:28:07,222 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with Content-Type : text/xml; charset=UTF-8 2007-03-08 13:28:07,693 DEBUG [org.jboss.ws.core.soap.SOAPMessageUnMarshaller] getMimeHeaders from: {ResponseCodeMessage=OK, null=[HTTP/1.1 200 OK], Date=[Thu, 08 Mar 2007 21:28:07 GMT], Content-Type=[text/xml;charset=utf-8], Server=[Apache-Coyote/1.1], HEADER={SOAPAction="", Content-Type=text/xml; charset=UTF-8}, Transfer-Encoding=[chunked], NoThrowOnError=true, ResponseCode=200} 2007-03-08 13:28:07,693 DEBUG [org.jboss.ws.core.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8] 2007-03-08 13:28:07,693 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] disconnect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@11c7571 2007-03-08 13:28:07,693 DEBUG [org.jboss.remoting.InvokerRegistry] destroying client for locator: InvokerLocator [http://127.0.0.1:8085/ALFEventManager/services/ALFLoggingService?datatype=SOAPMessage], invoker:org.jboss.remoting.transport.http.HTTPClientInvoker@11c7571, remaining list:{} 2007-03-08 13:28:07,693 DEBUG [org.jboss.remoting.InvokerRegistry] destroying client for locator: InvokerLocator [http://127.0.0.1:8085/ALFEventManager/services/ALFLoggingService?datatype=SOAPMessage], invoker:null, remaining list:{} 2007-03-08 13:28:07,693 TRACE [jbossws.SOAPMessage] Incoming Response SOAPMessage <soapenv:Envelope 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'> <soapenv:Header> <Dummy soapenv:actor='http://schemas.xmlsoap.org/soap/actor/next' soapenv:mustUnderstand='0' xmlns=''/> </soapenv:Header> <soapenv:Body> <logStringResponse xmlns='http://www.eclipse.org/alf/logging'/> </soapenv:Body> </soapenv:Envelope> 2007-03-08 13:28:07,693 DEBUG [org.jbpm.bpel.integration.jms.JmsIntegrationService] invoked: partnerLink=CommonLoggingPL, operation=logString, output={parameters=[defaultNS:logStringResponse: null]} 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'Invoke(1f8a98)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(Invoke-StructuredActivity.End)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'StructuredActivity$End(182c145)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'StructuredActivity$End(182c145)' for 'Token(/primary/null/primary/null/primary)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'completed' on 'Scope(21be6)' for 'Token(/primary/null/primary/null)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'Scope(21be6)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition(Scope-StructuredActivity.End)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:07,693 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'Throw(116cf35)' for 'Token(/primary/null/primary)' 2007-03-08 13:28:07,703 DEBUG [org.jbpm.bpel.graph.scope.FaultActionHandler] handling fault: org.jbpm.bpel.graph.exe.ScopeInstance@15b26b[name=<null>,token=/primary/null,state=performingPrimaryActivity,id=0] org.jbpm.bpel.graph.exe.BpelFaultException: org.jbpm.bpel.graph.exe.FaultInstance@10f5aca[name=SimpleCompensateExampleFault,id=0] at org.jbpm.bpel.graph.basic.Throw.execute(Throw.java:60) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor146.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:184) at org.jbpm.graph.exe.ExecutionContext.leaveNode(ExecutionContext.java:120) at org.jbpm.bpel.graph.exe.ScopeInstance.notifyCompletion(ScopeInstance.java:338) at org.jbpm.bpel.graph.exe.state.EndedState.enterCompleted(EndedState.java:62) at org.jbpm.bpel.graph.exe.state.ActiveState$1.completed(ActiveState.java:35) at org.jbpm.bpel.graph.exe.ScopeInstance.completed(ScopeInstance.java:145) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:203) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.struct.StructuredActivity$End_$$_javassist_76.leave(StructuredActivity$End_$$_javassist_76.java) at org.jbpm.bpel.graph.struct.StructuredActivity.leave(StructuredActivity.java:62) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.def.CompositeActivity_$$_javassist_156.leave(CompositeActivity_$$_javassist_156.java) at org.jbpm.bpel.graph.struct.StructuredActivity$End.execute(StructuredActivity.java:246) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor148.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.struct.StructuredActivity$End_$$_javassist_76.enter(StructuredActivity$End_$$_javassist_76.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:184) at org.jbpm.bpel.graph.basic.Invoke.execute(Invoke.java:47) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor146.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:184) at org.jbpm.bpel.graph.basic.Assign.execute(Assign.java:55) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor146.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.struct.StructuredActivity$Begin_$$_javassist_9.leave(StructuredActivity$Begin_$$_javassist_9.java) at org.jbpm.bpel.graph.struct.StructuredActivity.execute(StructuredActivity.java:58) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.def.CompositeActivity_$$_javassist_156.execute(CompositeActivity_$$_javassist_156.java) at org.jbpm.bpel.graph.struct.StructuredActivity$Begin.leave(StructuredActivity.java:222) at org.jbpm.graph.def.Node.execute(Node.java:349) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor148.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.struct.StructuredActivity$Begin_$$_javassist_9.enter(StructuredActivity$Begin_$$_javassist_9.java) at org.jbpm.bpel.graph.struct.StructuredActivity.enter(StructuredActivity.java:54) at sun.reflect.GeneratedMethodAccessor148.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.def.Activity_$$_javassist_159.enter(Activity_$$_javassist_159.java) at org.jbpm.bpel.graph.scope.Scope.execute(Scope.java:108) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor146.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:184) at org.jbpm.bpel.graph.basic.Invoke.execute(Invoke.java:47) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor146.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:184) at org.jbpm.bpel.graph.basic.Assign.execute(Assign.java:55) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor146.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.struct.StructuredActivity$Begin_$$_javassist_9.leave(StructuredActivity$Begin_$$_javassist_9.java) at org.jbpm.bpel.graph.struct.StructuredActivity.execute(StructuredActivity.java:58) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.def.CompositeActivity_$$_javassist_156.execute(CompositeActivity_$$_javassist_156.java) at org.jbpm.bpel.graph.struct.StructuredActivity$Begin.leave(StructuredActivity.java:222) at org.jbpm.graph.def.Node.execute(Node.java:349) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at sun.reflect.GeneratedMethodAccessor148.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.struct.StructuredActivity$Begin_$$_javassist_9.enter(StructuredActivity$Begin_$$_javassist_9.java) at org.jbpm.bpel.graph.struct.StructuredActivity.enter(StructuredActivity.java:54) at sun.reflect.GeneratedMethodAccessor148.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.def.Activity_$$_javassist_159.enter(Activity_$$_javassist_159.java) at org.jbpm.bpel.graph.scope.Scope.execute(Scope.java:108) at org.jbpm.bpel.graph.def.Activity.enter(Activity.java:105) at org.jbpm.graph.def.Transition.take(Transition.java:151) at org.jbpm.graph.def.Node.leave(Node.java:393) at org.jbpm.bpel.graph.def.Activity.leave(Activity.java:184) at org.jbpm.bpel.graph.struct.ProcessInstanceStarter.visit(ProcessInstanceStarter.java:66) at org.jbpm.bpel.graph.basic.Receive.accept(Receive.java:81) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.basic.Receive_$$_javassist_17.accept(Receive_$$_javassist_17.java) at org.jbpm.bpel.graph.struct.ProcessInstanceStarter.visit(ProcessInstanceStarter.java:100) at org.jbpm.bpel.graph.struct.Sequence.accept(Sequence.java:104) at sun.reflect.GeneratedMethodAccessor139.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:205) at org.jbpm.bpel.graph.def.Activity_$$_javassist_159.accept(Activity_$$_javassist_159.java) at org.jbpm.bpel.graph.struct.ProcessInstanceStarter.visit(ProcessInstanceStarter.java:54) at org.jbpm.bpel.graph.def.BpelDefinition.messageReceived(BpelDefinition.java:111) at org.jbpm.bpel.integration.jms.StartListener.onMessage(StartListener.java:124) at org.jboss.mq.SpyMessageConsumer.run(SpyMessageConsumer.java:696) at java.lang.Thread.run(Thread.java:595) 2007-03-08 13:28:07,723 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'terminatingPrimaryActivityOnFault' on 'Scope(121572b)' for 'Token(/primary/null)' 2007-03-08 13:28:07,733 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'faultingWithoutHandler' on 'Scope(121572b)' for 'Token(/primary/null)' 2007-03-08 13:28:07,733 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.Activity - this operation breaks == 2007-03-08 13:28:07,733 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.Activity - this operation breaks == 2007-03-08 13:28:07,733 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.Activity - this operation breaks == 2007-03-08 13:28:07,733 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.Activity - this operation breaks == 2007-03-08 13:28:07,733 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.graph.def.Activity - this operation breaks == 2007-03-08 13:28:07,733 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'faulted' on 'Scope(121572b)' for 'Token(/primary/null)' 2007-03-08 13:28:07,733 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'terminatingPrimaryActivityOnFault' on 'BpelDefinition$GlobalScope(4fa1cd)' for 'Token(/)' 2007-03-08 13:28:07,733 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'faultingWithoutHandler' on 'BpelDefinition$GlobalScope(4fa1cd)' for 'Token(/)' 2007-03-08 13:28:07,773 DEBUG [org.jbpm.bpel.graph.exe.ScopeInstance] state change to 'faulted' on 'BpelDefinition$GlobalScope(4fa1cd)' for 'Token(/)' 2007-03-08 13:28:07,773 DEBUG [org.jbpm.svc.Services] executing default save operations
Is something wrong with BPEL? This sample is tested on ActiveBPEL and it works there.
Thanks,
Meghana