I'm trying to get a bidirectional, 1-to-many (list) association working. When I try to add the child object to the parent's list, the generated SQL fails, as the foreign key (and the index column, for that matter!) is omitted from the generated INSERT statement. My POJOs are being generated using hbm2java.
Hibernate version:
Hibernate 3.1.2
Hibernate Tools 3.1.0beta4
Mapping documents:
<hibernate-mapping package="com.ndsystems.pet.model">
<class name="Lifecycle" table="Lifecycle">
<id name="id" column="lifecycleId" type="long">
<generator class="native"/>
</id>
<timestamp name="updateTimestamp"/>
<property name="description" not-null="false" type="string"/>
<list name="lifecyclePhaseList" cascade="all-delete-orphan" inverse="true" lazy="true">
<key column="lifecycleId" not-null="true"/>
<list-index column="sequenceNumber"/>
<one-to-many class="LifecyclePhase"/>
</list>
<property name="name" not-null="true" type="string" unique="true"/>
</class>
</hibernate-mapping>
<hibernate-mapping package="com.ndsystems.pet.model">
<class name="LifecyclePhase" table="LifecyclePhase">
<id name="id" column="lifecyclePhaseId" type="long">
<generator class="native"/>
</id>
<timestamp name="updateTimestamp"/>
<property name="description" not-null="false" type="string"/>
<many-to-one class="Lifecycle" column="lifecycleId" name="lifecycle" not-null="true" insert="false" update="false"/>
<property name="name" not-null="true" type="string"/>
</class>
</hibernate-mapping>
Code between sessionFactory.openSession() and session.close():
package com.ndsystems.pet.controller;
import com.ndsystems.pet.model.Lifecycle;
import com.ndsystems.pet.model.LifecyclePhase;
import org.apache.log4j.Logger;
import org.hibernate.Session;
import org.hibernate.Transaction;
public class Sample
{
private Logger logger = Logger.getLogger("com.ndsystems.pet.controller.Sample");
public Sample()
{
Session session = Database.getCurrentSession();
Transaction transaction = session.beginTransaction();
Lifecycle lifecycle = (Lifecycle) session.createQuery("from Lifecycle as lifecycle where lifecycle.name = ?")
.setString(0, "Get")
.uniqueResult();
LifecyclePhase lifecyclePhase = new LifecyclePhase("description",
lifecycle,
"name");
logger.debug("Trouble occurs here");
lifecycle.getLifecyclePhaseList().add(lifecyclePhase);
transaction.commit();
session.close();
}
public static void main(String[] argument)
{
new Sample();
}
}
Full stack trace of any exception that occurs:
Exception in thread "main" org.hibernate.exception.GenericJDBCException: could not insert: [com.ndsystems.pet.model.LifecyclePhase]
at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:91)
at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:79)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:1986)
at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2405)
at org.hibernate.action.EntityIdentityInsertAction.execute(EntityIdentityInsertAction.java:37)
at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:243)
at org.hibernate.event.def.AbstractSaveEventListener.performSaveOrReplicate(AbstractSaveEventListener.java:269)
at org.hibernate.event.def.AbstractSaveEventListener.performSave(AbstractSaveEventListener.java:167)
at org.hibernate.event.def.AbstractSaveEventListener.saveWithGeneratedId(AbstractSaveEventListener.java:101)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.saveWithGeneratedOrRequestedId(DefaultSaveOrUpdateEventListener.java:186)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.entityIsTransient(DefaultSaveOrUpdateEventListener.java:175)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.performSaveOrUpdate(DefaultSaveOrUpdateEventListener.java:98)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.onSaveOrUpdate(DefaultSaveOrUpdateEventListener.java:70)
at org.hibernate.impl.SessionImpl.fireSaveOrUpdate(SessionImpl.java:531)
at org.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:523)
at org.hibernate.engine.CascadingAction$1.cascade(CascadingAction.java:134)
at org.hibernate.engine.Cascade.cascadeToOne(Cascade.java:213)
at org.hibernate.engine.Cascade.cascadeAssociation(Cascade.java:157)
at org.hibernate.engine.Cascade.cascadeProperty(Cascade.java:108)
at org.hibernate.engine.Cascade.cascadeCollectionElements(Cascade.java:290)
at org.hibernate.engine.Cascade.cascadeCollection(Cascade.java:185)
at org.hibernate.engine.Cascade.cascadeAssociation(Cascade.java:160)
at org.hibernate.engine.Cascade.cascadeProperty(Cascade.java:108)
at org.hibernate.engine.Cascade.cascade(Cascade.java:248)
at org.hibernate.event.def.AbstractFlushingEventListener.cascadeOnFlush(AbstractFlushingEventListener.java:130)
at org.hibernate.event.def.AbstractFlushingEventListener.prepareEntityFlushes(AbstractFlushingEventListener.java:121)
at org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:65)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:26)
at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1009)
at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:356)
at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:106)
at com.ndsystems.pet.controller.Sample.<init>(Sample.java:23)
at com.ndsystems.pet.controller.Sample.main(Sample.java:29)
Caused by: java.sql.SQLException: Field 'lifecycleId' doesn't have a default value
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:2928)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1571)
at com.mysql.jdbc.ServerPreparedStatement.serverExecute(ServerPreparedStatement.java:1124)
at com.mysql.jdbc.ServerPreparedStatement.executeInternal(ServerPreparedStatement.java:676)
at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1166)
at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1082)
at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1067)
at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:1968)
... 30 more
Name and version of the database you are using:
MySQL 5.0.16
The generated SQL (show_sql=true):
Hibernate: select lifecycle0_.lifecycleId as lifecycl1_0_, lifecycle0_.updateTimestamp as updateTi2_0_, lifecycle0_.description as descript3_0_, lifecycle0_.name as name0_ from Lifecycle lifecycle0_ where lifecycle0_.name=?
Hibernate: insert into LifecyclePhase (updateTimestamp, description, name) values (?, ?, ?)
Debug level Hibernate log excerpt:
0 [main] INFO org.hibernate.cfg.Environment - Hibernate 3.1.2
0 [main] INFO org.hibernate.cfg.Environment - hibernate.properties not found
10 [main] INFO org.hibernate.cfg.Environment - using CGLIB reflection optimizer
10 [main] INFO org.hibernate.cfg.Environment - using JDK 1.4 java.sql.Timestamp handling
90 [main] INFO org.hibernate.cfg.Configuration - configuring from resource: /hibernate.cfg.xml
90 [main] INFO org.hibernate.cfg.Configuration - Configuration resource: /hibernate.cfg.xml
171 [main] DEBUG org.hibernate.util.DTDEntityResolver - trying to locate
http://hibernate.sourceforge.net/hibern ... on-3.0.dtd in classpath under org/hibernate/
171 [main] DEBUG org.hibernate.util.DTDEntityResolver - found
http://hibernate.sourceforge.net/hibern ... on-3.0.dtd in classpath
221 [main] DEBUG org.hibernate.cfg.Configuration - connection.driver_class=com.mysql.jdbc.Driver
221 [main] DEBUG org.hibernate.cfg.Configuration - connection.url=jdbc:mysql://localhost/pet
221 [main] DEBUG org.hibernate.cfg.Configuration - connection.username=root
221 [main] DEBUG org.hibernate.cfg.Configuration - connection.password=pass
221 [main] DEBUG org.hibernate.cfg.Configuration - connection.pool_size=10
221 [main] DEBUG org.hibernate.cfg.Configuration - dialect=org.hibernate.dialect.MySQLDialect
221 [main] DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@7ffe01 [Attribute: name resource value "com/ndsystems/pet/model/Lifecycle.hbm.xml"]
221 [main] INFO org.hibernate.cfg.Configuration - Reading mappings from resource: com/ndsystems/pet/model/Lifecycle.hbm.xml
231 [main] DEBUG org.hibernate.util.DTDEntityResolver - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath under org/hibernate/
231 [main] DEBUG org.hibernate.util.DTDEntityResolver - found
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath
361 [main] INFO org.hibernate.cfg.HbmBinder - Mapping class: com.ndsystems.pet.model.Lifecycle -> Lifecycle
372 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> lifecycleId
372 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: updateTimestamp -> updateTimestamp
382 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: description -> description
392 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: lifecyclePhaseList
392 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: name -> name
392 [main] DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@1d4c61c [Attribute: name resource value "com/ndsystems/pet/model/LifecyclePhase.hbm.xml"]
392 [main] INFO org.hibernate.cfg.Configuration - Reading mappings from resource: com/ndsystems/pet/model/LifecyclePhase.hbm.xml
392 [main] DEBUG org.hibernate.util.DTDEntityResolver - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath under org/hibernate/
402 [main] DEBUG org.hibernate.util.DTDEntityResolver - found
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath
432 [main] INFO org.hibernate.cfg.HbmBinder - Mapping class: com.ndsystems.pet.model.LifecyclePhase -> LifecyclePhase
432 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> lifecyclePhaseId
432 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: updateTimestamp -> updateTimestamp
432 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: description -> description
522 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: lifecycle -> lifecycleId
522 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: name -> name
522 [main] DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@5e3974 [Attribute: name resource value "com/ndsystems/pet/model/Permission.hbm.xml"]
522 [main] INFO org.hibernate.cfg.Configuration - Reading mappings from resource: com/ndsystems/pet/model/Permission.hbm.xml
522 [main] DEBUG org.hibernate.util.DTDEntityResolver - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath under org/hibernate/
522 [main] DEBUG org.hibernate.util.DTDEntityResolver - found
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath
562 [main] INFO org.hibernate.cfg.HbmBinder - Mapping class: com.ndsystems.pet.model.Permission -> Permission
562 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> permissionId
562 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: updateTimestamp -> updateTimestamp
562 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: description -> description
562 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: name -> name
562 [main] INFO org.hibernate.cfg.HbmBinder - Mapping collection: com.ndsystems.pet.model.Permission.roleSet -> RolePermission
562 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: roleSet
572 [main] DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@ab853b [Attribute: name resource value "com/ndsystems/pet/model/Role.hbm.xml"]
572 [main] INFO org.hibernate.cfg.Configuration - Reading mappings from resource: com/ndsystems/pet/model/Role.hbm.xml
572 [main] DEBUG org.hibernate.util.DTDEntityResolver - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath under org/hibernate/
572 [main] DEBUG org.hibernate.util.DTDEntityResolver - found
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath
592 [main] INFO org.hibernate.cfg.HbmBinder - Mapping class: com.ndsystems.pet.model.Role -> Role
592 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> roleId
592 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: updateTimestamp -> updateTimestamp
592 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: description -> description
592 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: name -> name
592 [main] INFO org.hibernate.cfg.HbmBinder - Mapping collection: com.ndsystems.pet.model.Role.permissionSet -> RolePermission
592 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: permissionSet
592 [main] INFO org.hibernate.cfg.HbmBinder - Mapping collection: com.ndsystems.pet.model.Role.userSet -> UserRole
592 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: userSet
592 [main] DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@15cda3f [Attribute: name resource value "com/ndsystems/pet/model/User.hbm.xml"]
592 [main] INFO org.hibernate.cfg.Configuration - Reading mappings from resource: com/ndsystems/pet/model/User.hbm.xml
592 [main] DEBUG org.hibernate.util.DTDEntityResolver - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath under org/hibernate/
592 [main] DEBUG org.hibernate.util.DTDEntityResolver - found
http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath
623 [main] INFO org.hibernate.cfg.HbmBinder - Mapping class: com.ndsystems.pet.model.User -> User
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> userId
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: updateTimestamp -> updateTimestamp
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: deleted -> deleted
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: description -> description
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: disabled -> disabled
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: firstName -> firstName
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: lastName -> lastName
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: password -> password
623 [main] INFO org.hibernate.cfg.HbmBinder - Mapping collection: com.ndsystems.pet.model.User.roleSet -> UserRole
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: roleSet
623 [main] DEBUG org.hibernate.cfg.HbmBinder - Mapped property: username -> username
623 [main] INFO org.hibernate.cfg.Configuration - Configured SessionFactory: null
623 [main] DEBUG org.hibernate.cfg.Configuration - properties: {hibernate.connection.password=pass, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\Program Files\Java\jre1.5.0_06\bin, java.vm.version=1.5.0_06-b05, hibernate.connection.username=root, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=US, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\Documents and Settings\mcsmith\My Documents\Eclipse Projects\PET\bin\WEB-INF, java.runtime.version=1.5.0_06-b05, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Program Files\Java\jre1.5.0_06\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\mcsmith\LOCALS~1\Temp\, line.separator=, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.jnu.encoding=Cp1252, java.library.path=C:\WINDOWS\system32;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\ATI Technologies\ATI Control Panel;C:\Program Files\Common Files\Roxio Shared\DLLShared;C:\Program Files\Microsoft SQL Server\80\Tools\BINN;C:\Program Files\Microsoft BizTalk Server 2004;C:\Program Files\Common Files\Microsoft Shared\Enterprise Servers\Commerce;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\QuickTime\QTSystem\;C:\Program Files\HP OpenView\bin;C:\Progra~1\Java\jdk1.5.0_06\bin;C:\Documents and Settings\mcsmith\My Documents\bin;"C:\Program Files\PuTTY", java.specification.name=Java Platform API Specification, java.class.version=49.0, hibernate.connection.pool_size=10, sun.management.compiler=HotSpot Client Compiler, os.version=5.1, connection.password=pass, user.home=C:\Documents and Settings\mcsmith, user.timezone=, connection.username=root, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.5, hibernate.connection.driver_class=com.mysql.jdbc.Driver, user.name=mcsmith, java.class.path=classes;lib\log4j-1.2.11.jar;lib\commons-logging-1.0.4.jar;lib\hibernate3.jar;lib\dom4j-1.6.1.jar;lib\commons-logging-1.0.4.jar;lib\commons-collections-2.1.1.jar;lib\mysql-connector-java-3.1.12-bin.jar;lib\ehcache-1.1.jar;lib\asm.jar;lib\cglib-2.1.3.jar;lib\jta.jar;lib\antlr-2.7.6rc1.jar, java.vm.specification.version=1.0, java.home=C:\Program Files\Java\jre1.5.0_06, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.MySQLDialect, hibernate.connection.url=jdbc:mysql://localhost/pet, connection.pool_size=10, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, sharing, java.version=1.5.0_06, java.ext.dirs=C:\Program Files\Java\jre1.5.0_06\lib\ext, sun.boot.class.path=C:\Program Files\Java\jre1.5.0_06\lib\rt.jar;C:\Program Files\Java\jre1.5.0_06\lib\i18n.jar;C:\Program Files\Java\jre1.5.0_06\lib\sunrsasign.jar;C:\Program Files\Java\jre1.5.0_06\lib\jsse.jar;C:\Program Files\Java\jre1.5.0_06\lib\jce.jar;C:\Program Files\Java\jre1.5.0_06\lib\charsets.jar;C:\Program Files\Java\jre1.5.0_06\classes, java.vendor=Sun Microsystems Inc., connection.driver_class=com.mysql.jdbc.Driver, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, sun.desktop=windows, connection.url=jdbc:mysql://localhost/pet, dialect=org.hibernate.dialect.MySQLDialect, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86}
623 [main] DEBUG org.hibernate.cfg.Configuration - Preparing to build session factory with filters : {}
623 [main] DEBUG org.hibernate.cfg.Configuration - processing extends queue
623 [main] DEBUG org.hibernate.cfg.Configuration - processing collection mappings
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: com.ndsystems.pet.model.Lifecycle.lifecyclePhaseList
623 [main] INFO org.hibernate.cfg.HbmBinder - Mapping collection: com.ndsystems.pet.model.Lifecycle.lifecyclePhaseList -> LifecyclePhase
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: lifecycleId, index: sequenceNumber, one-to-many: com.ndsystems.pet.model.LifecyclePhase
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: com.ndsystems.pet.model.Permission.roleSet
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: roleId, element: permissionId
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: com.ndsystems.pet.model.Role.permissionSet
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: permissionId, element: roleId
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: com.ndsystems.pet.model.Role.userSet
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: userId, element: roleId
623 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: com.ndsystems.pet.model.User.roleSet
633 [main] DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: roleId, element: userId
633 [main] DEBUG org.hibernate.cfg.Configuration - processing native query and ResultSetMapping mappings
633 [main] DEBUG org.hibernate.cfg.Configuration - processing association property references
633 [main] DEBUG org.hibernate.cfg.Configuration - processing foreign key constraints
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Lifecycle
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Role
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Permission
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Role
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Permission
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.User
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Role
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.User
633 [main] DEBUG org.hibernate.cfg.Configuration - resolving reference to class: com.ndsystems.pet.model.Role
643 [main] INFO org.hibernate.connection.DriverManagerConnectionProvider - Using Hibernate built-in connection pool (not for production use!)
643 [main] INFO org.hibernate.connection.DriverManagerConnectionProvider - Hibernate connection pool size: 10
643 [main] INFO org.hibernate.connection.DriverManagerConnectionProvider - autocommit mode: false
643 [main] INFO org.hibernate.connection.DriverManagerConnectionProvider - using driver: com.mysql.jdbc.Driver at URL: jdbc:mysql://localhost/pet
643 [main] INFO org.hibernate.connection.DriverManagerConnectionProvider - connection properties: {user=root, password=pass}
643 [main] DEBUG org.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
643 [main] DEBUG org.hibernate.connection.DriverManagerConnectionProvider - opening new JDBC connection
1024 [main] DEBUG org.hibernate.connection.DriverManagerConnectionProvider - created connection to: jdbc:mysql://localhost/pet, Isolation Level: 4
1024 [main] INFO org.hibernate.cfg.SettingsFactory - RDBMS: MySQL, version: 5.0.18-nt
1024 [main] 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$ )
1024 [main] DEBUG org.hibernate.connection.DriverManagerConnectionProvider - returning connection to pool, pool size: 1
1054 [main] INFO org.hibernate.dialect.Dialect - Using dialect: org.hibernate.dialect.MySQLDialect
1054 [main] INFO org.hibernate.transaction.TransactionFactoryFactory - Using default transaction strategy (direct JDBC transactions)
1054 [main] INFO org.hibernate.transaction.TransactionManagerLookupFactory - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Automatic flush during beforeCompletion(): disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Automatic session close at end of transaction: disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - JDBC batch size: 15
1064 [main] INFO org.hibernate.cfg.SettingsFactory - JDBC batch updates for versioned data: disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Scrollable result sets: enabled
1064 [main] DEBUG org.hibernate.cfg.SettingsFactory - Wrap result sets: disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - JDBC3 getGeneratedKeys(): enabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Connection release mode: auto
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Maximum outer join fetch depth: 2
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Default batch fetch size: 1
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Generate SQL with comments: disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Order SQL updates by primary key: disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
1064 [main] INFO org.hibernate.hql.ast.ASTQueryTranslatorFactory - Using ASTQueryTranslatorFactory
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Query language substitutions: {}
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Second-level cache: enabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Query cache: disabled
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Cache provider: org.hibernate.cache.EhCacheProvider
1064 [main] INFO org.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: disabled
1074 [main] INFO org.hibernate.cfg.SettingsFactory - Structured second-level cache entries: disabled
1074 [main] DEBUG org.hibernate.exception.SQLExceptionConverterFactory - Using dialect defined converter
1074 [main] INFO org.hibernate.cfg.SettingsFactory - Statistics: disabled
1074 [main] INFO org.hibernate.cfg.SettingsFactory - Deleted entity synthetic identifier rollback: disabled
1074 [main] INFO org.hibernate.cfg.SettingsFactory - Default entity-mode: pojo
1114 [main] INFO org.hibernate.impl.SessionFactoryImpl - building session factory
1114 [main] DEBUG org.hibernate.impl.SessionFactoryImpl - Session factory constructed with filter configurations : {}
1114 [main] DEBUG org.hibernate.impl.SessionFactoryImpl - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=pass, sun.boot.library.path=C:\Program Files\Java\jre1.5.0_06\bin, java.vm.version=1.5.0_06-b05, hibernate.connection.username=root, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=US, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\Documents and Settings\mcsmith\My Documents\Eclipse Projects\PET\bin\WEB-INF, java.runtime.version=1.5.0_06-b05, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Program Files\Java\jre1.5.0_06\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\mcsmith\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.jnu.encoding=Cp1252, java.library.path=C:\WINDOWS\system32;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\ATI Technologies\ATI Control Panel;C:\Program Files\Common Files\Roxio Shared\DLLShared;C:\Program Files\Microsoft SQL Server\80\Tools\BINN;C:\Program Files\Microsoft BizTalk Server 2004;C:\Program Files\Common Files\Microsoft Shared\Enterprise Servers\Commerce;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\QuickTime\QTSystem\;C:\Program Files\HP OpenView\bin;C:\Progra~1\Java\jdk1.5.0_06\bin;C:\Documents and Settings\mcsmith\My Documents\bin;"C:\Program Files\PuTTY", java.specification.name=Java Platform API Specification, java.class.version=49.0, hibernate.connection.pool_size=10, sun.management.compiler=HotSpot Client Compiler, os.version=5.1, user.home=C:\Documents and Settings\mcsmith, connection.password=pass, user.timezone=, java.awt.printerjob=sun.awt.windows.WPrinterJob, connection.username=root, java.specification.version=1.5, file.encoding=Cp1252, hibernate.connection.driver_class=com.mysql.jdbc.Driver, java.class.path=classes;lib\log4j-1.2.11.jar;lib\commons-logging-1.0.4.jar;lib\hibernate3.jar;lib\dom4j-1.6.1.jar;lib\commons-logging-1.0.4.jar;lib\commons-collections-2.1.1.jar;lib\mysql-connector-java-3.1.12-bin.jar;lib\ehcache-1.1.jar;lib\asm.jar;lib\cglib-2.1.3.jar;lib\jta.jar;lib\antlr-2.7.6rc1.jar, user.name=mcsmith, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=C:\Program Files\Java\jre1.5.0_06, hibernate.connection.url=jdbc:mysql://localhost/pet, hibernate.dialect=org.hibernate.dialect.MySQLDialect, java.specification.vendor=Sun Microsystems Inc., user.language=en, connection.pool_size=10, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, sharing, hibernate.cglib.use_reflection_optimizer=true, java.version=1.5.0_06, java.ext.dirs=C:\Program Files\Java\jre1.5.0_06\lib\ext, sun.boot.class.path=C:\Program Files\Java\jre1.5.0_06\lib\rt.jar;C:\Program Files\Java\jre1.5.0_06\lib\i18n.jar;C:\Program Files\Java\jre1.5.0_06\lib\sunrsasign.jar;C:\Program Files\Java\jre1.5.0_06\lib\jsse.jar;C:\Program Files\Java\jre1.5.0_06\lib\jce.jar;C:\Program Files\Java\jre1.5.0_06\lib\charsets.jar;C:\Program Files\Java\jre1.5.0_06\classes, java.vendor=Sun Microsystems Inc., file.separator=\, connection.driver_class=com.mysql.jdbc.Driver, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.desktop=windows, connection.url=jdbc:mysql://localhost/pet, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86, dialect=org.hibernate.dialect.MySQLDialect}
1124 [main] WARN net.sf.ehcache.config.Configurator - No configuration found. Configuring ehcache from ehcache-failsafe.xml found in the classpath: jar:file:/C:/Documents%20and%20Settings/mcsmith/My%20Documents/Eclipse%20Projects/PET/bin/WEB-INF/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: com.ndsystems.pet.model.Lifecycle
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Version select: select updateTimestamp from Lifecycle where lifecycleId =?
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select lifecycle_.lifecycleId, lifecycle_.updateTimestamp as updateTi2_0_, lifecycle_.description as descript3_0_, lifecycle_.name as name0_ from Lifecycle lifecycle_ where lifecycle_.lifecycleId=?
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into Lifecycle (updateTimestamp, description, name, lifecycleId) values (?, ?, ?, ?)
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update Lifecycle set updateTimestamp=?, description=?, name=? where lifecycleId=? and updateTimestamp=?
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from Lifecycle where lifecycleId=? and updateTimestamp=?
1546 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Identity insert: insert into Lifecycle (updateTimestamp, description, name) values (?, ?, ?)
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: com.ndsystems.pet.model.Role
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Version select: select updateTimestamp from Role where roleId =?
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select role_.roleId, role_.updateTimestamp as updateTi2_4_, role_.description as descript3_4_, role_.name as name4_ from Role role_ where role_.roleId=?
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into Role (updateTimestamp, description, name, roleId) values (?, ?, ?, ?)
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update Role set updateTimestamp=?, description=?, name=? where roleId=? and updateTimestamp=?
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from Role where roleId=? and updateTimestamp=?
1576 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Identity insert: insert into Role (updateTimestamp, description, name) values (?, ?, ?)
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: com.ndsystems.pet.model.LifecyclePhase
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Version select: select updateTimestamp from LifecyclePhase where lifecyclePhaseId =?
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select lifecyclep_.lifecyclePhaseId, lifecyclep_.updateTimestamp as updateTi2_1_, lifecyclep_.description as descript3_1_, lifecyclep_.name as name1_ from LifecyclePhase lifecyclep_ where lifecyclep_.lifecyclePhaseId=?
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into LifecyclePhase (updateTimestamp, description, name, lifecyclePhaseId) values (?, ?, ?, ?)
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update LifecyclePhase set updateTimestamp=?, description=?, name=? where lifecyclePhaseId=? and updateTimestamp=?
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from LifecyclePhase where lifecyclePhaseId=? and updateTimestamp=?
1596 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Identity insert: insert into LifecyclePhase (updateTimestamp, description, name) values (?, ?, ?)
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: com.ndsystems.pet.model.Permission
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Version select: select updateTimestamp from Permission where permissionId =?
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select permission_.permissionId, permission_.updateTimestamp as updateTi2_2_, permission_.description as descript3_2_, permission_.name as name2_ from Permission permission_ where permission_.permissionId=?
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into Permission (updateTimestamp, description, name, permissionId) values (?, ?, ?, ?)
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update Permission set updateTimestamp=?, description=?, name=? where permissionId=? and updateTimestamp=?
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from Permission where permissionId=? and updateTimestamp=?
1616 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Identity insert: insert into Permission (updateTimestamp, description, name) values (?, ?, ?)
1717 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: com.ndsystems.pet.model.User
1717 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Version select: select updateTimestamp from User where userId =?
1717 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select user_.userId, user_.updateTimestamp as updateTi2_6_, user_.deleted as deleted6_, user_.description as descript4_6_, user_.disabled as disabled6_, user_.firstName as firstName6_, user_.lastName as lastName6_, user_.password as password6_, user_.username as username6_ from User user_ where user_.userId=?
1717 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into User (updateTimestamp, deleted, description, disabled, firstName, lastName, password, username, userId) values (?, ?, ?, ?, ?, ?, ?, ?, ?)
1717 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update User set updateTimestamp=?, deleted=?, description=?, disabled=?, firstName=?, lastName=?, password=?, username=? where userId=? and updateTimestamp=?
1727 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from User where userId=? and updateTimestamp=?
1727 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Identity insert: insert into User (updateTimestamp, deleted, description, disabled, firstName, lastName, password, username) values (?, ?, ?, ?, ?, ?, ?, ?)
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: com.ndsystems.pet.model.Role.userSet
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: insert into UserRole (userId, roleId) values (?, ?)
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row update: update UserRole set roleId=? where userId=? and roleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: delete from UserRole where userId=? and roleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: delete from UserRole where userId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: com.ndsystems.pet.model.Permission.roleSet
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: insert into RolePermission (roleId, permissionId) values (?, ?)
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row update: update RolePermission set permissionId=? where roleId=? and permissionId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: delete from RolePermission where roleId=? and permissionId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: delete from RolePermission where roleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: com.ndsystems.pet.model.User.roleSet
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: insert into UserRole (roleId, userId) values (?, ?)
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row update: update UserRole set userId=? where roleId=? and userId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: delete from UserRole where roleId=? and userId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: delete from UserRole where roleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: com.ndsystems.pet.model.Lifecycle.lifecyclePhaseList
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: update LifecyclePhase set lifecycleId=?, sequenceNumber=? where lifecyclePhaseId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: update LifecyclePhase set lifecycleId=null, sequenceNumber=null where lifecycleId=? and lifecyclePhaseId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: update LifecyclePhase set lifecycleId=null, sequenceNumber=null where lifecycleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: com.ndsystems.pet.model.Role.permissionSet
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: insert into RolePermission (permissionId, roleId) values (?, ?)
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row update: update RolePermission set roleId=? where permissionId=? and roleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: delete from RolePermission where permissionId=? and roleId=?
1737 [main] DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: delete from RolePermission where permissionId=?
1777 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Lifecycle: select lifecycle0_.lifecycleId as lifecycl1_0_0_, lifecycle0_.updateTimestamp as updateTi2_0_0_, lifecycle0_.description as descript3_0_0_, lifecycle0_.name as name0_0_ from Lifecycle lifecycle0_ where lifecycle0_.lifecycleId=?
1777 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Lifecycle: select lifecycle0_.lifecycleId as lifecycl1_0_0_, lifecycle0_.updateTimestamp as updateTi2_0_0_, lifecycle0_.description as descript3_0_0_, lifecycle0_.name as name0_0_ from Lifecycle lifecycle0_ where lifecycle0_.lifecycleId=?
1777 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Lifecycle: select lifecycle0_.lifecycleId as lifecycl1_0_0_, lifecycle0_.updateTimestamp as updateTi2_0_0_, lifecycle0_.description as descript3_0_0_, lifecycle0_.name as name0_0_ from Lifecycle lifecycle0_ where lifecycle0_.lifecycleId=? for update
1777 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Lifecycle: select lifecycle0_.lifecycleId as lifecycl1_0_0_, lifecycle0_.updateTimestamp as updateTi2_0_0_, lifecycle0_.description as descript3_0_0_, lifecycle0_.name as name0_0_ from Lifecycle lifecycle0_ where lifecycle0_.lifecycleId=? for update
1787 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity com.ndsystems.pet.model.Lifecycle: select lifecycle0_.lifecycleId as lifecycl1_0_1_, lifecycle0_.updateTimestamp as updateTi2_0_1_, lifecycle0_.description as descript3_0_1_, lifecycle0_.name as name0_1_, lifecyclep1_.lifecycleId as lifecycl4_3_, lifecyclep1_.lifecyclePhaseId as lifecycl1_3_, lifecyclep1_.sequenceNumber as sequence6_3_, lifecyclep1_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep1_.updateTimestamp as updateTi2_1_0_, lifecyclep1_.description as descript3_1_0_, lifecyclep1_.lifecycleId as lifecycl4_1_0_, lifecyclep1_.name as name1_0_ from Lifecycle lifecycle0_ left outer join LifecyclePhase lifecyclep1_ on lifecycle0_.lifecycleId=lifecyclep1_.lifecycleId where lifecycle0_.lifecycleId=?
1787 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity com.ndsystems.pet.model.Lifecycle: select lifecycle0_.lifecycleId as lifecycl1_0_1_, lifecycle0_.updateTimestamp as updateTi2_0_1_, lifecycle0_.description as descript3_0_1_, lifecycle0_.name as name0_1_, lifecyclep1_.lifecycleId as lifecycl4_3_, lifecyclep1_.lifecyclePhaseId as lifecycl1_3_, lifecyclep1_.sequenceNumber as sequence6_3_, lifecyclep1_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep1_.updateTimestamp as updateTi2_1_0_, lifecyclep1_.description as descript3_1_0_, lifecyclep1_.lifecycleId as lifecycl4_1_0_, lifecyclep1_.name as name1_0_ from Lifecycle lifecycle0_ left outer join LifecyclePhase lifecyclep1_ on lifecycle0_.lifecycleId=lifecyclep1_.lifecycleId where lifecycle0_.lifecycleId=?
1787 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Role: select role0_.roleId as roleId4_0_, role0_.updateTimestamp as updateTi2_4_0_, role0_.description as descript3_4_0_, role0_.name as name4_0_ from Role role0_ where role0_.roleId=?
1787 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Role: select role0_.roleId as roleId4_0_, role0_.updateTimestamp as updateTi2_4_0_, role0_.description as descript3_4_0_, role0_.name as name4_0_ from Role role0_ where role0_.roleId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Role: select role0_.roleId as roleId4_0_, role0_.updateTimestamp as updateTi2_4_0_, role0_.description as descript3_4_0_, role0_.name as name4_0_ from Role role0_ where role0_.roleId=? for update
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Role: select role0_.roleId as roleId4_0_, role0_.updateTimestamp as updateTi2_4_0_, role0_.description as descript3_4_0_, role0_.name as name4_0_ from Role role0_ where role0_.roleId=? for update
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity com.ndsystems.pet.model.Role: select role0_.roleId as roleId4_0_, role0_.updateTimestamp as updateTi2_4_0_, role0_.description as descript3_4_0_, role0_.name as name4_0_ from Role role0_ where role0_.roleId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity com.ndsystems.pet.model.Role: select role0_.roleId as roleId4_0_, role0_.updateTimestamp as updateTi2_4_0_, role0_.description as descript3_4_0_, role0_.name as name4_0_ from Role role0_ where role0_.roleId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.LifecyclePhase: select lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecyclePhaseId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.LifecyclePhase: select lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecyclePhaseId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.LifecyclePhase: select lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecyclePhaseId=? for update
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.LifecyclePhase: select lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecyclePhaseId=? for update
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity com.ndsystems.pet.model.LifecyclePhase: select lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecyclePhaseId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity com.ndsystems.pet.model.LifecyclePhase: select lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecyclePhaseId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Permission: select permission0_.permissionId as permissi1_2_0_, permission0_.updateTimestamp as updateTi2_2_0_, permission0_.description as descript3_2_0_, permission0_.name as name2_0_ from Permission permission0_ where permission0_.permissionId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Permission: select permission0_.permissionId as permissi1_2_0_, permission0_.updateTimestamp as updateTi2_2_0_, permission0_.description as descript3_2_0_, permission0_.name as name2_0_ from Permission permission0_ where permission0_.permissionId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Permission: select permission0_.permissionId as permissi1_2_0_, permission0_.updateTimestamp as updateTi2_2_0_, permission0_.description as descript3_2_0_, permission0_.name as name2_0_ from Permission permission0_ where permission0_.permissionId=?for update
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.Permission: select permission0_.permissionId as permissi1_2_0_, permission0_.updateTimestamp as updateTi2_2_0_, permission0_.description as descript3_2_0_, permission0_.name as name2_0_ from Permission permission0_ where permission0_.permissionId=?for update
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity com.ndsystems.pet.model.Permission: select permission0_.permissionId as permissi1_2_0_, permission0_.updateTimestamp as updateTi2_2_0_, permission0_.description as descript3_2_0_, permission0_.name as name2_0_ from Permission permission0_ where permission0_.permissionId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity com.ndsystems.pet.model.Permission: select permission0_.permissionId as permissi1_2_0_, permission0_.updateTimestamp as updateTi2_2_0_, permission0_.description as descript3_2_0_, permission0_.name as name2_0_ from Permission permission0_ where permission0_.permissionId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.User: select user0_.userId as userId6_0_, user0_.updateTimestamp as updateTi2_6_0_, user0_.deleted as deleted6_0_, user0_.description as descript4_6_0_, user0_.disabled as disabled6_0_, user0_.firstName as firstName6_0_, user0_.lastName as lastName6_0_, user0_.password as password6_0_, user0_.username as username6_0_ from User user0_ where user0_.userId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.User: select user0_.userId as userId6_0_, user0_.updateTimestamp as updateTi2_6_0_, user0_.deleted as deleted6_0_, user0_.description as descript4_6_0_, user0_.disabled as disabled6_0_, user0_.firstName as firstName6_0_, user0_.lastName as lastName6_0_, user0_.password as password6_0_, user0_.username as username6_0_ from User user0_ where user0_.userId=?
1797 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.User: select user0_.userId as userId6_0_, user0_.updateTimestamp as updateTi2_6_0_, user0_.deleted as deleted6_0_, user0_.description as descript4_6_0_, user0_.disabled as disabled6_0_, user0_.firstName as firstName6_0_, user0_.lastName as lastName6_0_, user0_.password as password6_0_, user0_.username as username6_0_ from User user0_ where user0_.userId=? for update
1807 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity com.ndsystems.pet.model.User: select user0_.userId as userId6_0_, user0_.updateTimestamp as updateTi2_6_0_, user0_.deleted as deleted6_0_, user0_.description as descript4_6_0_, user0_.disabled as disabled6_0_, user0_.firstName as firstName6_0_, user0_.lastName as lastName6_0_, user0_.password as password6_0_, user0_.username as username6_0_ from User user0_ where user0_.userId=? for update
1807 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity com.ndsystems.pet.model.User: select user0_.userId as userId6_0_, user0_.updateTimestamp as updateTi2_6_0_, user0_.deleted as deleted6_0_, user0_.description as descript4_6_0_, user0_.disabled as disabled6_0_, user0_.firstName as firstName6_0_, user0_.lastName as lastName6_0_, user0_.password as password6_0_, user0_.username as username6_0_ from User user0_ where user0_.userId=?
1807 [main] DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity com.ndsystems.pet.model.User: select user0_.userId as userId6_0_, user0_.updateTimestamp as updateTi2_6_0_, user0_.deleted as deleted6_0_, user0_.description as descript4_6_0_, user0_.disabled as disabled6_0_, user0_.firstName as firstName6_0_, user0_.lastName as lastName6_0_, user0_.password as password6_0_, user0_.username as username6_0_ from User user0_ where user0_.userId=?
1807 [main] DEBUG org.hibernate.loader.collection.BasicCollectionLoader - Static select for collection com.ndsystems.pet.model.Role.userSet: select userset0_.userId as userId1_, userset0_.roleId as roleId1_, role1_.roleId as roleId4_0_, role1_.updateTimestamp as updateTi2_4_0_, role1_.description as descript3_4_0_, role1_.name as name4_0_ from UserRole userset0_ left outer join Role role1_ on userset0_.roleId=role1_.roleId where userset0_.userId=?
1807 [main] DEBUG org.hibernate.loader.collection.BasicCollectionLoader - Static select for collection com.ndsystems.pet.model.Permission.roleSet: select roleset0_.roleId as roleId1_, roleset0_.permissionId as permissi2_1_, permission1_.permissionId as permissi1_2_0_, permission1_.updateTimestamp as updateTi2_2_0_, permission1_.description as descript3_2_0_, permission1_.name as name2_0_ from RolePermission roleset0_ left outer join Permission permission1_ on roleset0_.permissionId=permission1_.permissionId where roleset0_.roleId=?
1807 [main] DEBUG org.hibernate.loader.collection.BasicCollectionLoader - Static select for collection com.ndsystems.pet.model.User.roleSet: select roleset0_.roleId as roleId1_, roleset0_.userId as userId1_, user1_.userId as userId6_0_, user1_.updateTimestamp as updateTi2_6_0_, user1_.deleted as deleted6_0_, user1_.description as descript4_6_0_, user1_.disabled as disabled6_0_, user1_.firstName as firstName6_0_, user1_.lastName as lastName6_0_, user1_.password as password6_0_, user1_.username as username6_0_ from UserRole roleset0_ left outer join User user1_ on roleset0_.userId=user1_.userId where roleset0_.roleId=?
1817 [main] DEBUG org.hibernate.loader.collection.OneToManyLoader - Static select for one-to-many com.ndsystems.pet.model.Lifecycle.lifecyclePhaseList: select lifecyclep0_.lifecycleId as lifecycl4_1_, lifecyclep0_.lifecyclePhaseId as lifecycl1_1_, lifecyclep0_.sequenceNumber as sequence6_1_, lifecyclep0_.lifecyclePhaseId as lifecycl1_1_0_, lifecyclep0_.updateTimestamp as updateTi2_1_0_, lifecyclep0_.description as descript3_1_0_, lifecyclep0_.lifecycleId as lifecycl4_1_0_, lifecyclep0_.name as name1_0_ from LifecyclePhase lifecyclep0_ where lifecyclep0_.lifecycleId=?
1817 [main] DEBUG org.hibernate.loader.collection.BasicCollectionLoader - Static select for collection com.ndsystems.pet.model.Role.permissionSet: select permission0_.permissionId as permissi2_1_, permission0_.roleId as roleId1_, role1_.roleId as roleId4_0_, role1_.updateTimestamp as updateTi2_4_0_, role1_.description as descript3_4_0_, role1_.name as name4_0_ from RolePermission permission0_ left outer join Role role1_ on permission0_.roleId=role1_.roleId where permission0_.permissionId=?
1817 [main] DEBUG org.hibernate.impl.SessionFactoryObjectFactory - initializing class SessionFactoryObjectFactory
1817 [main] DEBUG org.hibernate.impl.SessionFactoryObjectFactory - registered: 402880000a35433a010a35433d580000 (unnamed)
1817 [main] INFO org.hibernate.impl.SessionFactoryObjectFactory - Not binding factory to JNDI, no JNDI name configured
1817 [main] DEBUG org.hibernate.impl.SessionFactoryImpl - instantiated session factory
1817 [main] DEBUG org.hibernate.impl.SessionFactoryImpl - Checking 0 named HQL queries
1817 [main] DEBUG org.hibernate.impl.SessionFactoryImpl - Checking 0 named SQL queries
1867 [main] DEBUG org.hibernate.impl.SessionImpl - opened session at timestamp: 4683181669580800
1877 [main] DEBUG org.hibernate.transaction.JDBCTransaction - begin
1877 [main] DEBUG org.hibernate.jdbc.ConnectionManager - opening JDBC connection
1877 [main] DEBUG org.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
1877 [main] DEBUG org.hibernate.connection.DriverManagerConnectionProvider - using pooled JDBC connection, pool size: 0
1877 [main] DEBUG org.hibernate.transaction.JDBCTransaction - current autocommit status: false
1877 [main] DEBUG org.hibernate.jdbc.JDBCContext - after transaction begin
1877 [main] DEBUG org.hibernate.engine.query.QueryPlanCache - unable to locate HQL query plan in cache; generating (from Lifecycle as lifecycle where lifecycle.name = ?)
1958 [main] DEBUG org.hibernate.hql.ast.QueryTranslatorImpl - parse() - HQL: from com.ndsystems.pet.model.Lifecycle aslifecycle where lifecycle.name = ?
1968 [main] DEBUG org.hibernate.hql.ast.AST - --- HQL AST ---
\-[QUERY] 'query'
+-[SELECT_FROM] 'SELECT_FROM'
| \-[FROM] 'from'
| \-[RANGE] 'RANGE'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[DOT] '.'
| | | | | +-[IDENT] 'com'
| | | | | \-[IDENT] 'ndsystems'
| | | | \-[IDENT] 'pet'
| | | \-[IDENT] 'model'
| | \-[IDENT] 'Lifecycle'
| \-[ALIAS] 'lifecycle'
\-[WHERE] 'where'
\-[EQ] '='
+-[DOT] '.'
| +-[IDENT] 'lifecycle'
| \-[IDENT] 'name'
\-[PARAM] '?'
1968 [main] DEBUG org.hibernate.hql.ast.ErrorCounter - throwQueryException() : no errors
2018 [main] DEBUG org.hibernate.hql.antlr.HqlSqlBaseWalker - select << begin [level=1, statement=select]
2048 [main] DEBUG org.hibernate.hql.ast.tree.FromElement - FromClause{level=1} : com.ndsystems.pet.model.Lifecycle (lifecycle) -> lifecycle0_
2048 [main] DEBUG org.hibernate.hql.ast.tree.FromReferenceNode - Resolved : lifecycle -> lifecycle0_.lifecycleId
2048 [main] DEBUG org.hibernate.hql.ast.tree.DotNode - getDataType() : name -> org.hibernate.type.StringType@170888e
2048 [main] DEBUG org.hibernate.hql.ast.tree.FromReferenceNode - Resolved : lifecycle.name -> lifecycle0_.name
2058 [main] DEBUG org.hibernate.hql.antlr.HqlSqlBaseWalker - select : finishing up [level=1, statement=select]
2058 [main] DEBUG org.hibernate.hql.ast.HqlSqlWalker - processQuery() : ( SELECT ( FromClause{level=1} Lifecycle lifecycle0_ ) ( where ( = ( lifecycle0_.name lifecycle0_.lifecycleId name ) ? ) ) )
2058 [main] DEBUG org.hibernate.hql.ast.HqlSqlWalker - Derived SELECT clause created.
2068 [main] DEBUG org.hibernate.hql.ast.util.JoinProcessor - Using FROM fragment [Lifecycle lifecycle0_]
2068 [main] DEBUG org.hibernate.hql.antlr.HqlSqlBaseWalker - select >> end [level=1, statement=select]
2078 [main] DEBUG org.hibernate.hql.ast.AST - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (Lifecycle)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'lifecycle0_.lifecycleId as lifecycl1_0_' {FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=lifecycle,role=null,tableName=Lifecycle,tableAlias=lifecycle0_,origin=null,colums={,className=com.ndsystems.pet.model.Lifecycle}}}
| \-[SQL_TOKEN] SqlFragment: 'lifecycle0_.updateTimestamp as updateTi2_0_, lifecycle0_.description as descript3_0_, lifecycle0_.name as name0_'
+-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[lifecycle], fromElementByTableAlias=[lifecycle0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
| \-[FROM_FRAGMENT] FromElement: 'Lifecycle lifecycle0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=lifecycle,role=null,tableName=Lifecycle,tableAlias=lifecycle0_,origin=null,colums={,className=com.ndsystems.pet.model.Lifecycle}}
\-[WHERE] SqlNode: 'where'
\-[EQ] BinaryLogicOperatorNode: '='
+-[DOT] DotNode: 'lifecycle0_.name' {propertyName=name,dereferenceType=4,propertyPath=name,path=lifecycle.name,tableAlias=lifecycle0_,className=com.ndsystems.pet.model.Lifecycle,classAlias=lifecycle}
| +-[ALIAS_REF] IdentNode: 'lifecycle0_.lifecycleId' {alias=lifecycle, className=com.ndsystems.pet.model.Lifecycle, tableAlias=lifecycle0_}
| \-[IDENT] IdentNode: 'name' {originalText=name}
\-[PARAM] ParameterNode: '?' {ordinal=0, expectedType=org.hibernate.type.StringType@170888e}
2078 [main] DEBUG org.hibernate.hql.ast.ErrorCounter - throwQueryException() : no errors
2088 [main] DEBUG org.hibernate.hql.ast.QueryTranslatorImpl - HQL: from com.ndsystems.pet.model.Lifecycle as lifecyclewhere lifecycle.name = ?
2088 [main] DEBUG org.hibernate.hql.ast.QueryTranslatorImpl - SQL: select lifecycle0_.lifecycleId as lifecycl1_0_, lifecycle0_.updateTimestamp as updateTi2_0_, lifecycle0_.description as descript3_0_, lifecycle0_.name as name0_ from Lifecycle lifecycle0_ where lifecycle0_.name=?
2088 [main] DEBUG org.hibernate.hql.ast.ErrorCounter - throwQueryException() : no errors
2098 [main] DEBUG org.hibernate.engine.query.HQLQueryPlan - HQL param location recognition took 0 mills (from Lifecycle as lifecycle where lifecycle.name = ?)
2108 [main] DEBUG org.hibernate.engine.query.QueryPlanCache - located HQL query plan in cache (from Lifecycle as lifecycle where lifecycle.name = ?)
2108 [main] DEBUG org.hibernate.engine.query.HQLQueryPlan - find: from Lifecycle as lifecycle where lifecycle.name = ?
2108 [main] DEBUG org.hibernate.engine.QueryParameters - parameters: [Get]
2108 [main] DEBUG org.hibernate.engine.QueryParameters - named parameters: {}
2108 [main] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2108 [main] DEBUG org.hibernate.SQL - select lifecycle0_.lifecycleId as lifecycl1_0_, lifecycle0_.updateTimestamp as updateTi2_0_, lifecycle0_.description as descript3_0_, lifecycle0_.name as name0_ from Lifecycle lifecycle0_ where lifecycle0_.name=?
2108 [main] DEBUG org.hibernate.jdbc.AbstractBatcher - preparing statement
2128 [main] DEBUG org.hibernate.type.StringType - binding 'Get' to parameter: 1
2128 [main] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
2128 [main] DEBUG org.hibernate.loader.Loader - processing result set
2128 [main] DEBUG org.hibernate.loader.Loader - result set row: 0
2138 [main] DEBUG org.hibernate.type.LongType - returning '2' as column: lifecycl1_0_
2138 [main] DEBUG org.hibernate.loader.Loader - result row: EntityKey[com.ndsystems.pet.model.Lifecycle#2]
2138 [main] DEBUG org.hibernate.loader.Loader - Initializing object from ResultSet: [com.ndsystems.pet.model.Lifecycle#2]
2138 [main] DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Hydrating entity: [com.ndsystems.pet.model.Lifecycle#2]
2159 [main] DEBUG org.hibernate.type.TimestampType - returning '2006-01-01 00:00:00' as column: updateTi2_0_
2159 [main] DEBUG org.hibernate.type.StringType - returning 'Get test case' as column: descript3_0_
2159 [main] DEBUG org.hibernate.type.StringType - returning 'Get' as column: name0_
2159 [mai