I'm using hibernate-3.0 with c3p0 cache, MySQL Server 5.0, and JBoss jboss-4.0.2. Using the following JTA code (in a Struts action class -- no EJBs) the client passes the insert to MySql. I've turned on logging in MySQL and it shows that the insert is received OK but then a rollback is also received when, at least in my executed code, no rollback occurs.

Nothing unusual seems to appear in Jboss log.

Any idea what would cause the rollback ?


public class SaveCustomerAction extends Action{

public ActionForward execute(ActionMapping mapping,
ActionForm form,
HttpServletRequest request,
HttpServletResponse response) {


String result = "success";
DynaActionForm dvf = (DynaActionForm) form;

String customerID = (String)dvf.get("customerID");
String tenantID = (String)dvf.get("tenantID");
String tenantName = (String)dvf.get("tenantName");

Transaction tx = null;

try {
Session session = HibernateUtil.getSessionFactory().openSession();

tx = session.beginTransaction();

TENANT theTenant = new TENANT();
theTenant.setCustomerID(customerID);
theTenant.setTenantID(tenantID);
theTenant.setTenantName(tenantName);
theTenant.setCreateDateTime(new Date());
theTenant.setUpdateDateTime(new Date());

session.save(theTenant);

tx.commit();
session.close();
}
catch (Exception e) {
try {
result = "failure";
if (tx != null)
tx.rollback();
}
catch (Exception se) {

}
}


return mapping.findForward(result);
}



MYSQL LOG

17 Query SET NAMES latin1
17 Query SET character_set_results = NULL
17 Query SHOW VARIABLES
060119 13:46:48 17 Query SHOW COLLATION
17 Query SET autocommit=1
17 Query SHOW VARIABLES LIKE 'tx_isolation'
060119 13:47:10 14 Query SET autocommit=0
14 Prepare [1]
060119 13:47:11 14 Execute [1] insert into TENANT (CreateDateTime, CustomerID, TenantName, UpdateDateTime, TenantID) values ('2006-01-19 13:47:08', 'h', 'h', '2006-01-19 13:47:09', 'h')
060119 13:47:12 14 Query rollback
14 Query SET autocommit=1




<?xml version="1.0" encoding="UTF-8"?>
<!DOCTYPE hibernate-configuration PUBLIC
"-//Hibernate/Hibernate Configuration DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd">
<hibernate-configuration>
<session-factory name="java:hibernate/SessionFactory">
<property name="hibernate.connection.driver_class">com.mysql.jdbc.Driver</property>
<property name="hibernate.connection.password">harvest</property>
<property name="hibernate.connection.url">jdbc:mysql://localhost:3306/facilities</property>
<property name="hibernate.connection.username">NMDEVUSER</property>
<property name="hibernate.connection.database">facilities</property>
<property name="hibernate.transaction.factory_class">org.hibernate.transaction.JTATransactionFactory</property>
<property name="hibernate.transaction.manager_lookup_class">org.hibernate.transaction.JBossTransactionManagerLookup</property>
<property name="hibernate.current_session_context_class)">org.hibernate.context.JTASessionContext</property>

<property name="hibernate.c3p0.min_size">5</property>
<property name="hibernate.c3p0.max_size">10</property>
<property name="hibernate.c3p0.timeout">1800</property>
<property name="hibernate.c3p0.max_statements">50</property>
<!--
<property name="hibernate.connection.datasource">java:/comp/env/jdbc/test</property>
-->
<!-- Disable the second-level cache -->
<property name="cache.provider_class">org.hibernate.cache.NoCacheProvider</property>
<property name="hibernate.dialect">org.hibernate.dialect.MySQLDialect</property>

<property name="transaction.factory_class">
org.hibernate.transaction.JTATransactionFactory
</property>
<property name="jta.UserTransaction">java:comp/UserTransaction</property>


<mapping resource="com/distributel/nm/nm.hbm.xml"/>

</session-factory>
</hibernate-configuration>



package util;

import org.hibernate.*;
import org.hibernate.cfg.*;

public class HibernateUtil {

private static final SessionFactory sessionFactory;

static {
try {
// Create the SessionFactory from hibernate.cfg.xml
sessionFactory = new Configuration().configure().buildSessionFactory();
} catch (Throwable ex) {
// Make sure you log the exception, as it might be swallowed
System.err.println("Initial SessionFactory creation failed." + ex);
throw new ExceptionInInitializerError(ex);
}
}

public static SessionFactory getSessionFactory() {
return sessionFactory;
}

}


<?xml version="1.0"?> <!DOCTYPE hibernate-mapping PUBLIC "-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<!--
<hibernate-mapping schema="facilities">
-->
<hibernate-mapping >
<class name="com.distributel.nm.TENANT" table="TENANT">
<id name="TenantID" column="TenantID">
</id>
<property name="CreateDateTime" type="timestamp" column="CreateDateTime"/>
<property name="CustomerID"/>
<property name="TenantName"/>
<property name="UpdateDateTime" type="timestamp" column="UpdateDateTime"/>
</class>

</hibernate-mapping>



JBOSS LOG



m.name=Java HotSpot(TM) Client VM, jboss.server.base.dir=C:\Program Files\jboss-4.0.2\server, org.jboss.ORBSingletonDelegate=org.jacorb.orb.ORBSingleton, jboss.server.base.url=file:/C:/Program Files/jboss-4.0.2/server/, cache.provider_class=org.hibernate.cache.NoCacheProvider, javax.management.builder.initial=org.jboss.mx.server.MBeanServerBuilderImpl, org.omg.CORBA.ORBClass=org.jacorb.orb.ORB, file.encoding=Cp1252, jta.UserTransaction=java:comp/UserTransaction, catalina.ext.dirs=C:\Program Files\jboss-4.0.2\server\all\lib, hibernate.jta.UserTransaction=java:comp/UserTransaction, hibernate.transaction.manager_lookup_class=org.hibernate.transaction.JBossTransactionManagerLookup, java.specification.version=1.5, jboss.server.name=all, hibernate.c3p0.max_statements=50}
2006-01-19 13:46:35,837 DEBUG [org.hibernate.cfg.Configuration] Preparing to build session factory with filters : {}
2006-01-19 13:46:35,837 INFO [org.hibernate.cfg.Configuration] processing extends queue
2006-01-19 13:46:35,837 INFO [org.hibernate.cfg.Configuration] processing collection mappings
2006-01-19 13:46:35,853 INFO [org.hibernate.cfg.Configuration] processing association property references
2006-01-19 13:46:35,853 INFO [org.hibernate.cfg.Configuration] processing foreign key constraints
2006-01-19 13:46:37,790 INFO [org.hibernate.connection.C3P0ConnectionProvider] C3P0 using driver: com.mysql.jdbc.Driver at URL: jdbc:mysql://localhost:3306/facilities
2006-01-19 13:46:37,806 INFO [org.hibernate.connection.C3P0ConnectionProvider] Connection properties: {user=NMDEVUSER, password=****, database=facilities}
2006-01-19 13:46:37,806 INFO [org.hibernate.connection.C3P0ConnectionProvider] autocommit mode: false
2006-01-19 13:46:44,228 INFO [STDOUT] Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource@130f672 [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@16db810 [ acquireIncrement -> 1, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, idleConnectionTestPeriod -> 0, initialPoolSize -> 5, maxIdleTime -> 1800, maxPoolSize -> 10, maxStatements -> 50, maxStatementsPerConnection -> 0, minPoolSize -> 5, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@94e474 [ description -> null, driverClass -> null, factoryClassLocation -> null, jdbcUrl -> jdbc:mysql://localhost:3306/facilities, properties -> {user=******, password=******, database=facilities} ] , preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ] , factoryClassLocation -> null, numHelperThreads -> 3, poolOwnerIdentityToken -> 130f672 ]
2006-01-19 13:46:46,556 INFO [org.hibernate.cfg.SettingsFactory] RDBMS: MySQL, version: 5.0.18-nt-log
2006-01-19 13:46:46,572 INFO [org.hibernate.cfg.SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-3.1.12 ( $Date: 2005-11-17 15:53:48 +0100 (Thu, 17 Nov 2005) $, $Revision$ )
2006-01-19 13:46:47,400 INFO [org.hibernate.dialect.Dialect] Using dialect: org.hibernate.dialect.MySQLDialect
2006-01-19 13:46:47,619 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch size: 15
2006-01-19 13:46:47,650 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch updates for versioned data: disabled
2006-01-19 13:46:47,681 INFO [org.hibernate.cfg.SettingsFactory] Scrollable result sets: enabled
2006-01-19 13:46:47,697 DEBUG [org.hibernate.cfg.SettingsFactory] Wrap result sets: disabled
2006-01-19 13:46:47,697 INFO [org.hibernate.cfg.SettingsFactory] JDBC3 getGeneratedKeys(): enabled
2006-01-19 13:46:47,712 INFO [org.hibernate.cfg.SettingsFactory] Aggressive release : disabled
2006-01-19 13:46:47,712 INFO [org.hibernate.cfg.SettingsFactory] Maximum outer join fetch depth: 2
2006-01-19 13:46:47,728 INFO [org.hibernate.cfg.SettingsFactory] Default batch fetch size: 1
2006-01-19 13:46:47,744 INFO [org.hibernate.cfg.SettingsFactory] Generate SQL with comments: disabled
2006-01-19 13:46:47,744 INFO [org.hibernate.cfg.SettingsFactory] Order SQL updates by primary key: disabled
2006-01-19 13:46:47,759 INFO [org.hibernate.cfg.SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
2006-01-19 13:46:47,947 INFO [org.hibernate.hql.ast.ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
2006-01-19 13:46:47,947 INFO [org.hibernate.cfg.SettingsFactory] Query language substitutions: {}
2006-01-19 13:46:48,040 INFO [org.hibernate.transaction.TransactionFactoryFactory] Transaction strategy: org.hibernate.transaction.JTATransactionFactory
2006-01-19 13:46:48,165 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{}
2006-01-19 13:46:48,322 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
2006-01-19 13:46:48,431 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiated TransactionManagerLookup
2006-01-19 13:46:48,447 INFO [org.hibernate.cfg.SettingsFactory] Automatic flush during beforeCompletion(): disabled
2006-01-19 13:46:48,447 INFO [org.hibernate.cfg.SettingsFactory] Automatic session close at end of transaction: disabled
2006-01-19 13:46:48,462 INFO [org.hibernate.cfg.SettingsFactory] Second-level cache: enabled
2006-01-19 13:46:48,462 INFO [org.hibernate.cfg.SettingsFactory] Query cache: disabled
2006-01-19 13:46:48,462 INFO [org.hibernate.cfg.SettingsFactory] Cache provider: org.hibernate.cache.NoCacheProvider
2006-01-19 13:46:48,572 INFO [org.hibernate.cfg.SettingsFactory] Optimize cache for minimal puts: disabled
2006-01-19 13:46:48,572 INFO [org.hibernate.cfg.SettingsFactory] Structured second-level cache entries: enabled
2006-01-19 13:46:48,619 DEBUG [org.hibernate.exception.SQLExceptionConverterFactory] Using dialect defined converter
2006-01-19 13:46:48,869 INFO [org.hibernate.cfg.SettingsFactory] Statistics: disabled
2006-01-19 13:46:48,884 INFO [org.hibernate.cfg.SettingsFactory] Deleted entity synthetic identifier rollback: disabled
2006-01-19 13:46:48,915 INFO [org.hibernate.cfg.SettingsFactory] Default entity-mode: pojo
2006-01-19 13:46:51,556 INFO [org.hibernate.impl.SessionFactoryImpl] building session factory
2006-01-19 13:46:51,556 DEBUG [org.hibernate.impl.SessionFactoryImpl] Session factory constructed with filter configurations : {}
2006-01-19 13:46:51,619 DEBUG [org.hibernate.impl.SessionFactoryImpl] instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., catalina.base=C:\Program Files\jboss-4.0.2\server\all, hibernate.connection.url=jdbc:mysql://localhost:3306/facilities, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=false, hibernate.connection.database=facilities, os.name=Windows 2000, sun.boot.class.path=C:\Program Files\Java\jdk1.5.0_06\jre\lib\rt.jar;C:\Program Files\Java\jdk1.5.0_06\jre\lib\i18n.jar;C:\Program Files\Java\jdk1.5.0_06\jre\lib\sunrsasign.jar;C:\Program Files\Java\jdk1.5.0_06\jre\lib\jsse.jar;C:\Program Files\Java\jdk1.5.0_06\jre\lib\jce.jar;C:\Program Files\Java\jdk1.5.0_06\jre\lib\charsets.jar;C:\Program Files\Java\jdk1.5.0_06\jre\classes, sun.desktop=windows, hibernate.c3p0.max_size=10, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.5.0_06-b05, hibernate.c3p0.min_size=5, hibernate.cache.provider_class=org.hibernate.cache.NoCacheProvider, user.name=bruce_ellacott, shared.loader=${catalina.base}/shared/classes,${catalina.base}/shared/lib/*.jar, jboss.bind.address=0.0.0.0, tomcat.util.buf.StringCache.byte.enabled=true, hibernate.session_factory_name=java:hibernate/SessionFactory, hibernate.c3p0.timeout=1800, jboss.home.dir=C:\Program Files\jboss-4.0.2, user.language=en, java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, sun.boot.library.path=C:\Program Files\Java\jdk1.5.0_06\jre\bin, jboss.home.url=file:/C:/Program Files/jboss-4.0.2/, jacorb.config.log.verbosity=0, java.version=1.5.0_06, user.timezone=America/New_York, jboss.server.home.dir=C:\Program Files\jboss-4.0.2\server\all, sun.arch.data.model=32, java.endorsed.dirs=C:\Program Files\Java\jdk1.5.0_06\jre\lib\endorsed, jboss.server.home.url=file:/C:/Program Files/jboss-4.0.2/server/all/, sun.cpu.isalist=, sun.jnu.encoding=Cp1252, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=49.0, jboss.server.config.url=file:/C:/Program Files/jboss-4.0.2/server/all/conf/, user.country=CA, java.home=C:\Program Files\Java\jdk1.5.0_06\jre, java.vm.info=mixed mode, jboss.lib.url=file:/C:/Program Files/jboss-4.0.2/lib/, os.version=5.0, transaction.factory_class=org.hibernate.transaction.JTATransactionFactory, hibernate.transaction.factory_class=org.hibernate.transaction.JTATransactionFactory, org.omg.CORBA.ORBSingletonClass=org.jboss.system.ORBSingleton, path.separator=;, java.vm.version=1.5.0_06-b05, hibernate.connection.password=harvest, user.variant=, java.protocol.handler.pkgs=org.jboss.net.protocol, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=NMDEVUSER, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., jboss.server.temp.dir=C:\Program Files\jboss-4.0.2\server\all\tmp, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces, user.home=C:\Documents and Settings\bruce_ellacott, java.rmi.server.RMIClassLoaderSpi=org.jboss.system.JBossRMIClassLoader, java.specification.vendor=Sun Microsystems Inc., jboss.home=C:\Program Files\jboss-4.0.2, java.library.path=C:\Program Files\Java\jdk1.5.0_06\bin;.;C:\WINNT\system32;C:\WINNT;C:\WINNT\system32;C:\WINNT;C:\WINNT\System32\Wbem;C:\Program Files\ATI Technologies\ATI Control Panel;C:\Program Files\Microsoft SQL Server\80\Tools\BINN;C:\Program Files\Seapine\Surround SCM;C:\PROGRA~1\IBM\SQLLIB\BIN;C:\PROGRA~1\IBM\SQLLIB\FUNCTION;C:\PROGRA~1\IBM\SQLLIB\SAMPLES\REPL;"C:\Program Files\PKWARE\PKZIPC\";c:\ant\apache-ant-1.6.5\bin;C:\Program Files\Java\jdk1.5.0_06\bin, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.mysql.jdbc.Driver, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=org.hibernate.dialect.MySQLDialect, common.loader=${catalina.home}/common/classes,${catalina.home}/common/i18n/*.jar,${catalina.home}/common/endorsed/*.jar,${catalina.home}/common/lib/*.jar, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\Program Files\jboss-4.0.2\bin\run.jar;C:\Program Files\Java\jdk1.5.0_06\lib\tools.jar, jbossmx.loader.repository.class=org.jboss.mx.loading.UnifiedLoaderRepository3, hibernate.current_session_context_class)=org.hibernate.context.JTASessionContext, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=C:\Program Files\jboss-4.0.2\server\all, sun.cpu.endian=little, sun.os.patch.level=Service Pack 4, jboss.server.lib.url=file:/C:/Program Files/jboss-4.0.2/server/all/lib/, java.rmi.server.codebase=http://WS118:8083/, java.io.tmpdir=C:\DOCUME~1\BRUCE_~1\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=${catalina.home}/server/classes,${catalina.home}/server/lib/*.jar, jboss.server.data.dir=C:\Program Files\jboss-4.0.2\server\all\data, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\Program Files\Java\jdk1.5.0_06\jre\lib\ext, user.dir=C:\Program Files\jboss-4.0.2\bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, jboss.server.base.dir=C:\Program Files\jboss-4.0.2\server, org.jboss.ORBSingletonDelegate=org.jacorb.orb.ORBSingleton, jboss.server.base.url=file:/C:/Program Files/jboss-4.0.2/server/, cache.provider_class=org.hibernate.cache.NoCacheProvider, javax.management.builder.initial=org.jboss.mx.server.MBeanServerBuilderImpl, org.omg.CORBA.ORBClass=org.jacorb.orb.ORB, file.encoding=Cp1252, jta.UserTransaction=java:comp/UserTransaction, catalina.ext.dirs=C:\Program Files\jboss-4.0.2\server\all\lib, hibernate.jta.UserTransaction=java:comp/UserTransaction, hibernate.transaction.manager_lookup_class=org.hibernate.transaction.JBossTransactionManagerLookup, java.specification.version=1.5, jboss.server.name=all, hibernate.c3p0.max_statements=50}
2006-01-19 13:47:00,244 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Static SQL for entity: com.distributel.nm.TENANT
2006-01-19 13:47:00,244 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Version select: select TenantID from TENANT where TenantID =?
2006-01-19 13:47:00,259 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Snapshot select: select tenant_.TenantID, tenant_.CreateDateTime as CreateDa2_0_, tenant_.CustomerID as CustomerID0_, tenant_.TenantName as TenantName0_, tenant_.UpdateDateTime as UpdateDa5_0_ from TENANT tenant_ where tenant_.TenantID=?
2006-01-19 13:47:00,259 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Insert 0: insert into TENANT (CreateDateTime, CustomerID, TenantName, UpdateDateTime, TenantID) values (?, ?, ?, ?, ?)
2006-01-19 13:47:00,259 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Update 0: update TENANT set CreateDateTime=?, CustomerID=?, TenantName=?, UpdateDateTime=? where TenantID=?
2006-01-19 13:47:00,275 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Delete 0: delete from TENANT where TenantID=?
2006-01-19 13:47:00,572 DEBUG [org.hibernate.loader.entity.EntityLoader] Static select for entity com.distributel.nm.TENANT: select tenant0_.TenantID as TenantID0_, tenant0_.CreateDateTime as CreateDa2_0_0_, tenant0_.CustomerID as CustomerID0_0_, tenant0_.TenantName as TenantName0_0_, tenant0_.UpdateDateTime as UpdateDa5_0_0_ from TENANT tenant0_ where tenant0_.TenantID=?
2006-01-19 13:47:00,587 DEBUG [org.hibernate.loader.entity.EntityLoader] Static select for entity com.distributel.nm.TENANT: select tenant0_.TenantID as TenantID0_, tenant0_.CreateDateTime as CreateDa2_0_0_, tenant0_.CustomerID as CustomerID0_0_, tenant0_.TenantName as TenantName0_0_, tenant0_.UpdateDateTime as UpdateDa5_0_0_ from TENANT tenant0_ where tenant0_.TenantID=?
2006-01-19 13:47:00,603 DEBUG [org.hibernate.loader.entity.EntityLoader] Static select for entity com.distributel.nm.TENANT: select tenant0_.TenantID as TenantID0_, tenant0_.CreateDateTime as CreateDa2_0_0_, tenant0_.CustomerID as CustomerID0_0_, tenant0_.TenantName as TenantName0_0_, tenant0_.UpdateDateTime as UpdateDa5_0_0_ from TENANT tenant0_ where tenant0_.TenantID=? for update
2006-01-19 13:47:00,619 DEBUG [org.hibernate.loader.entity.EntityLoader] Static select for entity com.distributel.nm.TENANT: select tenant0_.TenantID as TenantID0_, tenant0_.CreateDateTime as CreateDa2_0_0_, tenant0_.CustomerID as CustomerID0_0_, tenant0_.TenantName as TenantName0_0_, tenant0_.UpdateDateTime as UpdateDa5_0_0_ from TENANT tenant0_ where tenant0_.TenantID=? for update
2006-01-19 13:47:00,681 DEBUG [org.hibernate.impl.SessionFactoryObjectFactory] initializing class SessionFactoryObjectFactory
2006-01-19 13:47:00,744 DEBUG [org.hibernate.impl.SessionFactoryObjectFactory] registered: 4028918b08e3fd860108e3fdb31a0000 (java:hibernate/SessionFactory)
2006-01-19 13:47:00,744 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Factory name: java:hibernate/SessionFactory
2006-01-19 13:47:00,759 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{}
2006-01-19 13:47:00,775 DEBUG [org.hibernate.util.NamingHelper] binding: java:hibernate/SessionFactory
2006-01-19 13:47:00,790 DEBUG [org.hibernate.impl.SessionFactoryImpl] Returning a Reference to the SessionFactory
2006-01-19 13:47:00,806 DEBUG [org.hibernate.util.NamingHelper] lookup: java:hibernate
2006-01-19 13:47:00,806 INFO [org.hibernate.util.NamingHelper] Creating subcontext: java:hibernate
2006-01-19 13:47:00,822 DEBUG [org.hibernate.util.NamingHelper] binding: SessionFactory
2006-01-19 13:47:00,837 DEBUG [org.hibernate.impl.SessionFactoryImpl] Returning a Reference to the SessionFactory
2006-01-19 13:47:00,837 DEBUG [org.hibernate.util.NamingHelper] Bound name: java:hibernate/SessionFactory
2006-01-19 13:47:00,837 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Bound factory to JNDI name: java:hibernate/SessionFactory
2006-01-19 13:47:00,853 WARN [org.hibernate.impl.SessionFactoryObjectFactory] InitialContext did not implement EventContext
2006-01-19 13:47:00,853 DEBUG [org.hibernate.impl.SessionFactoryImpl] instantiated session factory
2006-01-19 13:47:00,869 DEBUG [org.hibernate.impl.SessionFactoryImpl] obtaining JTA TransactionManager
2006-01-19 13:47:00,884 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{}
2006-01-19 13:47:00,900 INFO [org.hibernate.impl.SessionFactoryImpl] Checking 0 named queries
2006-01-19 13:47:01,072 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 11376964209
2006-01-19 13:47:05,978 DEBUG [org.hibernate.transaction.JTATransaction] begin
2006-01-19 13:47:05,978 DEBUG [org.hibernate.transaction.JTATransaction] Looking for UserTransaction under: java:comp/UserTransaction
2006-01-19 13:47:05,994 DEBUG [org.hibernate.transaction.JTATransaction] Obtained UserTransaction
2006-01-19 13:47:05,994 DEBUG [org.hibernate.transaction.JTATransaction] Began a new JTA transaction
2006-01-19 13:47:06,009 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered Synchronization
2006-01-19 13:47:09,915 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] saving transient instance
2006-01-19 13:47:09,931 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] generated identifier: h, using strategy: org.hibernate.id.Assigned
2006-01-19 13:47:09,931 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] saving [com.distributel.nm.TENANT#h]
2006-01-19 13:47:10,712 DEBUG [org.hibernate.transaction.JTATransaction] commit
2006-01-19 13:47:10,712 DEBUG [org.hibernate.impl.SessionImpl] automatically flushing session
2006-01-19 13:47:10,712 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] flushing session
2006-01-19 13:47:10,712 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] processing flush-time cascades
2006-01-19 13:47:10,728 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] dirty checking collections
2006-01-19 13:47:10,728 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushing entities and processing referenced collections
2006-01-19 13:47:10,744 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Processing unreferenced collections
2006-01-19 13:47:10,744 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Scheduling collection removes/(re)creates/updates
2006-01-19 13:47:10,744 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 1 insertions, 0 updates, 0 deletions to 1 objects
2006-01-19 13:47:10,744 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2006-01-19 13:47:10,744 DEBUG [org.hibernate.pretty.Printer] listing entities:
2006-01-19 13:47:10,759 DEBUG [org.hibernate.pretty.Printer] com.distributel.nm.TENANT{TenantName=h, UpdateDateTime=2006-01-19 13:47:09, CustomerID=h, CreateDateTime=2006-01-19 13:47:08, TenantID=h}
2006-01-19 13:47:10,759 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] executing flush
2006-01-19 13:47:10,775 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Inserting entity: [com.distributel.nm.TENANT#h]
2006-01-19 13:47:10,775 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-01-19 13:47:10,775 DEBUG [org.hibernate.jdbc.AbstractBatcher] opening JDBC connection
2006-01-19 13:47:10,775 DEBUG [org.hibernate.SQL] insert into TENANT (CreateDateTime, CustomerID, TenantName, UpdateDateTime, TenantID) values (?, ?, ?, ?, ?)
2006-01-19 13:47:10,775 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-01-19 13:47:11,165 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Dehydrating entity: [com.distributel.nm.TENANT#h]
2006-01-19 13:47:11,165 DEBUG [org.hibernate.type.TimestampType] binding '2006-01-19 13:47:08' to parameter: 1
2006-01-19 13:47:11,197 DEBUG [org.hibernate.type.StringType] binding 'h' to parameter: 2
2006-01-19 13:47:11,197 DEBUG [org.hibernate.type.StringType] binding 'h' to parameter: 3
2006-01-19 13:47:11,197 DEBUG [org.hibernate.type.TimestampType] binding '2006-01-19 13:47:09' to parameter: 4
2006-01-19 13:47:11,197 DEBUG [org.hibernate.type.StringType] binding 'h' to parameter: 5
2006-01-19 13:47:11,197 DEBUG [org.hibernate.jdbc.AbstractBatcher] Adding to batch
2006-01-19 13:47:11,212 DEBUG [org.hibernate.jdbc.AbstractBatcher] Executing batch size: 1
2006-01-19 13:47:11,228 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-01-19 13:47:11,228 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-01-19 13:47:11,228 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] post flush
2006-01-19 13:47:11,228 DEBUG [org.hibernate.transaction.CacheSynchronization] transaction before completion callback
2006-01-19 13:47:11,244 DEBUG [org.hibernate.jdbc.JDBCContext] before transaction completion
2006-01-19 13:47:11,244 DEBUG [org.hibernate.impl.SessionImpl] before transaction completion
2006-01-19 13:47:11,244 DEBUG [org.hibernate.transaction.CacheSynchronization] transaction after completion callback, status: 3
2006-01-19 13:47:11,244 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction completion
2006-01-19 13:47:11,244 DEBUG [org.hibernate.impl.SessionImpl] after transaction completion
2006-01-19 13:47:11,244 DEBUG [org.hibernate.transaction.JTATransaction] Committed JTA UserTransaction
2006-01-19 13:47:12,197 DEBUG [org.hibernate.impl.SessionImpl] closing session
2006-01-19 13:47:12,197 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing JDBC connection (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)
2006-01-19 13:47:12,322 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction completion
2006-01-19 13:47:12,322 DEBUG [org.hibernate.impl.SessionImpl] after transaction completion
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.taglib.UseMenuDisplayerTag] Looking for repository named 'net.sf.navigator.menu.MENU_REPOSITORY'
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.taglib.UseMenuDisplayerTag] stuffing repository into pageContext...
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.util.PropertyMessageResources] Initializing, config='net.sf.navigator.displayer.DisplayerStrings', returnNull=true
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.taglib.DisplayMenuTag] setting name to: ToDoListMenuProvision
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.displayer.CoolMenuDisplayer] Looking up message 'PROVISION' in Struts' MessageResources
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.displayer.CoolMenuDisplayer] Looking up message 'NEW' in Struts' MessageResources
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.displayer.CoolMenuDisplayer] Looking up message 'Customer' in Struts' MessageResources
2006-01-19 13:47:14,806 DEBUG [net.sf.navigator.displayer.CoolMenuDisplayer] Looking up message 'EXIT' in Struts' MessageResources
2006-01-19 13:47:15,978 DEBUG [org.hibernate.jdbc.JDBCContext] running Session.finalize()