[jboss-user] [JBoss jBPM] - fault never reaches catchall

meghanai_99 do-not-reply at jboss.com
Thu Mar 8 16:57:00 EST 2007


Hello,

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 at 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 at 113e259[name=CommonLoggingPL,id=268], reference=org.jbpm.bpel.endpointref.wsa.WsaEndpointReference at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 15b26b[name=<null>,token=/primary/null,state=performingPrimaryActivity,id=0]
  | org.jbpm.bpel.graph.exe.BpelFaultException: org.jbpm.bpel.graph.exe.FaultInstance at 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

View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4026440#4026440

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4026440



More information about the jboss-user mailing list