Eclipse Community Forums
Forum Search:

Search      Help    Register    Login    Home
Home » Eclipse Projects » EclipseLink » 2.0.0: where did the commit go?
2.0.0: where did the commit go? [message #507216] Tue, 12 January 2010 09:20 Go to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
When I execute a native query inside an Eclipse event, the commit is not executed.

This is the testcode:

public static void main(String[] args)
{
// EntityManager
EntityManagerFactory lEntityManagerFactory = ...
EntityManager lEntityManager = lEntityManagerFactory.createEntityManager();

// bind eclipse events to Batchtransferline
JpaEntityManager lJpaEntityManager = (JpaEntityManager)lEntityManager;
EclipselinkDescriptorEventListenerRouter lEclipselinkDescriptorEventListenerRouter = new EclipselinkDescriptorEventListenerRouter();
lJpaEntityManager.getSession().getClassDescriptor(Batchtrans ferline.class).getEventManager().addListener( lEclipselinkDescriptorEventListenerRouter );

// delete batchtransfer
try
{
Batchtransfer lBatchtransfer = lEntityManager.find(Batchtransfer.class, BigInteger.valueOf(15));
lEntityManager.getTransaction().begin();
Batchtransfer lMergedEntity = lEntityManager.merge(lBatchtransfer);
lEntityManager.remove( lMergedEntity ); // remove the merged entity
lEntityManager.getTransaction().commit();
}
catch (Throwable t)
{
t.printStackTrace(System.out);
System.out.println(">>> " + t.getMessage());
}

// close shop
lEntityManager.close();
lEntityManagerFactory.close();
}


This EclipselinkDescriptorEventListenerRouter class is just for redirecting the event to the entity.
I make sure all my entities have implemented EclipselinkEntityEventListener, so the it can do:

...
// delete
public void preDelete(DescriptorEvent descriptorEvent)
{
((EclipselinkEntityEventListener)descriptorEvent.getObject() ).preDelete();
}
public void aboutToDelete(DescriptorEvent descriptorEvent)
{
((EclipselinkEntityEventListener)descriptorEvent.getObject() ).aboutToDelete();
}
public void postDelete(DescriptorEvent descriptorEvent)
{
((EclipselinkEntityEventListener)descriptorEvent.getObject() ).postDelete();
}
...

The Batchtransferline then has:

@Override
public void postDelete()
{
Query lQuery = EntityManagerFinder.find().createNativeQuery("select sum(amount) from batchcount where to_amount_batchtransferlinenr=19");
lQuery.setFlushMode(FlushModeType.COMMIT); // make sure the query does not force the entity to be flushed to the DB
BigInteger lCurrentAmount = new BigInteger( EclipselinkUtil.getSingleResultOrDefault(lQuery, "0").toString() );
}

If the lines in the event method are commented out, I get:
...
[C1173553]: setAutoCommit( false )
...
[C1173553]: executeUpdate: DELETE FROM batchtransfer WHERE ((batchtransfernr = ?) AND (lazylock = ?))
[C1173553]: commit()
... stack trace
>>> Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
[EL Info]: 2010-01-12 15:08:41.206--ServerSession(30284778)--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user logout successful

This is correct! The record cannot be deleted, because there are references. This is what is supposed to be solved in the event logic.
Now if the three lines in the event method are active, I get:
...
[C14626104]: setAutoCommit( false )
...
[C14626104]: executeUpdate: DELETE FROM batchtransfer WHERE ((batchtransfernr = ?) AND (lazylock = ?))
[EL Info]: 2010-01-12 15:08:41.206--ServerSession(30284778)--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user logout successful

The delete statement is the last entry in the log; no close, no commit, no rollback... No exception! Because I still cannot delete the record!

How can only adding the native query cause this?

Tom
Re: 2.0.0: where did the commit go? [message #507565 is a reply to message #507216] Wed, 13 January 2010 15:42 Go to previous messageGo to next message
Peter Krogh is currently offline Peter Krogh
Messages: 8
Registered: July 2009
Junior Member
What do EntityManagerFinder.find() and EclipselinkUtil.getSingleResultOrDefault(..) do? Is it possible that that because of either of these utility methods, the EM that starts the transaction is the same as the one that finishes it?

if you find the EM and execute the query using straight JPA (ie - without your util classes...) do you you get the same issue?
Re: 2.0.0: where did the commit go? [message #507622 is a reply to message #507565] Thu, 14 January 2010 01:35 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
> What do EntityManagerFinder.find() and
> EclipselinkUtil.getSingleResultOrDefault(..) do? Is it possible that
> that because of either of these utility methods, the EM that starts the
> transaction is the same as the one that finishes it?
> if you find the EM and execute the query using straight JPA (ie -
> without your util classes...) do you you get the same issue?

They are simply wrappers;
The first finds the entity manager (which in this case is bound to the thread, I also have one which binds the EM to a JFrame). So all it does is find the EM and return it.
The second one wraps a getSingleResult call, catches the possible NoEntityFound exception and in that case returns the default value.
Neither is of any real influence on the execution.

At the moment I use an application event (a button press) to call the code that removes the references. But I would really prefer that this can be done from within a business model (persistency layer) event.
There is an thread about the same subject, but then using JPA events, about a year ago.
Point is: the event code itself is good. It runs, it is used in production. Issue is the location it is called from.
Re: 2.0.0: where did the commit go? [message #507731 is a reply to message #507622] Thu, 14 January 2010 08:58 Go to previous messageGo to next message
Chris Delahunt is currently offline Chris Delahunt
Messages: 1016
Registered: July 2009
Senior Member
Can you set the EclipseLink log level to FINEST and post the log? This will show the native query getting executed and other transactional information that might help show what is going on.

It is also odd that a DatabaseException is showing up in the logs but not the RollbackException - are you getting the RollbackException but just not showing it in the post?

Best Regards,
Chris
Re: 2.0.0: where did the commit go? [message #507746 is a reply to message #507731] Thu, 14 January 2010 09:24 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
On 14-1-2010 14:58, Chris Delahunt wrote:
> Can you set the EclipseLink log level to FINEST and post the log? This
> will show the native query getting executed and other transactional
> information that might help show what is going on.

With the event method:


java.vm.version=14.3-b01
IBM Informix JDBC Driver Version 3.50.JC3DE
log4j: Trying to find [log4j.xml] using context classloader sun.misc.Launcher$AppClassLoader@130c19b.
log4j: Using URL [file:/C:/Documents%20and%20Settings/User/My%20Documents/rei nders/voorraad/container/bm/build/log4j.xml] for automatic log4j configuration.
log4j: Preferred configurator class: org.apache.log4j.xml.DOMConfigurator
log4j: System property is :null
log4j: Standard DocumentBuilderFactory search succeded.
log4j: DocumentBuilderFactory is: org.apache.xerces.jaxp.DocumentBuilderFactoryImpl
log4j: debug attribute= "false".
2010-01-14 15:22:23,765 INFO nl.reinders.bm.BM.createEntityManagerFactory(BM.java:93) Creating EntityManagerFactory for user @ jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
[EL Info]: 2010-01-14 15:22:24.703--ServerSession(30149247)--Thread(Thread[main,5, main])--EclipseLink, version: Eclipse Persistence Services - 2.0.0.v20091127-r5931
[EL Config]: 2010-01-14 15:22:24.734--ServerSession(30149247)--Connection(23475212)- -Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-14 15:22:25.031--ServerSession(30149247)--Connection(24968504)- -Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Config]: 2010-01-14 15:22:25.031--ServerSession(30149247)--Connection(13926634)- -Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-14 15:22:25.078--ServerSession(30149247)--Connection(23953814)- -Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Config]: 2010-01-14 15:22:25.109--ServerSession(30149247)--Connection(2098848)-- Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-14 15:22:25.14--ServerSession(30149247)--Connection(26665270)-- Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Info]: 2010-01-14 15:22:25.562--ServerSession(30149247)--Thread(Thread[main,5, main])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user login successful
2010-01-14 15:22:25,781 INFO nl.knowledgeplaza.util.jpa.EntityManagerFinder.setEntityMana gerFinder(EntityManagerFinder.java:56) Registered an EntityManagerFinder: nl.knowledgeplaza.util.jpa.EntityManagerFinderSingleton@186dd93
[EL Fine]: 2010-01-14 15:22:25.812--ServerSession(30149247)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)
bind => [1]
[EL Fine]: 2010-01-14 15:22:26.031--ServerSession(30149247)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtransfernr, dwhmodified, lazylock, dwhby, enteredby_employeenr, reason, createdon FROM batchtransfer WHERE (batchtransfernr = ?)
bind => [18]
[EL Fine]: 2010-01-14 15:22:26.046--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtransferlinenr, to_amount, lazylock, to_batchtypenr, articlenr, reason, dwhmodified, batchtransfernr, from_batchtypenr, dwhby, from_amount FROM batchtransferline WHERE (batchtransfernr = ?)
bind => [18]
[EL Fine]: 2010-01-14 15:22:26.078--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [1]
[EL Fine]: 2010-01-14 15:22:26.078--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [2]
[EL Fine]: 2010-01-14 15:22:26.093--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--DELETE FROM batchtransferline WHERE ((batchtransferlinenr = ?) AND (lazylock = ?))
bind => [23, 1]
[EL Fine]: 2010-01-14 15:22:26.109--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--select sum(amount) from batchcount where to_amount_batchtransferlinenr=19
[EL Fine]: 2010-01-14 15:22:26.109--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--select sum(amount) from batchcount where to_amount_batchtransferlinenr=19
[EL Fine]: 2010-01-14 15:22:26.125--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--DELETE FROM batchtransfer WHERE ((batchtransfernr = ?) AND (lazylock = ?))
bind => [18, 1]
[EL Config]: 2010-01-14 15:22:26.125--ServerSession(30149247)--Connection(26665270)- -Thread(Thread[main,5,main])--disconnect
[EL Config]: 2010-01-14 15:22:26.14--ServerSession(30149247)--Connection(24968504)-- Thread(Thread[main,5,main])--disconnect
[EL Info]: 2010-01-14 15:22:26.14--ServerSession(30149247)--Thread(Thread[main,5,m ain])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user logout successful
[EL Config]: 2010-01-14 15:22:26.14--ServerSession(30149247)--Connection(23475212)-- Thread(Thread[main,5,main])--disconnect
[EL Config]: 2010-01-14 15:22:26.14--ServerSession(30149247)--Connection(23953814)-- Thread(Thread[main,5,main])--disconnect


> It is also odd that a DatabaseException is showing up in the logs but
> not the RollbackException - are you getting the RollbackException but
> just not showing it in the post?

Without the event method:


java.vm.version=14.3-b01
IBM Informix JDBC Driver Version 3.50.JC3DE
log4j: Trying to find [log4j.xml] using context classloader sun.misc.Launcher$AppClassLoader@130c19b.
log4j: Using URL [file:/C:/Documents%20and%20Settings/User/My%20Documents/rei nders/voorraad/container/bm/build/log4j.xml] for automatic log4j configuration.
log4j: Preferred configurator class: org.apache.log4j.xml.DOMConfigurator
log4j: System property is :null
log4j: Standard DocumentBuilderFactory search succeded.
log4j: DocumentBuilderFactory is: org.apache.xerces.jaxp.DocumentBuilderFactoryImpl
log4j: debug attribute= "false".
2010-01-14 15:23:23,156 INFO nl.reinders.bm.BM.createEntityManagerFactory(BM.java:93) Creating EntityManagerFactory for user @ jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
[EL Info]: 2010-01-14 15:23:24.0--ServerSession(30149247)--Thread(Thread[main,5,ma in])--EclipseLink, version: Eclipse Persistence Services - 2.0.0.v20091127-r5931
[EL Config]: 2010-01-14 15:23:24.015--ServerSession(30149247)--Connection(23475212)- -Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-14 15:23:24.25--ServerSession(30149247)--Connection(24968504)-- Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Config]: 2010-01-14 15:23:24.25--ServerSession(30149247)--Connection(13926634)-- Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-14 15:23:24.359--ServerSession(30149247)--Connection(23953814)- -Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Config]: 2010-01-14 15:23:24.375--ServerSession(30149247)--Connection(12893236)- -Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-14 15:23:24.421--ServerSession(30149247)--Connection(26665270)- -Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Info]: 2010-01-14 15:23:24.89--ServerSession(30149247)--Thread(Thread[main,5,m ain])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user login successful
2010-01-14 15:23:25,109 INFO nl.knowledgeplaza.util.jpa.EntityManagerFinder.setEntityMana gerFinder(EntityManagerFinder.java:56) Registered an EntityManagerFinder: nl.knowledgeplaza.util.jpa.EntityManagerFinderSingleton@186dd93
[EL Fine]: 2010-01-14 15:23:25.14--ServerSession(30149247)--Connection(23953814)-- Thread(Thread[main,5,main])--SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)
bind => [1]
[EL Fine]: 2010-01-14 15:23:25.265--ServerSession(30149247)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtransfernr, dwhmodified, lazylock, dwhby, enteredby_employeenr, reason, createdon FROM batchtransfer WHERE (batchtransfernr = ?)
bind => [18]
[EL Fine]: 2010-01-14 15:23:25.296--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtransferlinenr, to_amount, lazylock, to_batchtypenr, articlenr, reason, dwhmodified, batchtransfernr, from_batchtypenr, dwhby, from_amount FROM batchtransferline WHERE (batchtransfernr = ?)
bind => [18]
[EL Fine]: 2010-01-14 15:23:25.296--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [1]
[EL Fine]: 2010-01-14 15:23:25.296--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [2]
[EL Fine]: 2010-01-14 15:23:25.296--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--DELETE FROM batchtransferline WHERE ((batchtransferlinenr = ?) AND (lazylock = ?))
bind => [23, 1]
[EL Fine]: 2010-01-14 15:23:25.312--ClientSession(28011548)--Connection(23953814)- -Thread(Thread[main,5,main])--DELETE FROM batchtransfer WHERE ((batchtransfernr = ?) AND (lazylock = ?))
bind => [18, 1]
2010-01-14 15:23:25,359 INFO nl.knowledgeplaza.util.jdbc.Connection.invoke(Connection.jav a:148) #22852149[C22852149]: commit: java.lang.reflect.InvocationTargetException->java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
2010-01-14 15:23:25,406 INFO nl.reinders.jdbc.ReindersInformixConnection.invoke(ReindersI nformixConnection.java:70) commit: java.lang.reflect.InvocationTargetException->java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
[EL Warning]: 2010-01-14 15:23:25.406--ClientSession(28011548)--Thread(Thread[main,5, main])--Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
javax.persistence.RollbackException: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commitInternal(EntityTransactionImpl.java:102)
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commit(EntityTransactionImpl.java:63)
at nl.reinders.bm.BMTestToplink.main(BMTestToplink.java:439)
Caused by: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
at org.eclipse.persistence.exceptions.DatabaseException.sqlExce ption(DatabaseException.java:324)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.basicCommitTransaction(DatabaseAccessor.java:420)
at org.eclipse.persistence.internal.databaseaccess.DatasourceAc cessor.commitTransaction(DatasourceAccessor.java:386)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.commitTransaction(DatabaseAccessor.java:403)
at org.eclipse.persistence.internal.sessions.AbstractSession.ba sicCommitTransaction(AbstractSession.java:503)
at org.eclipse.persistence.sessions.server.ClientSession.basicC ommitTransaction(ClientSession.java:155)
at org.eclipse.persistence.internal.sessions.AbstractSession.co mmitTransaction(AbstractSession.java:685)
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitTransaction(UnitOfWorkImpl.java:1565)
at org.eclipse.persistence.internal.sessions.RepeatableWriteUni tOfWork.commitTransaction(RepeatableWriteUnitOfWork.java:560 )
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitTransactionAfterWriteChanges(UnitOfWorkImpl.java:1588)
at org.eclipse.persistence.internal.sessions.RepeatableWriteUni tOfWork.commitRootUnitOfWork(RepeatableWriteUnitOfWork.java: 207)
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitAndResume(UnitOfWorkImpl.java:1131)
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commitInternal(EntityTransactionImpl.java:84)
... 2 more
Caused by: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
at com.informix.jdbc.IfxSqli.a(IfxSqli.java:3449)
at com.informix.jdbc.IfxSqli.E(IfxSqli.java:3762)
at com.informix.jdbc.IfxSqli.dispatchMsg(IfxSqli.java:2574)
at com.informix.jdbc.IfxSqli.receiveMessage(IfxSqli.java:2490)
at com.informix.jdbc.IfxSqli.executeCommit(IfxSqli.java:497)
at com.informix.jdbc.IfxSqliConnect.commit(IfxSqliConnect.java: 1964)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAcce ssorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at nl.knowledgeplaza.util.jdbc.Connection.invoke(Connection.jav a:112)
at $Proxy0.commit(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAcce ssorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at nl.reinders.jdbc.ReindersInformixConnection.invoke(ReindersI nformixConnection.java:66)
at $Proxy2.commit(Unknown Source)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.basicCommitTransaction(DatabaseAccessor.java:412)
... 13 more
Caused by: java.sql.SQLException
at com.informix.util.IfxErrMsg.getSQLException(IfxErrMsg.java:4 07)
at com.informix.jdbc.IfxSqli.E(IfxSqli.java:3767)
... 30 more
>>> Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
[EL Config]: 2010-01-14 15:23:25.421--ServerSession(30149247)--Connection(26665270)- -Thread(Thread[main,5,main])--disconnect
[EL Config]: 2010-01-14 15:23:25.421--ServerSession(30149247)--Connection(24968504)- -Thread(Thread[main,5,main])--disconnect
[EL Info]: 2010-01-14 15:23:25.421--ServerSession(30149247)--Thread(Thread[main,5, main])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user logout successful
[EL Config]: 2010-01-14 15:23:25.421--ServerSession(30149247)--Connection(23475212)- -Thread(Thread[main,5,main])--disconnect
[EL Config]: 2010-01-14 15:23:25.421--ServerSession(30149247)--Connection(23953814)- -Thread(Thread[main,5,main])--disconnect
Re: 2.0.0: where did the commit go? [message #508027 is a reply to message #507746] Fri, 15 January 2010 10:34 Go to previous messageGo to next message
Chris Delahunt is currently offline Chris Delahunt
Messages: 1016
Registered: July 2009
Senior Member
Hello,

The log is missing messages that would be logged under the FINER and FINEST settins, so I assume it was set to FINE? Can you post the log with it set to FINEST - as mentioned, FINEST will show messages detailing EclipseLink's interaction with the transaction.

Also, you mentioned that EclipselinkUtil.getSingleResultOrDefault will catch exceptions and return the default value - could it be catching and ignoring unexpected exceptions too? It would be good if you could change it to log the exception - the exception could cause the transaction to rollback which is why you do not see the commit occur.

Best Regards,
Chris
Re: 2.0.0: where did the commit go? [message #508039 is a reply to message #508027] Fri, 15 January 2010 10:56 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
On 2010-01-15 16:34, Chris Delahunt wrote:
> Hello,
>
> The log is missing messages that would be logged under the FINER and
> FINEST settins, so I assume it was set to FINE? Can you post the log
> with it set to FINEST - as mentioned, FINEST will show messages
> detailing EclipseLink's interaction with the transaction.
> Also, you mentioned that EclipselinkUtil.getSingleResultOrDefault will
> catch exceptions and return the default value - could it be catching and
> ignoring unexpected exceptions too? It would be good if you could change
> it to log the exception - the exception could cause the transaction to
> rollback which is why you do not see the commit occur.
> Best Regards,
> Chris

Owwwww. Ok. Sorry. Attempt #2.

BTW, I updated the event code to this:

@Override
public void postDelete()
{
Query lQuery = EntityManagerFinder.find().createNativeQuery("select sum(amount) from batchcount where to_amount_batchtransferlinenr=19");
lQuery.setFlushMode(FlushModeType.COMMIT); // make sure the query does not force the entity to be flushed to the DB
lQuery.getSingleResult();
}

So the getSingleResultOrDefault is out of the equation.

Without event code:

java.vm.version=14.3-b01
IBM Informix JDBC Driver Version 3.50.JC3DE
log4j: Trying to find [log4j.xml] using context classloader sun.misc.Launcher$AppClassLoader@130c19b.
log4j: Using URL [file:/C:/Documents%20and%20Settings/User/My%20Documents/rei nders/voorraad/container/bm/build/log4j.xml] for automatic log4j configuration.
log4j: Preferred configurator class: org.apache.log4j.xml.DOMConfigurator
log4j: System property is :null
log4j: Standard DocumentBuilderFactory search succeded.
log4j: DocumentBuilderFactory is: org.apache.xerces.jaxp.DocumentBuilderFactoryImpl
log4j: debug attribute= "false".
2010-01-15 16:55:07,937 INFO nl.reinders.bm.BM.createEntityManagerFactory(BM.java:93) Creating EntityManagerFactory for user @ jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
[EL Finest]: 2010-01-15 16:55:08.125--ServerSession(14008569)--Thread(Thread[main,5, main])--property=javax.persistence.jdbc.user; value=user
[EL Finest]: 2010-01-15 16:55:08.156--ServerSession(14008569)--Thread(Thread[main,5, main])--property=javax.persistence.jdbc.password; value=xxxxxx
[EL Finest]: 2010-01-15 16:55:08.859--ServerSession(14008569)--Thread(Thread[main,5, main])--property=eclipselink.target-database; value=nl.reinders.jdbc.InformixPlatform
[EL Finest]: 2010-01-15 16:55:08.859--ServerSession(14008569)--Thread(Thread[main,5, main])--property=javax.persistence.jdbc.driver; value=nl.reinders.jdbc.ReindersInformixAndLoggingDriver
[EL Finest]: 2010-01-15 16:55:08.859--ServerSession(14008569)--Thread(Thread[main,5, main])--property=javax.persistence.jdbc.url; value= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
[EL Finest]: 2010-01-15 16:55:08.859--ServerSession(14008569)--Thread(Thread[main,5, main])--property=eclipselink.jdbc.sequence-connection-pool; value=true
[EL Finest]: 2010-01-15 16:55:08.875--ServerSession(14008569)--Thread(Thread[main,5, main])--property=eclipselink.cache.shared.default; value=false
[EL Finest]: 2010-01-15 16:55:08.875--ServerSession(14008569)--Thread(Thread[main,5, main])--property=eclipselink.allow-zero-id; value=true
[EL Info]: 2010-01-15 16:55:08.875--ServerSession(14008569)--Thread(Thread[main,5, main])--EclipseLink, version: Eclipse Persistence Services - 2.0.0.v20091127-r5931
[EL Config]: 2010-01-15 16:55:08.89--ServerSession(14008569)--Connection(17605128)-- Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-15 16:55:09.14--ServerSession(14008569)--Connection(23953814)-- Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Config]: 2010-01-15 16:55:09.14--ServerSession(14008569)--Connection(5683514)--T hread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-15 16:55:09.203--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Config]: 2010-01-15 16:55:09.218--ServerSession(14008569)--Connection(10202458)- -Thread(Thread[main,5,main])--connecting(DatabaseLogin(
platform=>InformixPlatform
user name=> "user"
datasource URL=> " jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8"
))
[EL Config]: 2010-01-15 16:55:09.25--ServerSession(14008569)--Connection(9703298)--T hread(Thread[main,5,main])--Connected: jdbc:informix-sqli://toeu_reinders:9088/reinders:INFORMIXSER VER=ol_ids_1150_1;DB_LOCALE=en_us.utf8
User: user
Database: Informix Dynamic Server Version: 11.50.TC3
Driver: IBM Informix JDBC Driver for IBM Informix Dynamic Server Version: 3.50.JC3DE
[EL Finest]: 2010-01-15 16:55:09.25--ServerSession(14008569)--Thread(Thread[main,5,m ain])--sequencing connected, state is Preallocation_Transaction_Accessor_State
[EL Finest]: 2010-01-15 16:55:09.25--ServerSession(14008569)--Thread(Thread[main,5,m ain])--sequence oidnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.25--ServerSession(14008569)--Thread(Thread[main,5,m ain])--sequence webordersitemsnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.25--ServerSession(14008569)--Thread(Thread[main,5,m ain])--sequence countrycodenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence standassignnr: preallocation size 10
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence reservationnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationaliasnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence calendarnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence standareanr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationframenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchtransferlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence contractnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence categorynr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence rollentodolinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_paymentenginenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence contacttelnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence currencynr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence buyorderstatusnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationpricerulenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence contactnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationsalenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence articletypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationgroupnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence standtypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sellexpectnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence id: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence taskprioritynr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationversionnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license2countrynr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence groupnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence countrycode2countrygroupnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_paymentnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationstandcatnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence producttypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence contractpaymentnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationgroupstandnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sellorderlinenr: preallocation size 25
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence edifactordersnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence catalogentrynr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationframecompositionnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence standversionnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationstocknr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchbuyordlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence retourlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence taskfollowupnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence guid: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence countrygroupnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_article_nr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence webordersnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence rollendonenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence standmaterialnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchtypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence messagenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence rollentodonr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sellexpectlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence taskstatusnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationtelnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationstandtoframenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence timeframenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchcreditnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_articletypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence task_articlenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchtransfernr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_payment_detailnr: preallocation size 25
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence rollendonelinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationstandtypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence catalognr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence buyordernr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence edifactordersitemsnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence tablename: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence type: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence licensenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sellordernr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence btwnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence standnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sellorderrangenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence eanrangenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_payment_printednr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence permissionnodenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence pdaordernr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationgroupeannr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license_payment_typenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.265--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence taskarticlestatusnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence taskarticlekeywordnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence pdaorderlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence contractpaymentlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence articlenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sleevestypenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence sellorderline_retournr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationstand2catnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence retournr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence taskprojectnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence articlekeywordnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence tasknr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence buyorderlinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence storagenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence relationstandnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchselllinenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence translationnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence employeenr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence addressnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence deliverynr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence article2articlekeywordnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence license2countrygroupnr: preallocation size 1
[EL Finest]: 2010-01-15 16:55:09.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequence batchcountnr: preallocation size 1
[EL Info]: 2010-01-15 16:55:09.687--ServerSession(14008569)--Thread(Thread[main,5, main])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user login successful
[EL Finer]: 2010-01-15 16:55:09.75--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.765--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.781--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.796--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.812--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.828--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.843--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.843--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.843--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.843--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.859--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.875--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.89--ServerSession(14008569)--Thread(Thread[main,5,m ain])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during initialization.
[EL Finer]: 2010-01-15 16:55:09.906--ServerSession(14008569)--Thread(Thread[main,5, main])--No Canonical Metamodel classes found during init
Re: 2.0.0: where did the commit go? [message #508643 is a reply to message #508039] Tue, 19 January 2010 12:21 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
Let me "rewarm" this thread by adding a new post; I'm still very keen on getting these events out of my application layer and into the BM.


> Owwwww. Ok. Sorry. Attempt #2.
Re: 2.0.0: where did the commit go? [message #508833 is a reply to message #508643] Wed, 20 January 2010 09:03 Go to previous messageGo to next message
Chris Delahunt is currently offline Chris Delahunt
Messages: 1016
Registered: July 2009
Senior Member
hello,

Can you post the tail of the log ending with the exception? The log you posted got truncated, and ends with numerous "No Canonical Metamodel classes found during initialization" (which are due to bug 297927).

Regards,
Chris
Re: 2.0.0: where did the commit go? [message #508862 is a reply to message #508833] Wed, 20 January 2010 05:14 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
On 2010-01-20 15:03, Chris Delahunt wrote:
> hello,
>
> Can you post the tail of the log ending with the exception? The log you
> posted got truncated, and ends with numerous "No Canonical Metamodel
> classes found during initialization" (which are due to bug 297927).
> Regards,
> Chris

I can see the exception in my previous post, but here is the tail of both (copied from my previous post):

Without event code:

....
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 3753755
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 7028679
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 19417347
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 30502607
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 12704779
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 22379127
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 14371981
2010-01-15 16:55:10,031 INFO nl.knowledgeplaza.util.jpa.EntityManagerFinder.setEntityMana gerFinder(EntityManagerFinder.java:56) Registered an EntityManagerFinder: nl.knowledgeplaza.util.jpa.EntityManagerFinderSingleton@8b1323
[EL Finer]: 2010-01-15 16:55:10.031--ServerSession(14008569)--Thread(Thread[main,5, main])--client acquired
[EL Finest]: 2010-01-15 16:55:10.062--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(referenceClass=Employee sql="SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)")
[EL Fine]: 2010-01-15 16:55:10.062--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)
bind => [1]
[EL Finest]: 2010-01-15 16:55:10.171--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(referenceClass=Batchtransfer sql="SELECT batchtransfernr, dwhmodified, lazylock, dwhby, enteredby_employeenr, reason, createdon FROM batchtransfer WHERE (batchtransfernr = ?)")
[EL Fine]: 2010-01-15 16:55:10.171--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--SELECT batchtransfernr, dwhmodified, lazylock, dwhby, enteredby_employeenr, reason, createdon FROM batchtransfer WHERE (batchtransfernr = ?)
bind => [18]
[EL Finest]: 2010-01-15 16:55:10.171--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(referenceClass=Employee sql="SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)")
[EL Finer]: 2010-01-15 16:55:10.171--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--begin transaction
[EL Finest]: 2010-01-15 16:55:10.187--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Merge clone with references nl.reinders.bm.Batchtransfer@11c248a&Batchtransfernr=18
[EL Finest]: 2010-01-15 16:55:10.187--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--The remove operation has been performed on: nl.reinders.bm.Batchtransfer@11c248a&Batchtransfernr=18
[EL Finest]: 2010-01-15 16:55:10.187--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadAllQuery(name="iBatchtransferlinesWhereIAmBatchtransfer " referenceClass=Batchtransferline )
[EL Fine]: 2010-01-15 16:55:10.187--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--SELECT batchtransferlinenr, to_amount, lazylock, to_batchtypenr, articlenr, reason, dwhmodified, batchtransfernr, from_batchtypenr, dwhby, from_amount FROM batchtransferline WHERE (batchtransfernr = ?)
bind => [18]
[EL Finest]: 2010-01-15 16:55:10.187--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(name="iFromBatchtype" referenceClass=Batchtype )
[EL Fine]: 2010-01-15 16:55:10.187--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [1]
[EL Finest]: 2010-01-15 16:55:10.203--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(name="iToBatchtype" referenceClass=Batchtype )
[EL Fine]: 2010-01-15 16:55:10.203--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [2]
[EL Finest]: 2010-01-15 16:55:10.203--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--The remove operation has been performed on: nl.reinders.bm.Batchtransferline@cf7fd0&Batchtransferlinenr=23
[EL Finer]: 2010-01-15 16:55:10.203--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--begin unit of work commit
[EL Finest]: 2010-01-15 16:55:10.218--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query DeleteObjectQuery(nl.reinders.bm.Batchtransferline@cf7fd0&Batchtransferlinenr=23)
[EL Fine]: 2010-01-15 16:55:10.218--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--DELETE FROM batchtransferline WHERE ((batchtransferlinenr = ?) AND (lazylock = ?))
bind => [23, 1]
[EL Finest]: 2010-01-15 16:55:10.218--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query DeleteObjectQuery(nl.reinders.bm.Batchtransfer@11c248a&Batchtransfernr=18)
[EL Fine]: 2010-01-15 16:55:10.218--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--DELETE FROM batchtransfer WHERE ((batchtransfernr = ?) AND (lazylock = ?))
bind => [18, 1]
[EL Finer]: 2010-01-15 16:55:10.218--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--commit transaction
2010-01-15 16:55:10,234 INFO nl.knowledgeplaza.util.jdbc.Connection.invoke(Connection.jav a:148) #3620408[C3620408]: commit: java.lang.reflect.InvocationTargetException->java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
2010-01-15 16:55:10,234 INFO nl.reinders.jdbc.ReindersInformixConnection.invoke(ReindersI nformixConnection.java:70) commit: java.lang.reflect.InvocationTargetException->java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
[EL Warning]: 2010-01-15 16:55:10.234--ClientSession(6767312)--Thread(Thread[main,5,m ain])--Local Exception Stack:
Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
at org.eclipse.persistence.exceptions.DatabaseException.sqlExce ption(DatabaseException.java:324)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.basicCommitTransaction(DatabaseAccessor.java:420)
at org.eclipse.persistence.internal.databaseaccess.DatasourceAc cessor.commitTransaction(DatasourceAccessor.java:386)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.commitTransaction(DatabaseAccessor.java:403)
at org.eclipse.persistence.internal.sessions.AbstractSession.ba sicCommitTransaction(AbstractSession.java:503)
at org.eclipse.persistence.sessions.server.ClientSession.basicC ommitTransaction(ClientSession.java:155)
at org.eclipse.persistence.internal.sessions.AbstractSession.co mmitTransaction(AbstractSession.java:685)
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitTransaction(UnitOfWorkImpl.java:1565)
at org.eclipse.persistence.internal.sessions.RepeatableWriteUni tOfWork.commitTransaction(RepeatableWriteUnitOfWork.java:560 )
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitTransactionAfterWriteChanges(UnitOfWorkImpl.java:1588)
at org.eclipse.persistence.internal.sessions.RepeatableWriteUni tOfWork.commitRootUnitOfWork(RepeatableWriteUnitOfWork.java: 207)
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitAndResume(UnitOfWorkImpl.java:1131)
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commitInternal(EntityTransactionImpl.java:84)
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commit(EntityTransactionImpl.java:63)
at nl.reinders.bm.BMTestToplink.main(BMTestToplink.java:434)
Caused by: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
at com.informix.jdbc.IfxSqli.a(IfxSqli.java:3449)
at com.informix.jdbc.IfxSqli.E(IfxSqli.java:3762)
at com.informix.jdbc.IfxSqli.dispatchMsg(IfxSqli.java:2574)
at com.informix.jdbc.IfxSqli.receiveMessage(IfxSqli.java:2490)
at com.informix.jdbc.IfxSqli.executeCommit(IfxSqli.java:497)
at com.informix.jdbc.IfxSqliConnect.commit(IfxSqliConnect.java: 1964)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAcce ssorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at nl.knowledgeplaza.util.jdbc.Connection.invoke(Connection.jav a:112)
at $Proxy0.commit(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAcce ssorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at nl.reinders.jdbc.ReindersInformixConnection.invoke(ReindersI nformixConnection.java:66)
at $Proxy2.commit(Unknown Source)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.basicCommitTransaction(DatabaseAccessor.java:412)
... 13 more
Caused by: java.sql.SQLException
at com.informix.util.IfxErrMsg.getSQLException(IfxErrMsg.java:4 07)
at com.informix.jdbc.IfxSqli.E(IfxSqli.java:3767)
... 30 more

[EL Finer]: 2010-01-15 16:55:10.25--ClientSession(6767312)--Connection(5910186)--Th read(Thread[main,5,main])--rollback transaction
[EL Finer]: 2010-01-15 16:55:10.25--UnitOfWork(23747954)--Thread(Thread[main,5,main ])--release unit of work
[EL Finer]: 2010-01-15 16:55:10.25--UnitOfWork(23747954)--Thread(Thread[main,5,main ])--release unit of work
[EL Finer]: 2010-01-15 16:55:10.25--ClientSession(6767312)--Thread(Thread[main,5,ma in])--client released
javax.persistence.RollbackException: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commitInternal(EntityTransactionImpl.java:102)
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commit(EntityTransactionImpl.java:63)
at nl.reinders.bm.BMTestToplink.main(BMTestToplink.java:434)
Caused by: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
at org.eclipse.persistence.exceptions.DatabaseException.sqlExce ption(DatabaseException.java:324)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.basicCommitTransaction(DatabaseAccessor.java:420)
at org.eclipse.persistence.internal.databaseaccess.DatasourceAc cessor.commitTransaction(DatasourceAccessor.java:386)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.commitTransaction(DatabaseAccessor.java:403)
at org.eclipse.persistence.internal.sessions.AbstractSession.ba sicCommitTransaction(AbstractSession.java:503)
at org.eclipse.persistence.sessions.server.ClientSession.basicC ommitTransaction(ClientSession.java:155)
at org.eclipse.persistence.internal.sessions.AbstractSession.co mmitTransaction(AbstractSession.java:685)
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitTransaction(UnitOfWorkImpl.java:1565)
at org.eclipse.persistence.internal.sessions.RepeatableWriteUni tOfWork.commitTransaction(RepeatableWriteUnitOfWork.java:560 )
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitTransactionAfterWriteChanges(UnitOfWorkImpl.java:1588)
at org.eclipse.persistence.internal.sessions.RepeatableWriteUni tOfWork.commitRootUnitOfWork(RepeatableWriteUnitOfWork.java: 207)
at org.eclipse.persistence.internal.sessions.UnitOfWorkImpl.com mitAndResume(UnitOfWorkImpl.java:1131)
at org.eclipse.persistence.internal.jpa.transaction.EntityTrans actionImpl.commitInternal(EntityTransactionImpl.java:84)
... 2 more
Caused by: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
at com.informix.jdbc.IfxSqli.a(IfxSqli.java:3449)
at com.informix.jdbc.IfxSqli.E(IfxSqli.java:3762)
at com.informix.jdbc.IfxSqli.dispatchMsg(IfxSqli.java:2574)
at com.informix.jdbc.IfxSqli.receiveMessage(IfxSqli.java:2490)
at com.informix.jdbc.IfxSqli.executeCommit(IfxSqli.java:497)
at com.informix.jdbc.IfxSqliConnect.commit(IfxSqliConnect.java: 1964)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAcce ssorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at nl.knowledgeplaza.util.jdbc.Connection.invoke(Connection.jav a:112)
at $Proxy0.commit(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAcce ssorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMe thodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at nl.reinders.jdbc.ReindersInformixConnection.invoke(ReindersI nformixConnection.java:66)
at $Proxy2.commit(Unknown Source)
at org.eclipse.persistence.internal.databaseaccess.DatabaseAcce ssor.basicCommitTransaction(DatabaseAccessor.java:412)
... 13 more
Caused by: java.sql.SQLException
at com.informix.util.IfxErrMsg.getSQLException(IfxErrMsg.java:4 07)
at com.informix.jdbc.IfxSqli.E(IfxSqli.java:3767)
... 30 more
>>> Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.0.0.v20091127-r5931): org.eclipse.persistence.exceptions.DatabaseException
Internal Exception: java.sql.SQLException: Key value for constraint (informix.u208_344) is still being referenced.
Error Code: -692
[EL Finest]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Thread(Thread[main,5, main])--Begin undeploying Persistence Unit reinders; session reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user; state Deployed; factoryCount 1
[EL Config]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Connection(9703298)-- Thread(Thread[main,5,main])--disconnect
[EL Finest]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Thread(Thread[main,5, main])--sequencing disconnected
[EL Config]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Connection(23953814)- -Thread(Thread[main,5,main])--disconnect
[EL Finer]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Thread(Thread[main,5, main])--initialize identitymaps
[EL Info]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Thread(Thread[main,5, main])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user logout successful
[EL Config]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Connection(17605128)- -Thread(Thread[main,5,main])--disconnect
[EL Config]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--disconnect
[EL Finest]: 2010-01-15 16:55:10.281--ServerSession(14008569)--Thread(Thread[main,5, main])--End undeploying Persistence Unit reinders; session reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user; state Undeployed; factoryCount



============================================================ ============================================================ ===============================


With event code:

....
[EL Finer]: 2010-01-15 16:52:31.765--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 19417347
[EL Finer]: 2010-01-15 16:52:31.765--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 30502607
[EL Finer]: 2010-01-15 16:52:31.765--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 12704779
[EL Finer]: 2010-01-15 16:52:31.765--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 22379127
[EL Finer]: 2010-01-15 16:52:31.765--ServerSession(14008569)--Thread(Thread[main,5, main])--acquire unit of work: 14371981
2010-01-15 16:52:31,765 INFO nl.knowledgeplaza.util.jpa.EntityManagerFinder.setEntityMana gerFinder(EntityManagerFinder.java:56) Registered an EntityManagerFinder: nl.knowledgeplaza.util.jpa.EntityManagerFinderSingleton@8b1323
[EL Finer]: 2010-01-15 16:52:31.765--ServerSession(14008569)--Thread(Thread[main,5, main])--client acquired
[EL Finest]: 2010-01-15 16:52:31.796--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(referenceClass=Employee sql="SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)")
[EL Fine]: 2010-01-15 16:52:31.796--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)
bind => [1]
[EL Finest]: 2010-01-15 16:52:31.937--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(referenceClass=Batchtransfer sql="SELECT batchtransfernr, dwhmodified, lazylock, dwhby, enteredby_employeenr, reason, createdon FROM batchtransfer WHERE (batchtransfernr = ?)")
[EL Fine]: 2010-01-15 16:52:31.937--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--SELECT batchtransfernr, dwhmodified, lazylock, dwhby, enteredby_employeenr, reason, createdon FROM batchtransfer WHERE (batchtransfernr = ?)
bind => [18]
[EL Finest]: 2010-01-15 16:52:31.937--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(referenceClass=Employee sql="SELECT employeenr, dwhmodified, lazylock, dwhby, name FROM employee WHERE (employeenr = ?)")
[EL Finer]: 2010-01-15 16:52:31.953--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--begin transaction
[EL Finest]: 2010-01-15 16:52:31.953--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Merge clone with references nl.reinders.bm.Batchtransfer@11c248a&Batchtransfernr=18
[EL Finest]: 2010-01-15 16:52:31.953--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--The remove operation has been performed on: nl.reinders.bm.Batchtransfer@11c248a&Batchtransfernr=18
[EL Finest]: 2010-01-15 16:52:31.953--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadAllQuery(name="iBatchtransferlinesWhereIAmBatchtransfer " referenceClass=Batchtransferline )
[EL Fine]: 2010-01-15 16:52:31.953--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--SELECT batchtransferlinenr, to_amount, lazylock, to_batchtypenr, articlenr, reason, dwhmodified, batchtransfernr, from_batchtypenr, dwhby, from_amount FROM batchtransferline WHERE (batchtransfernr = ?)
bind => [18]
[EL Finest]: 2010-01-15 16:52:31.968--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(name="iFromBatchtype" referenceClass=Batchtype )
[EL Fine]: 2010-01-15 16:52:31.968--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [1]
[EL Finest]: 2010-01-15 16:52:31.968--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query ReadObjectQuery(name="iToBatchtype" referenceClass=Batchtype )
[EL Fine]: 2010-01-15 16:52:31.968--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--SELECT batchtypenr, lazylock, dwhmodified, dwhby, pricerulecode, description FROM batchtype WHERE (batchtypenr = ?)
bind => [2]
[EL Finest]: 2010-01-15 16:52:31.968--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--The remove operation has been performed on: nl.reinders.bm.Batchtransferline@cf7fd0&Batchtransferlinenr=23
[EL Finer]: 2010-01-15 16:52:31.968--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--begin unit of work commit
[EL Finest]: 2010-01-15 16:52:31.984--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query DeleteObjectQuery(nl.reinders.bm.Batchtransferline@cf7fd0&Batchtransferlinenr=23)
[EL Fine]: 2010-01-15 16:52:31.984--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--DELETE FROM batchtransferline WHERE ((batchtransferlinenr = ?) AND (lazylock = ?))
bind => [23, 1]
[EL Finest]: 2010-01-15 16:52:32.0--UnitOfWork(23747954)--Thread(Thread[main,5,main] )--Execute query DataReadQuery(sql="select sum(amount) from batchcount where to_amount_batchtransferlinenr=19")
[EL Fine]: 2010-01-15 16:52:32.031--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--select sum(amount) from batchcount where to_amount_batchtransferlinenr=19
[EL Finest]: 2010-01-15 16:52:32.125--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--No data:02000 - null
[EL Finest]: 2010-01-15 16:52:32.125--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--Execute query DeleteObjectQuery(nl.reinders.bm.Batchtransfer@11c248a&Batchtransfernr=18)
[EL Fine]: 2010-01-15 16:52:32.125--ClientSession(6767312)--Connection(5910186)--T hread(Thread[main,5,main])--DELETE FROM batchtransfer WHERE ((batchtransfernr = ?) AND (lazylock = ?))
bind => [18, 1]
[EL Finer]: 2010-01-15 16:52:32.171--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--end unit of work commit
[EL Finer]: 2010-01-15 16:52:32.171--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--resume unit of work
[EL Finer]: 2010-01-15 16:52:32.171--UnitOfWork(23747954)--Thread(Thread[main,5,mai n])--release unit of work
[EL Finer]: 2010-01-15 16:52:32.171--ClientSession(6767312)--Thread(Thread[main,5,m ain])--client released
[EL Finest]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Thread(Thread[main,5, main])--Begin undeploying Persistence Unit reinders; session reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user; state Deployed; factoryCount 1
[EL Config]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Connection(9703298)-- Thread(Thread[main,5,main])--disconnect
[EL Finest]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Thread(Thread[main,5, main])--sequencing disconnected
[EL Config]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Connection(23953814)- -Thread(Thread[main,5,main])--disconnect
[EL Finer]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Thread(Thread[main,5, main])--initialize identitymaps
[EL Info]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Thread(Thread[main,5, main])--reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user logout successful
[EL Config]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Connection(17605128)- -Thread(Thread[main,5,main])--disconnect
[EL Config]: 2010-01-15 16:52:32.171--ServerSession(14008569)--Connection(5910186)-- Thread(Thread[main,5,main])--disconnect
[EL Finest]: 2010-01-15 16:52:32.203--ServerSession(14008569)--Thread(Thread[main,5, main])--End undeploying Persistence Unit reinders; session reinders_url= jdbc:reinders:com.informix.jdbc.IfxDriver#jdbc:informix-sqli ://toeu_reinders:9088/reinders:INFORMIXSERVER=ol_ids_1150_1;DB_LOCALE=en_us.utf8_user=user; state Undeployed; factoryCount 0
Re: 2.0.0: where did the commit go? [message #509299 is a reply to message #508862] Thu, 21 January 2010 16:32 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
> here is the tail of both (copied from my previous post)

Let me "rewarm" this thread by adding a new post; I'm still very keen on getting these events out of my application layer and into the BM.
Re: 2.0.0: where did the commit go? [message #509868 is a reply to message #507216] Mon, 25 January 2010 11:10 Go to previous messageGo to next message
James Sutherland is currently offline James Sutherland
Messages: 1939
Registered: July 2009
Location: Ottawa, Canada
Senior Member

I can't see how the commit could be missed, but from your logs the commit on the transaction is never being called, so the commit does not trigger the constraint error.

I noticed in your log 5 acquire uow being called, this seems odd?

Also the beginTransaction call is early, how are you configuring this? What happens if you do not begin the transaction early?

The missing commit definitely seems odd though. If you can make a simple test to reproduce this, please log a bug.


James : Wiki : Book : Blog : Twitter
Re: 2.0.0: where did the commit go? [message #510055 is a reply to message #509868] Tue, 26 January 2010 06:31 Go to previous messageGo to next message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
On 25-1-2010 17:10, James wrote:
> I noticed in your log 5 acquire uow being called, this seems odd?

The test code just uses JPA code; find, begin, merge, remove, commit. All UOW related things are done by Eclipselink.


> Also the beginTransaction call is early, how are you configuring this?
> What happens if you do not begin the transaction early?

I don't understand how I could be beginning the transaction early, this is the core code:

Batchtransfer lBatchtransfer = lEntityManager.find(Batchtransfer.class, BigInteger.valueOf(18));
lEntityManager.getTransaction().begin();
Batchtransfer lMergedEntity = lEntityManager.merge(lBatchtransfer);
lEntityManager.remove( lMergedEntity ); // remove the merged entity
lEntityManager.getTransaction().commit();

What does happen is that a temporarily entity manager is used to bind the eclipse events with, but it is closed immediately.


> The missing commit definitely seems odd though. If you can make a simple
> test to reproduce this, please log a bug.

I reduced the scenario to two entities, one test class, and two classes to hook-in the "set constraints all deferred" (maybe there is a better way to do it?) and filed it under bug 300788.

Reproducing it will depend on the deferred constraints; in normal operation the process already aborts in the sql delete, since the constraint intercepts that immediately and execution never reaches the postDelete.

Tom
Re: 2.0.0: where did the commit go? [message #510756 is a reply to message #510055] Thu, 28 January 2010 09:48 Go to previous messageGo to next message
James Sutherland is currently offline James Sutherland
Messages: 1939
Registered: July 2009
Location: Ottawa, Canada
Senior Member

>> temporarily entity manager

This is probably what is causing the extra acquire units of work, not sure if this is related to your problem.

What are you persistence.xml setting?


James : Wiki : Book : Blog : Twitter
Re: 2.0.0: where did the commit go? [message #510766 is a reply to message #510756] Thu, 28 January 2010 10:06 Go to previous message
Tom Eugelink is currently offline Tom Eugelink
Messages: 806
Registered: July 2009
Senior Member
> This is probably what is causing the extra acquire units of work, not
> sure if this is related to your problem.

I moved it there after Chris pointed out that the original setup code could be executed more than once. The problem already exited then, so I do not expect so.


> What are you persistence.xml setting?

The bugzilla entry contains a complete example including the persistence.xml. But I use configuration-by-code, so the persistence.xml only contains the provider and classes.

Tom
Previous Topic:User defined (custom) collections
Next Topic:Performance degredation
Goto Forum:
  


Current Time: Tue Jul 29 14:53:22 EDT 2014

Powered by FUDForum. Page generated in 0.03381 seconds