ode-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Alexandre SAPANEL <alexandre.sapa...@photobox.com>
Subject Re: Transaction problem in parallel foreach (Apache ODE 1.3.5)
Date Thu, 18 Aug 2011 11:30:55 GMT
Sorry, maybe my previous email was not very clear... In fact I need to to be
able to retrieve a list of process instances by filtering them on the value
of a correlation property (prodOrderId) and this functionnality only works
with Hibernate... That's why I need Hibernate as a persistence layer ...

On Thu, Aug 18, 2011 at 1:03 PM, Alexandre SAPANEL <
alexandre.sapanel@photobox.com> wrote:

> Ok, i'll will try this but I really need Hibernate as a persistence layer
> because I need some ODE functionnality (I mean the possibility to invoke the
> "listInstance" service with  $prodOrderId in filter) that are not working
> with OpenJPA...
>
>
> On Wed, Aug 10, 2011 at 2:02 PM, Waruna Ranasinghe <warunapww@gmail.com>wrote:
>
>> Hi,
>>
>> WSO2 BPS 2.1.0 also use Apache ODE but with OpenJPA as the persistent
>> layer
>> (not Hibernate).
>> Can you try this process with Apache ODE with OpenJPA as the persistent
>> layer instead of Hibernate.
>>
>> Thanks,
>> Waruna
>> On 2 August 2011 00:05, Alexandre SAPANEL <alexandre.sapanel@photobox.com
>> >wrote:
>>
>> >  *CONFIGURATION USED* : Apache ODE 1.3.5, Mysql 5, Tomcat 6.0.29 and
>> > Hibernate
>> >
>>
>> >
>> >
>> >
>> > Hi,
>> >
>> >
>> >
>> > I am facing a problem with a parallel foreach (iterating over 2
>> > “orderItems”) and synchronous invoke.
>> >
>> >
>> >
>> > I can see in logs that the call to the setOrderItemStatus operation is
>> > correctly done for the 2 orderItems and the received responses are
>> correct,
>> > but the scope “SetOrderItemStatus_Scope”of the first iteration is never
>> > completed whereas the scope of the second iteration is completed.
>> >
>> > In my opinion it seems to be a transaction problem but and I can’t see
>> > anything interesting in logs (see below).
>> >
>> >
>> >
>> >
>> >
>> > Any help will be greatly appreciated. Also*, I made a test on WSO2 BPS
>> > 2.1.0 and my process works well* … Maybe I need to add some
>> configuration
>> > parameters in my ode-axis2.properties …
>> >
>> >
>> >
>> >
>> >
>> > Thanks
>> >
>> >
>> >
>> > Alexandre
>> >
>> >
>> >
>> > ---------- BPEL EXTRACT ------------------
>> >
>> > <bpel:forEach parallel="yes" counterName="CounterOrderItem"
>> >
>> >           name="ForEach_OrderItem_In_ProductionOrder">
>> >
>> >           <bpel:startCounterValue><![CDATA[1]]></bpel:startCounterValue>
>> >
>> >
>> >
>> <bpel:finalCounterValue><![CDATA[$nbOrderItems]]></bpel:finalCounterValue>
>> >
>> >           <bpel:scope name="SplitOrderIntoItems">
>> >
>> >                     <bpel:variables>
>> >
>> >                               <bpel:variable name="orderItem"
>> > type="dom:orderItem" />
>> >
>> >                               <bpel:variable name="nbItemComponent"
>> > type="xs:int" />
>> >
>> >                     </bpel:variables>
>> >
>> >                     <bpel:partnerLinks>
>> >
>> >                               <bpel:partnerLink
>> > name="ProductionOrderServicePartnerBis"
>> >
>> >
>> > partnerLinkType="process:ProductionOrderServiceLT"
>> > partnerRole="ProductionOrderServiceProvider"></bpel:partnerLink>
>> >
>> >                     </bpel:partnerLinks>
>> >
>> >                     <bpel:sequence name="Sequence">
>> >
>> >                               <bpel:assign validate="no"
>> > name="AssignOrderItem">
>> >
>> >                                        <bpel:copy>
>> >
>> >                                                  <bpel:from>
>> >
>> >
>> > <![CDATA[$productionOrder/dom:orderItems/dom:orderItem[position() =
>> > $CounterOrderItem]]]>
>> >
>> >                                                  </bpel:from>
>> >
>> >                                                  <bpel:to
>> > variable="orderItem"></bpel:to>
>> >
>> >                                        </bpel:copy>
>> >
>> >                               </bpel:assign>
>> >
>> >                               <bpel:assign validate="no"
>> > name="setNbItemComponent">
>> >
>> >                                        <bpel:copy>
>> >
>> >                                                  <bpel:from>
>> >
>> >                                                            <bpel:literal
>> > xml:space="preserve">1</bpel:literal>
>> >
>> >                                                  </bpel:from>
>> >
>> >                                                  <bpel:to
>> > variable="nbItemComponent"></bpel:to>
>> >
>> >                                        </bpel:copy>
>> >
>> >                                        <bpel:copy>
>> >
>> >                                                  <bpel:from>
>> >
>> >
>> > <![CDATA[count($orderItem/dom:components/dom:component)]]>
>> >
>> >                                                  </bpel:from>
>> >
>> >                                                  <bpel:to
>> > variable="nbItemComponent"></bpel:to>
>> >
>> >                                        </bpel:copy>
>> >
>> >                               </bpel:assign>
>> >
>> >                               <!-- Update the order item status -->
>> >
>> >                               <bpel:scope
>> name="SetOrderItemStatus_Scope">
>> >
>> >                                        <bpel:variables>
>> >
>> >                                                  <bpel:variable
>> > name="setOrderItemStatusInput"
>> >
>> >
>> > messageType="pom:setOrderItemStatus"></bpel:variable>
>> >
>> >                                                  <bpel:variable
>> > name="technicalException"
>> >
>> >
>> > messageType="pom:TechnicalException"></bpel:variable>
>> >
>> >                                                  <bpel:variable
>> > name="businessException"
>> >
>> >
>> > messageType="pom:BusinessException"></bpel:variable>
>> >
>> >                                                  <bpel:variable
>> > name="updatedOrderItemStatus"
>> >
>> >
>> > messageType="pom:setOrderItemStatusResponse">
>> >
>> >                                                            <bpel:from>
>> >
>> >
>> > <bpel:literal xml:space="preserve"><tns:setOrderItemStatusResponse
>> >
>> >
>> >           xmlns:tns="
>> > http://photobox.com/production/services/pom/ProductionOrderService"
>> >
>> >
>> >           xmlns:tns1="
>> > http://photobox.com/production/domain/model/schemas/extension"
>> >
>> >
>> >           xmlns:tns2="
>> http://photobox.com/production/domain/model/schemas"
>> >
>> >
>> >           xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
>> >
>> >
>> >
>> > <return>true</return></tns:setOrderItemStatusResponse>
>> >
>> >
>> >
>> >
>> > </bpel:literal>
>> >
>> >                                                            </bpel:from>
>> >
>> >                                                  </bpel:variable>
>> >
>> >                                        </bpel:variables>
>> >
>> >                                        <bpel:sequence
>> > name="SetOrderItemStatus_Sequence">
>> >
>> >                                                  <bpel:assign
>> validate="no"
>> > name="prepareSetOrderItemStatus">
>> >
>> >                                                            <bpel:copy>
>> >
>> >
>> > <bpel:from>
>> >
>> >
>> > <bpel:literal xml:space="preserve"><tns:setOrderItemStatus
>> >
>> >
>> >           xmlns:tns="
>> > http://photobox.com/production/services/pom/ProductionOrderService"
>> >
>> >
>> >           xmlns:tns1="
>> > http://photobox.com/production/domain/model/schemas/extension"
>> >
>> >
>> >           xmlns:tns2="
>> http://photobox.com/production/domain/model/schemas"
>> >
>> >
>> >           xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
>> >
>> >
>> >           <orderItemId>0</orderItemId>
>> >
>> >
>> >           <orderItemStatus>INITIALIZED</orderItemStatus>
>> >
>> >
>> > </tns:setOrderItemStatus>
>> >
>> >
>> > </bpel:literal>
>> >
>> >
>> > </bpel:from>
>> >
>> >
>> > <bpel:to part="parameters" variable="setOrderItemStatusInput"></bpel:to>
>> >
>> >                                                            </bpel:copy>
>> >
>> >                                                            <bpel:copy>
>> >
>> >
>> > <bpel:from>
>> >
>> >
>> >           <bpel:literal><![CDATA[PROCESSING]]></bpel:literal>
>> >
>> >
>> > </bpel:from>
>> >
>> >
>> > <bpel:to part="parameters" variable="setOrderItemStatusInput">
>> >
>> >
>> > <bpel:query
>> queryLanguage="urn:oasis:names:tc:wsbpel:2.0:sublang:xpath1.0">
>> >
>> >
>> >           <![CDATA[orderItemStatus]]>
>> >
>> >
>> > </bpel:query>
>> >
>> >
>> > </bpel:to>
>> >
>> >                                                            </bpel:copy>
>> >
>> >                                                            <bpel:copy>
>> >
>> >
>> > <bpel:from> <![CDATA[$orderItem/dom:id]]>
>> >
>> >
>> > </bpel:from>
>> >
>> >
>> > <bpel:to part="parameters" variable="setOrderItemStatusInput">
>> >
>> >
>> > <bpel:query
>> >
>> queryLanguage="urn:oasis:names:tc:wsbpel:2.0:sublang:xpath1.0"><![CDATA[orderItemId]]></bpel:query>
>> >
>> >
>> > </bpel:to>
>> >
>> >                                                            </bpel:copy>
>> >
>> >                                                  </bpel:assign>
>> >
>> >                                                  <*bpel:invoke
>> > name="setOrderItemStatus"*
>> >
>> >
>> > partnerLink="ProductionOrderServicePartnerBis"
>> > operation="setOrderItemStatus"
>> >
>> >
>> > portType="pom:ProductionOrderService"
>> > inputVariable="setOrderItemStatusInput"
>> >
>> >
>> > outputVariable="updatedOrderItemStatus">
>> >
>> >                                                  </bpel:invoke>
>> >
>> >                                                  <!-- Update the
>> orderItem
>> > variable with the new status -->
>> >
>> >                                                  <bpel:assign
>> validate="no"
>> > name="setOrderItemStatus">
>> >
>> >                                                            <bpel:copy>
>> >
>> >
>> > <bpel:from>
>> >
>> >
>> >           <bpel:literal><![CDATA[PROCESSING]]></bpel:literal>
>> >
>> >
>> > </bpel:from>
>> >
>> >
>> > <bpel:to variable="orderItem">
>> >
>> >
>> > <bpel:query
>> queryLanguage="urn:oasis:names:tc:wsbpel:2.0:sublang:xpath1.0">
>> >
>> >
>> >           <![CDATA[dom:status]]>
>> >
>> >
>> > </bpel:query>
>> >
>> >
>> > </bpel:to>
>> >
>> >                                                            </bpel:copy>
>> >
>> >                                                  </bpel:assign>
>> >
>> >                                        </bpel:sequence>
>> >
>> >                               </bpel:scope>
>> >
>> >                     </bpel:scope>
>> >
>> >           </bpel:foreach>
>> >
>> >
>> >
>> >
>> >
>> > ---------------- LOGS EXTRACT-----------------
>> >
>> > [2011-08-01 11:00:41,640] INFO: Response:
>> >
>> > <?xml version="1.0" encoding="UTF-8"?>
>> >
>> > <message><parameters><setOrderItemStatusResponse xmlns="
>> > http://photobox.com/production/services/pom/ProductionOrderService"
>> > xmlns:prod="
>> > http://photobox.com/production/services/pom/ProductionOrderService"
>> > xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/">
>> >
>> >          <return xmlns="">true</return>
>> >
>> >       </setOrderItemStatusResponse></parameters></message>
>> > [org.apache.ode.axis2.ExternalService]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: reply mex=1906
>> > [org.apache.ode.bpel.engine.PartnerRoleMessageExchangeImpl]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: create work event for mex=1906
>> > [org.apache.ode.bpel.engine.PartnerRoleMessageExchangeImpl]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: scheduling JobDetails( instanceId: 2100
>> > mexId: 1906 processId: null type: INVOKE_RESPONSE channel: 125
>> correlatorId:
>> > null correlationKeySet: null retryCount: null inMem: false detailsExt:
>> {})
>> > for Mon Aug 01 11:00:41 CEST 2011
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: insertJob hqejbhcnphr6hbepu99t63 on
>> node
>> > hqejbhcnphr6hbepu99t5e loaded=true
>> > [org.apache.ode.scheduler.simple.JdbcDelegate]
>> >
>> > [2011-08-01 11:00:41,656] INFO: Response:
>> >
>> > <?xml version="1.0" encoding="UTF-8"?>
>> >
>> > <message><parameters><setOrderItemStatusResponse xmlns="
>> > http://photobox.com/production/services/pom/ProductionOrderService"
>> > xmlns:prod="
>> > http://photobox.com/production/services/pom/ProductionOrderService"
>> > xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/">
>> >
>> >          <return xmlns="">true</return>
>> >
>> >       </setOrderItemStatusResponse></parameters></message>
>> > [org.apache.ode.axis2.ExternalService]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: reply mex=1907
>> > [org.apache.ode.bpel.engine.PartnerRoleMessageExchangeImpl]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: create work event for mex=1907
>> > [org.apache.ode.bpel.engine.PartnerRoleMessageExchangeImpl]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: scheduling JobDetails( instanceId: 2100
>> > mexId: 1907 processId: null type: INVOKE_RESPONSE channel: 144
>> correlatorId:
>> > null correlationKeySet: null retryCount: null inMem: false detailsExt:
>> {})
>> > for Mon Aug 01 11:00:41 CEST 2011
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: insertJob hqejbhcnphr6hbepu99t64 on
>> node
>> > hqejbhcnphr6hbepu99t5e loaded=true
>> > [org.apache.ode.scheduler.simple.JdbcDelegate]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: insert into ODE_JOB
>> > (jobid, nodeid, ts, scheduled, transacted,
>> >
>> instanceId,mexId,processId,type,channel,correlatorId,correlationKeySet,retryCount,inMem,detailsExt)
>> > values(?, ?, ?, ?, ?,?,?,?,?,?,?,?,?,?,?) [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: insert into ODE_JOB
>> > (jobid, nodeid, ts, scheduled, transacted,
>> >
>> instanceId,mexId,processId,type,channel,correlatorId,correlationKeySet,retryCount,inMem,detailsExt)
>> > values(?, ?, ?, ?, ?,?,?,?,?,?,?,?,?,?,?) [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeUpdate, bound
>> > (1,hqejbhcnphr6hbepu99t63) (2,hqejbhcnphr6hbepu99t5e) (3,1312189241656)
>> > (4,1) (5,1) (6,2100) (7,1906) (8,null) (9,INVOKE_RESPONSE) (10,125)
>> > (11,null) (12,null) (13,null) (14,false) (15,null)  [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeUpdate, bound
>> > (1,hqejbhcnphr6hbepu99t64) (2,hqejbhcnphr6hbepu99t5e) (3,1312189241656)
>> > (4,1) (5,1) (6,2100) (7,1907) (8,null) (9,INVOKE_RESPONSE) (10,144)
>> > (11,null) (12,null) (13,null) (14,false) (15,null)  [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: scheduled immediate job:
>> > hqejbhcnphr6hbepu99t63 [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: Commiting on
>> >
>> org.apache.geronimo.transaction.manager.GeronimoTransactionManager@a0c486.
>> ..
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: insert into
>> BPEL_MESSAGE
>> > (MEX, TYPE, MESSAGE_DATA, MESSAGE_HEADER, INSERT_TIME, MLOCK, ID) values
>> (?,
>> > ?, ?, ?, ?, ?, ?) [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeBatch, bound (1,1906) (2,{
>> >
>> http://photobox.com/production/services/pom/ProductionOrderService}setOrderItemStatusResponse
>> )
>> > (3,null) (4,null) (5,2011-08-01 11:00:41.64) (6,0) (7,2009)
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: scheduled immediate job:
>> > hqejbhcnphr6hbepu99t64 [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: Commiting on
>> >
>> org.apache.geronimo.transaction.manager.GeronimoTransactionManager@a0c486.
>> ..
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: update
>> > BPEL_MESSAGE_EXCHANGE set RESPONSE=?, STATE=? where ID=?
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeBatch, bound (1,2009)
>> (2,RESPONSE)
>> > (3,1906)  [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: insert into
>> BPEL_MESSAGE
>> > (MEX, TYPE, MESSAGE_DATA, MESSAGE_HEADER, INSERT_TIME, MLOCK, ID) values
>> (?,
>> > ?, ?, ?, ?, ?, ?) [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeBatch, bound (1,1907) (2,{
>> >
>> http://photobox.com/production/services/pom/ProductionOrderService}setOrderItemStatusResponse
>> )
>> > (3,null) (4,null) (5,2011-08-01 11:00:41.64) (6,0) (7,2010)
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: update BPEL_MESSAGE
>> set
>> > MEX=?, TYPE=?, MESSAGE_DATA=?, MESSAGE_HEADER=?, INSERT_TIME=?, MLOCK=?
>> > where ID=? [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeBatch, bound (1,1906) (2,{
>> >
>> http://photobox.com/production/services/pom/ProductionOrderService}setOrderItemStatusResponse
>> )
>> > (3,[B@184cdab) (4,null) (5,2011-08-01 11:00:41.64) (6,0) (7,2009)
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: update
>> > BPEL_MESSAGE_EXCHANGE set RESPONSE=?, STATE=? where ID=?
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: executeBatch, bound (1,2010)
>> (2,RESPONSE)
>> > (3,1907)  [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\r]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: Beginning a new transaction
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\n]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "0" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: deleteJob hqejbhcnphr6hbepu99t63 on
>> node
>> > hqejbhcnphr6hbepu99t5e [org.apache.ode.scheduler.simple.JdbcDelegate]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\r]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\n]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\r]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\n]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: << "[\r][\n]" [httpclient.wire.header]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: prepareStatement: update BPEL_MESSAGE
>> set
>> > MEX=?, TYPE=?, MESSAGE_DATA=?, MESSAGE_HEADER=?, INSERT_TIME=?, MLOCK=?
>> > where ID=? [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: Resorting to protocol version default
>> > close connection policy [org.apache.commons.httpclient.HttpMethodBase]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: Should NOT close connection, using
>> > HTTP/1.1 [org.apache.commons.httpclient.HttpMethodBase]
>> >
>> > [2011-08-01 11:00:41,656] DEBUG: Releasing connection back to connection
>> > manager. [org.apache.commons.httpclient.HttpConnection]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Freeing connection,
>> > hostConfig=HostConfiguration[host=http://localhost:8091]
>> > [org.apache.commons.httpclient.MultiThreadedHttpConnectionManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Adding connection at: 1312189241671
>> > [org.apache.commons.httpclient.util.IdleConnectionHandler]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Notifying no-one, there are no waiting
>> > threads
>> [org.apache.commons.httpclient.MultiThreadedHttpConnectionManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: executeBatch, bound (1,1907) (2,{
>> >
>> http://photobox.com/production/services/pom/ProductionOrderService}setOrderItemStatusResponse
>> )
>> > (3,[B@12dd803) (4,null) (5,2011-08-01 11:00:41.64) (6,0) (7,2010)
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: prepareStatement: delete from ODE_JOB
>> > where jobid = ? and nodeid = ? [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: executeUpdate, bound
>> > (1,hqejbhcnphr6hbepu99t63) (2,hqejbhcnphr6hbepu99t5e)
>>  [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\r]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Beginning a new transaction
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\n]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: deleteJob hqejbhcnphr6hbepu99t64 on
>> node
>> > hqejbhcnphr6hbepu99t5e [org.apache.ode.scheduler.simple.JdbcDelegate]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "0" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\r]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\n]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\r]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Thread[ODEServer-63,5,main]:
>> > lock(iid=2100, time=1MICROSECONDS)
>> > [org.apache.ode.bpel.engine.InstanceLockManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\n]" [httpclient.wire.content]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Thread[ODEServer-63,5,main]:
>> > lock(iid=2100, time=1MICROSECONDS)-->GRANTED
>> > [org.apache.ode.bpel.engine.InstanceLockManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: << "[\r][\n]" [httpclient.wire.header]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Resorting to protocol version default
>> > close connection policy [org.apache.commons.httpclient.HttpMethodBase]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Should NOT close connection, using
>> > HTTP/1.1 [org.apache.commons.httpclient.HttpMethodBase]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Releasing connection back to connection
>> > manager. [org.apache.commons.httpclient.HttpConnection]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Freeing connection,
>> > hostConfig=HostConfiguration[host=http://localhost:8091]
>> > [org.apache.commons.httpclient.MultiThreadedHttpConnectionManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: prepareStatement: delete from ODE_JOB
>> > where jobid = ? and nodeid = ? [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Adding connection at: 1312189241671
>> > [org.apache.commons.httpclient.util.IdleConnectionHandler]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Notifying no-one, there are no waiting
>> > threads
>> [org.apache.commons.httpclient.MultiThreadedHttpConnectionManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: executeUpdate, bound
>> > (1,hqejbhcnphr6hbepu99t64) (2,hqejbhcnphr6hbepu99t5e)
>>  [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: prepareStatement: select
>> hprocessin0_.ID
>> > as ID1_1_, hprocessin0_.INSTANTIATING_CORRELATOR as INSTANTI2_1_1_,
>> > hprocessin0_.FAULT as FAULT1_1_, hprocessin0_.JACOB_STATE_DATA as
>> > JACOB4_1_1_, hprocessin0_.PREVIOUS_STATE as PREVIOUS5_1_1_,
>> > hprocessin0_.PROCESS_ID as PROCESS6_1_1_, hprocessin0_.STATE as
>> STATE1_1_,
>> > hprocessin0_.LAST_ACTIVE_DT as LAST8_1_1_, hprocessin0_.SEQUENCE as
>> > SEQUENCE1_1_, hprocessin0_.FAILURE_COUNT as FAILURE10_1_1_,
>> > hprocessin0_.FAILURE_DT as FAILURE11_1_1_, hprocessin0_.INSERT_TIME as
>> > INSERT12_1_1_, hprocessin0_.MLOCK as MLOCK1_1_, hprocess1_.ID as ID0_0_,
>> > hprocess1_.PROCID as PROCID0_0_, hprocess1_.deployer as deployer0_0_,
>> > hprocess1_.deploydate as deploydate0_0_, hprocess1_.type_name as
>> type5_0_0_,
>> > hprocess1_.type_ns as type6_0_0_, hprocess1_.version as version0_0_,
>> > hprocess1_.ACTIVE_ as ACTIVE8_0_0_, hprocess1_.guid as guid0_0_,
>> > hprocess1_.INSERT_TIME as INSERT10_0_0_, hprocess1_.MLOCK as MLOCK0_0_
>> from
>> > BPEL_INSTANCE hprocessin0_ left outer join BPEL_PROCESS hprocess1_ on
>> > hprocessin0_.PROCESS_ID=hprocess1_.ID where hprocessin0_.ID=?
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: executeQuery, bound (1,2100)
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Thread[ODEServer-64,5,main]: lock(iid=
>> >
>> > iuy2100, time=1MICROSECONDS)
>> > [org.apache.ode.bpel.engine.InstanceLockManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Thread[ODEServer-64,5,main]:
>> > lock(iid=2100, time=1MICROSECONDS)-->WAITING(held by
>> > Thread[ODEServer-63,5,main])
>> > [org.apache.ode.bpel.engine.InstanceLockManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: >> handleJobDetails(jobData=JobDetails(
>> > instanceId: 2100 mexId: 1906 processId: null type: INVOKE_RESPONSE
>> channel:
>> > 125 correlatorId: null correlationKeySet: null retryCount: null inMem:
>> false
>> > detailsExt: {})) [org.apache.ode.bpel.engine.BpelProcess]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: getConnection (tx=2)
>> [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Thread[ODEServer-64,5,main]:
>> > lock(iid=2100, time=1MICROSECONDS)-->TIMEOUT (held by
>> > Thread[ODEServer-63,5,main])
>> > [org.apache.ode.bpel.engine.InstanceLockManager]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Instance 2100 is busy, rescheduling
>> job.
>> > [org.apache.ode.bpel.engine.BpelEngineImpl]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Rollbacking on
>> >
>> org.apache.geronimo.transaction.manager.GeronimoTransactionManager@a0c486.
>> ..
>> > [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: Will retry the transaction in 1000
>> msecs
>> > on
>> org.apache.geronimo.transaction.manager.GeronimoTransactionManager@a0c486forerror:
 [org.apache.ode.scheduler.simple.SimpleScheduler]
>> >
>> > org.apache.ode.bpel.iapi.Scheduler$JobProcessorException
>> >
>> >           at
>> >
>> org.apache.ode.bpel.engine.BpelEngineImpl.acquireInstanceLock(BpelEngineImpl.java:394)
>> >
>> >           at
>> >
>> org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:403)
>> >
>> >           at
>> >
>> org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:450)
>> >
>> >           at
>> >
>> org.apache.ode.scheduler.simple.SimpleScheduler$RunJob$1.call(SimpleScheduler.java:518)
>> >
>> >           at
>> >
>> org.apache.ode.scheduler.simple.SimpleScheduler$RunJob$1.call(SimpleScheduler.java:512)
>> >
>> >           at
>> >
>> org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:284)
>> >
>> >           at
>> >
>> org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:239)
>> >
>> >           at
>> >
>> org.apache.ode.scheduler.simple.SimpleScheduler$RunJob.call(SimpleScheduler.java:512)
>> >
>> >           at
>> >
>> org.apache.ode.scheduler.simple.SimpleScheduler$RunJob.call(SimpleScheduler.java:496)
>> >
>> >           at
>> > java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
>> >
>> >           at java.util.concurrent.FutureTask.run(FutureTask.java:138)
>> >
>> >           at
>> >
>> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
>> >
>> >           at
>> >
>> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
>> >
>> >           at java.lang.Thread.run(Thread.java:619)
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: prepareStatement: select this_.ID as
>> > ID0_0_, this_.PROCID as PROCID0_0_, this_.deployer as deployer0_0_,
>> > this_.deploydate as deploydate0_0_, this_.type_name as type5_0_0_,
>> > this_.type_ns as type6_0_0_, this_.version as version0_0_, this_.ACTIVE_
>> as
>> > ACTIVE8_0_0_, this_.guid as guid0_0_, this_.INSERT_TIME as
>> INSERT10_0_0_,
>> > this_.MLOCK as MLOCK0_0_ from BPEL_PROCESS this_ where this_.PROCID=?
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: executeQuery, bound (1,{
>> >
>> http://photobox.com/production/workflow/ProductionOrderProcess}ProductionOrderProcess-2
>> )
>> > [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> > [2011-08-01 11:00:41,671] DEBUG: close() [org.apache.ode.sql]
>> >
>> >   *Alexandre SAPANEL*
>> >
>> >  IT Prod Software Architect
>> >
>> > *E-mail*
>> >
>> >  alexandre.sapanel@photobox.com
>> >
>> > *Téléphone*
>> >
>> >  01 30 86 80 80
>> >
>> > *Fax*
>> >
>> >  01 30 86 80 59
>> >
>> >
>> >
>>
>>
>>
>> --
>> -----------------------------------------------------
>> Regards,
>> Waruna Ranasinghe
>>
>> blog: http://warunapw.blogspot.com
>> twitter: http://twitter.com/warunapww
>> http://lk.linkedin.com/in/waruna
>> www.facebook.com/waruna.ranasinghe
>>
>
>

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message