ode-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Matheswaran Nachimuthu <matheswara...@infosys.com>
Subject ode exception
Date Wed, 28 Jan 2009 12:00:15 GMT

Hi,

I am using ode 1.1 along with MySql

When i try to run the orchestration the following error occurs sometimes and
the orchestration stops completely.

This happens after the invoke statement

Loggers :

Logger: DEBUG  - TimeStamp: 2009-01-28 15:39:27,094
An exception occurred while ending the transaction.  This exception will be
re-thrown.

<openjpa-1.0.1-r420667:592145 nonfatal store error>
org.apache.openjpa.util.OptimisticException: Optimistic locking errors were
detected when flushing to the data store.  The following objects may have
been concurrently modified in another transaction:
[org.apache.ode.dao.jpa.MessageRouteDAOImpl-org.apache.ode.dao.jpa.MessageRouteDAOImpl-953]
	at
org.apache.openjpa.kernel.BrokerImpl.newFlushException(BrokerImpl.java:2104)
	at org.apache.openjpa.kernel.BrokerImpl.flush(BrokerImpl.java:1954)
	at org.apache.openjpa.kernel.BrokerImpl.flushSafe(BrokerImpl.java:1852)
	at
org.apache.openjpa.kernel.BrokerImpl.beforeCompletion(BrokerImpl.java:1770)
	at
org.apache.geronimo.transaction.manager.TransactionImpl.beforeCompletion(TransactionImpl.java:515)
	at
org.apache.geronimo.transaction.manager.TransactionImpl.beforePrepare(TransactionImpl.java:399)
	at
org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:256)
	at
org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:264)
	at
org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:186)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:339)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:336)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
Caused by: <openjpa-1.0.1-r420667:592145 nonfatal store error>
org.apache.openjpa.util.OptimisticException: An optimistic lock violation
was detected when flushing object instance
"org.apache.ode.dao.jpa.MessageRouteDAOImpl-org.apache.ode.dao.jpa.MessageRouteDAOImpl-953"
to the data store.  This indicates that the object was concurrently modified
in another transaction.
FailedObject:
org.apache.ode.dao.jpa.MessageRouteDAOImpl-org.apache.ode.dao.jpa.MessageRouteDAOImpl-953
	at
org.apache.openjpa.jdbc.kernel.PreparedStatementManagerImpl.flushInternal(PreparedStatementManagerImpl.java:101)
	at
org.apache.openjpa.jdbc.kernel.PreparedStatementManagerImpl.flush(PreparedStatementManagerImpl.java:73)
	at
org.apache.openjpa.jdbc.kernel.ConstraintUpdateManager.flush(ConstraintUpdateManager.java:543)
	at
org.apache.openjpa.jdbc.kernel.ConstraintUpdateManager.flush(ConstraintUpdateManager.java:119)
	at
org.apache.openjpa.jdbc.kernel.AbstractUpdateManager.flush(AbstractUpdateManager.java:89)
	at
org.apache.openjpa.jdbc.kernel.AbstractUpdateManager.flush(AbstractUpdateManager.java:72)
	at
org.apache.openjpa.jdbc.kernel.JDBCStoreManager.flush(JDBCStoreManager.java:514)
	at
org.apache.openjpa.kernel.DelegatingStoreManager.flush(DelegatingStoreManager.java:130)
	... 15 more
Logger: WARN  - TimeStamp: 2009-01-28 15:39:27,094
Unexpected exception from beforeCompletion; transaction will roll back

<openjpa-1.0.1-r420667:592145 nonfatal store error>
org.apache.openjpa.persistence.OptimisticLockException: Optimistic locking
errors were detected when flushing to the data store.  The following objects
may have been concurrently modified in another transaction:
[org.apache.ode.dao.jpa.MessageRouteDAOImpl-org.apache.ode.dao.jpa.MessageRouteDAOImpl-953]
	at
org.apache.openjpa.kernel.BrokerImpl.newFlushException(BrokerImpl.java:2104)
	at org.apache.openjpa.kernel.BrokerImpl.flush(BrokerImpl.java:1954)
	at org.apache.openjpa.kernel.BrokerImpl.flushSafe(BrokerImpl.java:1852)
	at
org.apache.openjpa.kernel.BrokerImpl.beforeCompletion(BrokerImpl.java:1770)
	at
org.apache.geronimo.transaction.manager.TransactionImpl.beforeCompletion(TransactionImpl.java:515)
	at
org.apache.geronimo.transaction.manager.TransactionImpl.beforePrepare(TransactionImpl.java:399)
	at
org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:256)
	at
org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:264)
	at
org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:186)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:339)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:336)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
Caused by: <openjpa-1.0.1-r420667:592145 nonfatal store error>
org.apache.openjpa.persistence.OptimisticLockException: An optimistic lock
violation was detected when flushing object instance
"org.apache.ode.dao.jpa.MessageRouteDAOImpl-org.apache.ode.dao.jpa.MessageRouteDAOImpl-953"
to the data store.  This indicates that the object was concurrently modified
in another transaction.
FailedObject:
org.apache.ode.dao.jpa.MessageRouteDAOImpl-org.apache.ode.dao.jpa.MessageRouteDAOImpl-953
	at
org.apache.openjpa.jdbc.kernel.PreparedStatementManagerImpl.flushInternal(PreparedStatementManagerImpl.java:101)
	at
org.apache.openjpa.jdbc.kernel.PreparedStatementManagerImpl.flush(PreparedStatementManagerImpl.java:73)
	at
org.apache.openjpa.jdbc.kernel.ConstraintUpdateManager.flush(ConstraintUpdateManager.java:543)
	at
org.apache.openjpa.jdbc.kernel.ConstraintUpdateManager.flush(ConstraintUpdateManager.java:119)
	at
org.apache.openjpa.jdbc.kernel.AbstractUpdateManager.flush(AbstractUpdateManager.java:89)
	at
org.apache.openjpa.jdbc.kernel.AbstractUpdateManager.flush(AbstractUpdateManager.java:72)
	at
org.apache.openjpa.jdbc.kernel.JDBCStoreManager.flush(JDBCStoreManager.java:514)
	at
org.apache.openjpa.kernel.DelegatingStoreManager.flush(DelegatingStoreManager.java:130)
	... 15 more
Logger: DEBUG  - TimeStamp: 2009-01-28 15:39:27,094
<t 17920721, conn 0> [0 ms] close

Logger: DEBUG  - TimeStamp: 2009-01-28 15:39:27,110
<t 10716664, conn 24749257> executing prepstmnt 20180812 SELECT t0.CALLEE,
t0.CHANNEL, t0.CORRELATION_ID, t0.CORRELATION_KEYS, t0.CORRELATION_STATUS,
t0.CORR_ID, t0.CREATE_TIME, t0.DIRECTION, t0.EPR, t0.FAULT,
t0.FAULT_EXPLANATION, t0.OPERATION, t0.PARTNER_LINK_ID,
t0.PARTNER_LINK_MODEL_ID, t0.PATTERN, t0.PIPED_ID, t0.PORT_TYPE,
t0.PROCESS_ID, t0.PROCESS_INSTANCE_ID, t0.PROPAGATE_TRANS,
t0.REQUEST_MESSAGE_ID, t0.RESPONSE_MESSAGE_ID, t0.STATUS, t1.MEX_ID, t1.ID,
t1.PROP_KEY, t1.PROP_VALUE FROM ODE_MESSAGE_EXCHANGE t0 LEFT OUTER JOIN
ODE_MEX_PROP t1 ON t0.MESSAGE_EXCHANGE_ID = t1.MEX_ID WHERE
t0.MESSAGE_EXCHANGE_ID = ? ORDER BY t1.MEX_ID ASC [params=(String)
hqejbhcnphr400bemm923o]

Logger: ERROR  - TimeStamp: 2009-01-28 15:39:27,110
Error while executing transaction

javax.transaction.RollbackException: Unable to commit: transaction marked
for rollback
	at
org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:270)
	at
org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:264)
	at
org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:186)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:339)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:336)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
Logger: DEBUG  - TimeStamp: 2009-01-28 15:39:27,110
<t 10716664, conn 24749257> [0 ms] spent




After this error message the BPEL throws the following error msg and stops 





Exception while processing invoke response

org.apache.ode.bpel.iapi.BpelEngineException: Engine requested response
while the message exchange hqejbhcnphr400bemm923q was in the state ASYNC
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl._getPartnerResponse(BpelRuntimeContextImpl.java:1181)
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl.getPartnerResponse(BpelRuntimeContextImpl.java:1091)
	at org.apache.ode.bpel.runtime.INVOKE$1.onResponse(INVOKE.java:111)
	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.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:451)
	at org.apache.ode.jacob.vpu.JacobVPU.execute(JacobVPU.java:139)
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl.execute(BpelRuntimeContextImpl.java:812)
	at
org.apache.ode.bpel.engine.BpelProcess.handleWorkEvent(BpelProcess.java:392)
	at
org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:328)
	at
org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:373)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:341)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:340)
	at
org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:179)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:339)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:336)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
Logger: ERROR  - TimeStamp: 2009-01-28 15:39:27,172
Method "onResponse" in class "org.apache.ode.bpel.runtime.INVOKE$1" threw an
unexpected exception.

java.lang.RuntimeException: org.apache.ode.bpel.iapi.BpelEngineException:
Engine requested response while the message exchange hqejbhcnphr400bemm923q
was in the state ASYNC
	at org.apache.ode.bpel.runtime.INVOKE$1.onResponse(INVOKE.java:114)
	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.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:451)
	at org.apache.ode.jacob.vpu.JacobVPU.execute(JacobVPU.java:139)
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl.execute(BpelRuntimeContextImpl.java:812)
	at
org.apache.ode.bpel.engine.BpelProcess.handleWorkEvent(BpelProcess.java:392)
	at
org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:328)
	at
org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:373)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:341)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:340)
	at
org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:179)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:339)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:336)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
Caused by: org.apache.ode.bpel.iapi.BpelEngineException: Engine requested
response while the message exchange hqejbhcnphr400bemm923q was in the state
ASYNC
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl._getPartnerResponse(BpelRuntimeContextImpl.java:1181)
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl.getPartnerResponse(BpelRuntimeContextImpl.java:1091)
	at org.apache.ode.bpel.runtime.INVOKE$1.onResponse(INVOKE.java:111)
	... 20 more
Logger: ERROR  - TimeStamp: 2009-01-28 15:39:27,172
Scheduled job failed; jobDetail={type=INVOKE_RESPONSE,
mexid=hqejbhcnphr400bemm923o, channel=390, iid=301}

java.lang.RuntimeException: java.lang.RuntimeException:
org.apache.ode.bpel.iapi.BpelEngineException: Engine requested response
while the message exchange hqejbhcnphr400bemm923q was in the state ASYNC
	at org.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:464)
	at org.apache.ode.jacob.vpu.JacobVPU.execute(JacobVPU.java:139)
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl.execute(BpelRuntimeContextImpl.java:812)
	at
org.apache.ode.bpel.engine.BpelProcess.handleWorkEvent(BpelProcess.java:392)
	at
org.apache.ode.bpel.engine.BpelEngineImpl.onScheduledJob(BpelEngineImpl.java:328)
	at
org.apache.ode.bpel.engine.BpelServerImpl.onScheduledJob(BpelServerImpl.java:373)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:341)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4$1.call(SimpleScheduler.java:340)
	at
org.apache.ode.scheduler.simple.SimpleScheduler.execTransaction(SimpleScheduler.java:179)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:339)
	at
org.apache.ode.scheduler.simple.SimpleScheduler$4.call(SimpleScheduler.java:336)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
	at java.util.concurrent.FutureTask.run(FutureTask.java:123)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
	at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
	at java.lang.Thread.run(Thread.java:595)
Caused by: java.lang.RuntimeException:
org.apache.ode.bpel.iapi.BpelEngineException: Engine requested response
while the message exchange hqejbhcnphr400bemm923q was in the state ASYNC
	at org.apache.ode.bpel.runtime.INVOKE$1.onResponse(INVOKE.java:114)
	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.apache.ode.jacob.vpu.JacobVPU$JacobThreadImpl.run(JacobVPU.java:451)
	... 15 more
Caused by: org.apache.ode.bpel.iapi.BpelEngineException: Engine requested
response while the message exchange hqejbhcnphr400bemm923q was in the state
ASYNC
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl._getPartnerResponse(BpelRuntimeContextImpl.java:1181)
	at
org.apache.ode.bpel.engine.BpelRuntimeContextImpl.getPartnerResponse(BpelRuntimeContextImpl.java:1091)
	at org.apache.ode.bpel.runtime.INVOKE$1.onResponse(INVOKE.java:111)
	... 20 more

%-|
-- 
View this message in context: http://www.nabble.com/ode-exception-tp21704412p21704412.html
Sent from the Apache Ode Dev mailing list archive at Nabble.com.


Mime
View raw message