When I:
1. Start a transaction
2. Retrieve a persistent object through Hibernate
3. Commit the transaction
Hibernate performs an update, even though I accessed no setter methods on the persistent object. The Hibernate debug log (see below) says
that the "version" attribute of the object is dirty(??). The "version" attribute is the attribute I am using with Hibernate versioning
(<version name="version" column="version" unsaved-value="undefined" />). The only code that would update the version attribute
would be Hibernate code.
I have included all the details below. Any help would be greatly appreciated.
Hibernate version: 2.1.5
Mapping documents:
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd">
<hibernate-mapping auto-import="true">
<class
name="com...domain.user.UserImpl"
table="EMPLOYEE"
polymorphism="implicit"
discriminator-value="null"
lazy="true"
>
<id name="id" column="Id" unsaved-value="-1">
<generator class="identity"/>
</id>
<discriminator column="Lab_Cd"/>
<version name="version" column="version" unsaved-value="undefined"/>
<property name="loginId" column="Logon_Id"/>
<property name="lastPassword" column="Last_Password"/>
<property name="displayName" column="Display_Nm"/>
<component name="roles" access="field" class="com...domain.actor.UserRoleAssociation$UserRoles">
<set name="backingSet" table="Employee_Role" lazy="true">
<key column="Employee_Key"/>
<many-to-many class="com...domain.actor.role.RoleImpl" column="Role_Key"/>
</set>
</component>
</class>
<subclass
name="com...domain.actor.user.employee.EmployeeImpl"
extends="com..domain.actor.user.UserImpl"
discriminator-value="not null"
lazy="true"
>
<property name="firstName" column="First_Nm"/>
<property name="lastName" column="Last_Nm"/>
<property name="labCode" column="Lab_Cd"/>
<property name="LCLSId" column="LCLS_Id"/>
</subclass>
</hibernate-mapping>
Code between sessionFactory.openSession() and session.close():
I can simply say that I simply retrieve an EmployeeImpl object
using an hql query exectued against the Session...
Code:
select e from EmployeeImpl as e " +
"where e.id = " + internalId;
that's all - no setter methods are called on EmployeeImpl.
Full stack trace of any exception that occurs: No exceptions.
Name and version of the database you are using: DB2 8.1 Personal Edition
Debug level Hibernate log excerpt:
2004-08-23 18:48:45,250 INFO [main] (Environment.java:469) - Hibernate 2.1.5
2004-08-23 18:48:45,266 INFO [main] (Environment.java:498) - hibernate.properties not found
2004-08-23 18:48:45,266 INFO [main] (Environment.java:529) - using CGLIB reflection optimizer
2004-08-23 18:48:45,266 INFO [main] (Environment.java:540) - JVM does not support Statement.getGeneratedKeys()
2004-08-23 18:48:45,266 INFO [main] (Environment.java:551) - JVM does not support LinkedHasMap, LinkedHashSet - ordered maps and sets disabled
2004-08-23 18:48:45,266 INFO [main] (Environment.java:554) - using workaround for JVM bug in java.sql.Timestamp
2004-08-23 18:48:45,281 INFO [main] (Configuration.java:895) - configuring from resource: /hibernate.cfg.xml
2004-08-23 18:48:45,281 INFO [main] (Configuration.java:867) - Configuration resource: /hibernate.cfg.xml
2004-08-23 18:48:45,438 DEBUG [main] (DTDEntityResolver.java:20) - trying to locate
http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/
2004-08-23 18:48:45,438 DEBUG [main] (DTDEntityResolver.java:29) - found
http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - default_schema=PGPDEV
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - connection.driver_class=COM.ibm.db2.jdbc.app.DB2Driver
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - connection.url=jdbc:db2:pgp_dev
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - connection.username=
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - connection.password=
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - dialect=net.sf.hibernate.dialect.DB2Dialect
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - c3p0.max_size=3
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - c3p0.min_size=2
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - c3p0.timeout=5000
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - c3p0.max_statements=100
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - c3p0.validate=false
2004-08-23 18:48:45,500 DEBUG [main] (Configuration.java:853) - show_sql=true
2004-08-23 18:48:45,516 DEBUG [main] (Configuration.java:853) - use_outer_join=true
2004-08-23 18:48:45,516 DEBUG [main] (Configuration.java:853) - cglib.use_reflection_optimizer=true
2004-08-23 18:48:45,516 DEBUG [main] (Configuration.java:1012) - null<-org.dom4j.tree.DefaultAttribute@6a9e3102 [Attribute: name resource value "EmployeeImpl.hbm.xml"]
2004-08-23 18:48:45,516 INFO [main] (Configuration.java:331) - Mapping resource: EmployeeImpl.hbm.xml
2004-08-23 18:48:45,531 DEBUG [main] (DTDEntityResolver.java:20) - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
2004-08-23 18:48:45,531 DEBUG [main] (DTDEntityResolver.java:29) - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
2004-08-23 18:48:45,719 INFO [main] (Binder.java:229) - Mapping class: com....domain.actor.user.UserImpl -> PGA005_EMPLOYEE
2004-08-23 18:48:45,844 DEBUG [main] (Binder.java:485) - Mapped property: id -> PGA005_Employee_Id, type: long
2004-08-23 18:48:45,844 DEBUG [main] (Binder.java:485) - Mapped property: version -> version, type: integer
2004-08-23 18:48:45,875 DEBUG [main] (Binder.java:485) - Mapped property: loginId -> Logon_Id, type: string
2004-08-23 18:48:45,875 DEBUG [main] (Binder.java:485) - Mapped property: lastPassword -> Last_Password, type: string
2004-08-23 18:48:45,875 DEBUG [main] (Binder.java:485) - Mapped property: displayName -> Display_Nm, type: string
2004-08-23 18:48:45,891 DEBUG [main] (Binder.java:485) - Mapped property: createdBy -> Create_EmpId, type: com....domain.actor.user.UserImpl
2004-08-23 18:48:45,891 DEBUG [main] (Binder.java:485) - Mapped property: createdTimestamp -> Create_Tmstp, type: calendar
2004-08-23 18:48:45,891 DEBUG [main] (Binder.java:485) - Mapped property: lastUpdatedBy -> Last_Change_EmpId, type: com....domain.actor.user.UserImpl
2004-08-23 18:48:45,891 DEBUG [main] (Binder.java:485) - Mapped property: lastUpdatedTimestamp -> Last_Change_Tmstp, type: calendar
2004-08-23 18:48:45,891 INFO [main] (Binder.java:570) - Mapping collection: com....domain.actor.user.UserImpl.roles.backingSet -> Employee_Role
2004-08-23 18:48:45,906 DEBUG [main] (Binder.java:485) - Mapped property: backingSet, type: java.util.Set
2004-08-23 18:48:46,172 INFO [main] (ReflectHelper.java:176) - reflection optimizer disabled for: com....domain.actor.UserRoleAssociation$UserRoles, IllegalAccessError: com....domain.actor.UserRoleAssociation$UserRoles$$BulkBeanByCGLIB$$60a2e71a tried to access method com/.../domain/actor/role/RoleSetImpl.getBackingSet()Ljava/util/Set;
2004-08-23 18:48:46,188 DEBUG [main] (Binder.java:485) - Mapped property: roles, type: com....domain.actor.UserRoleAssociation$UserRoles
2004-08-23 18:48:46,234 INFO [main] (Binder.java:169) - Mapping subclass: com....domain.actor.user.employee.EmployeeImpl -> PGA005_EMPLOYEE
2004-08-23 18:48:46,250 DEBUG [main] (Binder.java:485) - Mapped property: firstName -> First_Nm, type: string
2004-08-23 18:48:46,250 DEBUG [main] (Binder.java:485) - Mapped property: lastName -> Last_Nm, type: string
2004-08-23 18:48:46,250 DEBUG [main] (Binder.java:485) - Mapped property: labCode -> Lab_Cd, type: string
2004-08-23 18:48:46,250 DEBUG [main] (Binder.java:485) - Mapped property: LCLSId -> LCLS_Id, type: string
2004-08-23 18:48:46,250 DEBUG [main] (Configuration.java:1012) - null<-org.dom4j.tree.DefaultAttribute@6a98f102 [Attribute: name resource value "RoleImpl.hbm.xml"]
2004-08-23 18:48:46,250 INFO [main] (Configuration.java:331) - Mapping resource: RoleImpl.hbm.xml
2004-08-23 18:48:46,266 DEBUG [main] (DTDEntityResolver.java:20) - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
2004-08-23 18:48:46,266 DEBUG [main] (DTDEntityResolver.java:29) - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
2004-08-23 18:48:46,359 INFO [main] (Binder.java:229) - Mapping class: com....domain.actor.role.RoleImpl -> PGA021_Sys_Role
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: id -> PGA021_Sys_Role_Id, type: long
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: code -> System_Role_Cd, type: string
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: description -> System_Role_Desc, type: string
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: createdBy -> Create_EmpId, type: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: createdTimestamp -> Create_Tmstp, type: calendar
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: lastUpdatedBy -> Last_Change_EmpId, type: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,375 DEBUG [main] (Binder.java:485) - Mapped property: lastUpdatedTimestamp -> Last_Change_Tmstp, type: calendar
2004-08-23 18:48:46,391 INFO [main] (Binder.java:570) - Mapping collection: com....domain.actor.role.RoleImpl.membership.backingSet -> Employee_Role
2004-08-23 18:48:46,391 DEBUG [main] (Binder.java:485) - Mapped property: backingSet, type: java.util.Set
2004-08-23 18:48:46,406 INFO [main] (ReflectHelper.java:176) - reflection optimizer disabled for: com....domain.actor.UserRoleAssociation$RoleMembership, IllegalAccessError: com....domain.actor.UserRoleAssociation$RoleMembership$$BulkBeanByCGLIB$$f00e896 tried to access method com/.../domain/actor/user/UserSetImpl.getBackingSet()Ljava/util/Set;
2004-08-23 18:48:46,406 DEBUG [main] (Binder.java:485) - Mapped property: membership, type: com....domain.actor.UserRoleAssociation$RoleMembership
2004-08-23 18:48:46,406 INFO [main] (Configuration.java:1053) - Configured SessionFactory: null
2004-08-23 18:48:46,422 DEBUG [main] (Configuration.java:1054) - properties: {java.vendor=IBM Corporation, show_sql=true, hibernate.connection.url=jdbc:db2:pgp_dev, c3p0.min_size=2, os.name=Windows XP, sun.boot.class.path=C:\WSAD\eclipse\jre\lib\rt.jar;C:\WSAD\eclipse\jre\lib\i18n.jar;C:\WSAD\eclipse\jre\classes, hibernate.c3p0.max_size=3, java.vm.specification.vendor=Sun Microsystems Inc., c3p0.max_size=3, java.runtime.version=1.3.1, default_schema=PGPDEV, hibernate.c3p0.min_size=2, user.name=stainbk, java.compiler=, connection.driver_class=COM.ibm.db2.jdbc.app.DB2Driver, hibernate.c3p0.timeout=5000, user.language=en, sun.boot.library.path=C:\WSAD\eclipse\jre\bin, dialect=net.sf.hibernate.dialect.DB2Dialect, java.version=1.3.1, c3p0.validate=false, user.timezone=GMT-05:00, hibernate.use_outer_join=true, file.encoding.pkg=sun.io, file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=46.0, connection.url=jdbc:db2:pgp_dev, java.home=C:\WSAD\eclipse\jre, java.vm.info=J2RE 1.3.1 IBM Windows 32 build cn131-20030711a (JIT disabled), hibernate.c3p0.validate=false, os.version=5.1, java.awt.fonts=, path.separator=;, connection.password=, java.vm.version=1.3.1, hibernate.connection.password=, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=, java.assistive=ON, user.home=C:\Documents and Settings\stainbk, java.specification.vendor=Sun Microsystems Inc., invokedviajava=, java.library.path=C:\WSAD\eclipse\jre\bin;.;C:\WINDOWS\System32;C:\WINDOWS;C:\PROGRA~1\PVCS\vm\win32\bin;C:\PROGRA~1\PVCS\vm\common\bin\win32;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Common Files\Adaptec Shared\System;C:\WINDOWS\system32\nls;C:\WINDOWS\system32\nls\ENGLISH;C:\Program Files\Novell\ZENworks\;C:\PROGRA~1\IBM\SQLLIB\BIN;C:\PROGRA~1\IBM\SQLLIB\FUNCTION;C:\PROGRA~1\IBM\SQLLIB\SAMPLES\REPL, java.vendor.url=http://www.ibm.com/, hibernate.connection.driver_class=COM.ibm.db2.jdbc.app.DB2Driver, connection.username=, java.vm.vendor=IBM Corporation, hibernate.dialect=net.sf.hibernate.dialect.DB2Dialect, java.fullversion=J2RE 1.3.1 IBM Windows 32 build cn131-20030711a (JIT disabled), java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:/WSAD/eclipse/plugins/org.eclipse.jdt.junit_2.1.1/junitsupport.jar;C:\Development\pla\PLAJava\resources;C:\Development\pla\PLAJava\bin;C:\Development\pla\PLAJava\JavaSource\lib\junit.jar;C:\Development\pla\PLAJava\JavaSource\lib\log4j-1.2.8.jar;C:\Development\pla\PLAJava\JavaSource\lib\commons-collections.jar;C:\Development\pla\PLAJava\JavaSource\lib\commons-logging.jar;C:\Development\pla\PLAJava\JavaSource\lib\db2java.jar;C:\Development\pla\PLAJava\JavaSource\lib\jta.jar;C:\Development\pla\PLAJava\JavaSource\lib\c3p0-0.8.4.5.jar;C:\Development\pla\PLAJava\JavaSource\lib\cglib-full-2.0.1.jar;C:\Development\pla\PLAJava\JavaSource\lib\dom4j-1.4.jar;C:\Development\pla\PLAJava\JavaSource\lib\odmg-3.0.jar;C:\Development\pla\PLAJava\JavaSource\lib\xerces-2.4.0.jar;C:\Development\pla\PLAJava\JavaSource\lib\jdbc2_0-stdext.jar;C:\Development\pla\PLAJava\JavaSource\lib\xml-apis.jar;C:\Development\pla\PLAJava\JavaSource\lib\db2jcc.jar;C:\Development\pla\PLAJava\JavaSource\lib\jms-1.0.2a.jar;C:\Development\pla\PLAJava\JavaSource\lib\j2ee.jar;C:\Development\pla\PLAJava\JavaSource\lib\hibernate-2.1.5.jar;C:\Development\pla\PLAJava\JavaSource\lib\ehcache-0.9.jar, use_outer_join=true, c3p0.timeout=5000, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, java.io.tmpdir=C:\Temp\, java.vendor.url.bug=, hibernate.default_schema=PGPDEV, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\WSAD\eclipse\jre\lib\ext, user.dir=C:\Development\pla\PLAJava, line.separator=
, java.vm.name=Classic VM, user.region=US, file.encoding=Cp1252, cglib.use_reflection_optimizer=true, java.specification.version=1.3, c3p0.max_statements=100, hibernate.show_sql=true, hibernate.c3p0.max_statements=100}
2004-08-23 18:48:46,422 INFO [main] (Configuration.java:627) - processing one-to-many association mappings
2004-08-23 18:48:46,422 DEBUG [main] (Binder.java:1350) - Second pass for collection: com....domain.actor.user.UserImpl.roles.backingSet
2004-08-23 18:48:46,422 DEBUG [main] (Binder.java:1365) - Mapped collection key: Employee_Key, element: Role_Key, type: com....domain.actor.role.RoleImpl
2004-08-23 18:48:46,422 DEBUG [main] (Binder.java:1350) - Second pass for collection: com....domain.actor.role.RoleImpl.membership.backingSet
2004-08-23 18:48:46,422 DEBUG [main] (Binder.java:1365) - Mapped collection key: Role_Key, element: Employee_Key, type: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,422 INFO [main] (Configuration.java:636) - processing one-to-one association property references
2004-08-23 18:48:46,438 INFO [main] (Configuration.java:661) - processing foreign key constraints
2004-08-23 18:48:46,438 DEBUG [main] (Configuration.java:678) - resolving reference to class: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,438 DEBUG [main] (Configuration.java:678) - resolving reference to class: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,438 DEBUG [main] (Configuration.java:678) - resolving reference to class: com....domain.actor.role.RoleImpl
2004-08-23 18:48:46,438 DEBUG [main] (Configuration.java:678) - resolving reference to class: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,438 DEBUG [main] (Configuration.java:678) - resolving reference to class: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,438 DEBUG [main] (Configuration.java:678) - resolving reference to class: com....domain.actor.user.UserImpl
2004-08-23 18:48:46,469 INFO [main] (Dialect.java:82) - Using dialect: net.sf.hibernate.dialect.DB2Dialect
2004-08-23 18:48:46,484 INFO [main] (SettingsFactory.java:63) - Use outer join fetching: true
2004-08-23 18:48:46,484 INFO [main] (C3P0ConnectionProvider.java:48) - C3P0 using driver: COM.ibm.db2.jdbc.app.DB2Driver at URL: jdbc:db2:pgp_dev
2004-08-23 18:48:46,484 INFO [main] (C3P0ConnectionProvider.java:49) - Connection properties: {user=, password=}
2004-08-23 18:48:46,562 INFO [main] (TransactionManagerLookupFactory.java:33) - No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended)
Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource@7fb97102 [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@49947102 [ acquireIncrement -> 1, autoCommitOnClose -> false, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, idleConnectionTestPeriod -> 0, initialPoolSize -> 2, maxIdleTime -> 5000, maxPoolSize -> 3, maxStatements -> 100, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@598fb102 [ description -> null, driverClass -> null, factoryClassLocation -> null, jdbcUrl -> jdbc:db2:pgp_dev, properties -> {user=, password=} ] , propertyCycle -> 300, testConnectionOnCheckout -> false ] , factoryClassLocation -> null, numHelperThreads -> 3 ]
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:103) - Use scrollable result sets: true
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:106) - Use JDBC3 getGeneratedKeys(): false
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:109) - Optimize cache for minimal puts: false
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:112) - Default schema set to: PGPDEV
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:115) - echoing all SQL to stdout
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:118) - Query language substitutions: {}
2004-08-23 18:48:46,844 INFO [main] (SettingsFactory.java:129) - cache provider: net.sf.hibernate.cache.EhCacheProvider
2004-08-23 18:48:46,859 INFO [main] (Configuration.java:1116) - instantiating and configuring caches
2004-08-23 18:48:46,891 INFO [main] (SessionFactoryImpl.java:118) - building session factory
2004-08-23 18:48:46,891 DEBUG [main] (SessionFactoryImpl.java:124) - instantiating session factory with properties: {java.vendor=IBM Corporation, show_sql=true, hibernate.connection.url=jdbc:db2:pgp_dev, c3p0.min_size=2, os.name=Windows XP, sun.boot.class.path=C:\WSAD\eclipse\jre\lib\rt.jar;C:\WSAD\eclipse\jre\lib\i18n.jar;C:\WSAD\eclipse\jre\classes, hibernate.c3p0.max_size=3, java.vm.specification.vendor=Sun Microsystems Inc., c3p0.max_size=3, java.runtime.version=1.3.1, default_schema=PGPDEV, hibernate.c3p0.min_size=2, user.name=stainbk, java.compiler=, connection.driver_class=COM.ibm.db2.jdbc.app.DB2Driver, hibernate.c3p0.timeout=5000, user.language=en, sun.boot.library.path=C:\WSAD\eclipse\jre\bin, dialect=net.sf.hibernate.dialect.DB2Dialect, java.version=1.3.1, c3p0.validate=false, user.timezone=GMT-05:00, hibernate.use_outer_join=true, file.encoding.pkg=sun.io, file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=46.0, connection.url=jdbc:db2:pgp_dev, java.home=C:\WSAD\eclipse\jre, java.vm.info=J2RE 1.3.1 IBM Windows 32 build cn131-20030711a (JIT disabled), hibernate.c3p0.validate=false, os.version=5.1, java.awt.fonts=, path.separator=;, connection.password=, java.vm.version=1.3.1, hibernate.connection.password=, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=, java.assistive=ON, user.home=C:\Documents and Settings\stainbk, java.specification.vendor=Sun Microsystems Inc., invokedviajava=, java.library.path=C:\WSAD\eclipse\jre\bin;.;C:\WINDOWS\System32;C:\WINDOWS;C:\PROGRA~1\PVCS\vm\win32\bin;C:\PROGRA~1\PVCS\vm\common\bin\win32;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Common Files\Adaptec Shared\System;C:\WINDOWS\system32\nls;C:\WINDOWS\system32\nls\ENGLISH;C:\Program Files\Novell\ZENworks\;C:\PROGRA~1\IBM\SQLLIB\BIN;C:\PROGRA~1\IBM\SQLLIB\FUNCTION;C:\PROGRA~1\IBM\SQLLIB\SAMPLES\REPL, java.vendor.url=http://www.ibm.com/, hibernate.connection.driver_class=COM.ibm.db2.jdbc.app.DB2Driver, connection.username=, java.vm.vendor=IBM Corporation, hibernate.dialect=net.sf.hibernate.dialect.DB2Dialect, java.fullversion=J2RE 1.3.1 IBM Windows 32 build cn131-20030711a (JIT disabled), java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:/WSAD/eclipse/plugins/org.eclipse.jdt.junit_2.1.1/junitsupport.jar;C:\Development\pla\PLAJava\resources;C:\Development\pla\PLAJava\bin;C:\Development\pla\PLAJava\JavaSource\lib\junit.jar;C:\Development\pla\PLAJava\JavaSource\lib\log4j-1.2.8.jar;C:\Development\pla\PLAJava\JavaSource\lib\commons-collections.jar;C:\Development\pla\PLAJava\JavaSource\lib\commons-logging.jar;C:\Development\pla\PLAJava\JavaSource\lib\db2java.jar;C:\Development\pla\PLAJava\JavaSource\lib\jta.jar;C:\Development\pla\PLAJava\JavaSource\lib\c3p0-0.8.4.5.jar;C:\Development\pla\PLAJava\JavaSource\lib\cglib-full-2.0.1.jar;C:\Development\pla\PLAJava\JavaSource\lib\dom4j-1.4.jar;C:\Development\pla\PLAJava\JavaSource\lib\odmg-3.0.jar;C:\Development\pla\PLAJava\JavaSource\lib\xerces-2.4.0.jar;C:\Development\pla\PLAJava\JavaSource\lib\jdbc2_0-stdext.jar;C:\Development\pla\PLAJava\JavaSource\lib\xml-apis.jar;C:\Development\pla\PLAJava\JavaSource\lib\db2jcc.jar;C:\Development\pla\PLAJava\JavaSource\lib\jms-1.0.2a.jar;C:\Development\pla\PLAJava\JavaSource\lib\j2ee.jar;C:\Development\pla\PLAJava\JavaSource\lib\hibernate-2.1.5.jar;C:\Development\pla\PLAJava\JavaSource\lib\ehcache-0.9.jar, use_outer_join=true, c3p0.timeout=5000, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, java.io.tmpdir=C:\Temp\, java.vendor.url.bug=, hibernate.default_schema=PGPDEV, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\WSAD\eclipse\jre\lib\ext, user.dir=C:\Development\pla\PLAJava, line.separator=
, java.vm.name=Classic VM, user.region=US, file.encoding=Cp1252, cglib.use_reflection_optimizer=true, java.specification.version=1.3, c3p0.max_statements=100, hibernate.show_sql=true, hibernate.c3p0.max_statements=100}
2004-08-23 18:48:48,141 DEBUG [main] (SessionFactoryObjectFactory.java:39) - initializing class SessionFactoryObjectFactory
2004-08-23 18:48:48,141 DEBUG [main] (SessionFactoryObjectFactory.java:76) - registered: 8a1d10f2fe8e0d4200fe8e0d477d0000 (unnamed)
2004-08-23 18:48:48,141 INFO [main] (SessionFactoryObjectFactory.java:82) - Not binding factory to JNDI, no JNDI name configured
2004-08-23 18:48:48,141 DEBUG [main] (SessionFactoryImpl.java:195) - instantiated session factory
2004-08-23 18:48:48,250 DEBUG [main] (SessionImpl.java:555) - opened session
2004-08-23 18:48:48,266 DEBUG [main] (JDBCTransaction.java:37) - begin
2004-08-23 18:48:48,266 DEBUG [main] (JDBCTransaction.java:41) - current autocommit status:false
2004-08-23 18:48:48,266 DEBUG [main] (HibernateTransaction.java:89) - Transaction started...
2004-08-23 18:48:48,266 DEBUG [main] (HibernateTransaction.java:89) - Transaction started...
2004-08-23 18:48:48,266 DEBUG [main] (HibernateTransaction.java:99) - incrementing begin counter by 1: begin counter = 1
2004-08-23 18:48:48,266 DEBUG [main] (HibernateTransaction.java:99) - incrementing begin counter by 1: begin counter = 1
2004-08-23 18:49:05,156 DEBUG [main] (SessionImpl.java:1527) - find: select e from EmployeeImpl as e where e.id = 1
2004-08-23 18:49:05,188 DEBUG [main] (QueryTranslator.java:147) - compiling query
2004-08-23 18:49:05,219 DEBUG [main] (SessionImpl.java:2246) - flushing session
2004-08-23 18:49:05,219 DEBUG [main] (SessionImpl.java:2439) - Flushing entities and processing referenced collections
2004-08-23 18:49:05,219 DEBUG [main] (SessionImpl.java:2780) - Processing unreferenced collections
2004-08-23 18:49:05,219 DEBUG [main] (SessionImpl.java:2794) - Scheduling collection removes/(re)creates/updates
2004-08-23 18:49:05,219 DEBUG [main] (SessionImpl.java:2270) - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
2004-08-23 18:49:05,234 DEBUG [main] (SessionImpl.java:2275) - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2004-08-23 18:49:05,234 DEBUG [main] (SessionImpl.java:1818) - Dont need to execute flush
2004-08-23 18:49:05,234 DEBUG [main] (QueryTranslator.java:207) - HQL: select e from com....domain.actor.user.employee.EmployeeImpl as e where e.id = 1
2004-08-23 18:49:05,234 DEBUG [main] (QueryTranslator.java:208) - SQL: select employeeim0_.PGA005_Employee_Id as PGA005_E1_, employeeim0_.First_Nm as First_Nm, employeeim0_.Last_Nm as Last_Nm, employeeim0_.Lab_Cd as Lab_Cd, employeeim0_.LCLS_Id as LCLS_Id, employeeim0_.version as version, employeeim0_.Logon_Id as Logon_Id, employeeim0_.Last_Password as Last_Pas5_, employeeim0_.Display_Nm as Display_Nm, employeeim0_.Create_EmpId as Create_E7_, employeeim0_.Create_Tmstp as Create_T8_, employeeim0_.Last_Change_EmpId as Last_Cha9_, employeeim0_.Last_Change_Tmstp as Last_Ch10_ from PGPDEV.PGA005_EMPLOYEE employeeim0_ where employeeim0_.Lab_Cd is not null and ((employeeim0_.PGA005_Employee_Id=1 ))
2004-08-23 18:49:05,234 DEBUG [main] (BatcherImpl.java:200) - about to open: 0 open PreparedStatements, 0 open ResultSets
2004-08-23 18:49:05,234 DEBUG [main] (BatcherImpl.java:226) - select employeeim0_.PGA005_Employee_Id as PGA005_E1_, employeeim0_.First_Nm as First_Nm, employeeim0_.Last_Nm as Last_Nm, employeeim0_.Lab_Cd as Lab_Cd, employeeim0_.LCLS_Id as LCLS_Id, employeeim0_.version as version, employeeim0_.Logon_Id as Logon_Id, employeeim0_.Last_Password as Last_Pas5_, employeeim0_.Display_Nm as Display_Nm, employeeim0_.Create_EmpId as Create_E7_, employeeim0_.Create_Tmstp as Create_T8_, employeeim0_.Last_Change_EmpId as Last_Cha9_, employeeim0_.Last_Change_Tmstp as Last_Ch10_ from PGPDEV.PGA005_EMPLOYEE employeeim0_ where employeeim0_.Lab_Cd is not null and ((employeeim0_.PGA005_Employee_Id=1 ))
Hibernate: select employeeim0_.PGA005_Employee_Id as PGA005_E1_, employeeim0_.First_Nm as First_Nm, employeeim0_.Last_Nm as Last_Nm, employeeim0_.Lab_Cd as Lab_Cd, employeeim0_.LCLS_Id as LCLS_Id, employeeim0_.version as version, employeeim0_.Logon_Id as Logon_Id, employeeim0_.Last_Password as Last_Pas5_, employeeim0_.Display_Nm as Display_Nm, employeeim0_.Create_EmpId as Create_E7_, employeeim0_.Create_Tmstp as Create_T8_, employeeim0_.Last_Change_EmpId as Last_Cha9_, employeeim0_.Last_Change_Tmstp as Last_Ch10_ from PGPDEV.PGA005_EMPLOYEE employeeim0_ where employeeim0_.Lab_Cd is not null and ((employeeim0_.PGA005_Employee_Id=1 ))
2004-08-23 18:49:05,234 DEBUG [main] (BatcherImpl.java:249) - preparing statement
2004-08-23 18:49:05,266 DEBUG [main] (Loader.java:278) - processing result set
2004-08-23 18:49:05,281 DEBUG [main] (Loader.java:474) - result row: 1
2004-08-23 18:49:05,281 DEBUG [main] (Loader.java:605) - Initializing object from ResultSet: 1
2004-08-23 18:49:05,281 DEBUG [main] (Loader.java:674) - Hydrating entity: com....domain.actor.user.employee.EmployeeImpl#1
2004-08-23 18:49:05,281 DEBUG [main] (SessionImpl.java:1910) - Version: 1
2004-08-23 18:49:05,281 DEBUG [main] (Loader.java:295) - done processing result set (1 rows)
2004-08-23 18:49:05,281 DEBUG [main] (BatcherImpl.java:207) - done closing: 0 open PreparedStatements, 0 open ResultSets
2004-08-23 18:49:05,281 DEBUG [main] (BatcherImpl.java:269) - closing statement
2004-08-23 18:49:05,297 DEBUG [main] (Loader.java:308) - total objects hydrated: 1
2004-08-23 18:49:05,297 DEBUG [main] (SessionImpl.java:2202) - resolving associations for [com....domain.actor.user.employee.EmployeeImpl#1]
2004-08-23 18:49:05,297 DEBUG [main] (SessionImpl.java:1986) - loading [com....domain.actor.user.UserImpl#1]
2004-08-23 18:49:05,297 DEBUG [main] (SessionImpl.java:2083) - attempting to resolve [com....domain.actor.user.UserImpl#1]
2004-08-23 18:49:05,297 DEBUG [main] (SessionImpl.java:2099) - resolved object in session cache [com....domain.actor.user.UserImpl#1]
2004-08-23 18:49:05,297 DEBUG [main] (SessionImpl.java:3933) - creating collection wrapper:[com....domain.actor.user.UserImpl.roles.backingSet#1]
2004-08-23 18:49:05,312 DEBUG [main] (SessionImpl.java:2226) - done materializing entity [com....domain.actor.user.employee.EmployeeImpl#1]
2004-08-23 18:49:05,312 DEBUG [main] (SessionImpl.java:3116) - initializing non-lazy collections
2004-08-23 18:49:05,312 DEBUG [main] (JDBCTransaction.java:59) - commit
2004-08-23 18:49:05,312 DEBUG [main] (SessionImpl.java:2246) - flushing session
2004-08-23 18:49:05,312 DEBUG [main] (SessionImpl.java:2439) - Flushing entities and processing referenced collections
2004-08-23 18:49:05,328 DEBUG [main] (AbstractEntityPersister.java:275) - com....domain.actor.user.employee.EmployeeImpl.version is dirty
2004-08-23 18:49:05,344 DEBUG [main] (SessionImpl.java:2533) - Updating entity: [com....domain.actor.user.employee.EmployeeImpl#1]
2004-08-23 18:49:05,344 DEBUG [main] (Versioning.java:26) - Incrementing: 1 to 2
2004-08-23 18:49:05,344 DEBUG [main] (SessionImpl.java:2884) - Collection found: [com....domain.actor.user.UserImpl.roles.backingSet#1], was: [com....domain.actor.user.UserImpl.roles.backingSet#1]
2004-08-23 18:49:05,344 DEBUG [main] (SessionImpl.java:2780) - Processing unreferenced collections
2004-08-23 18:49:05,344 DEBUG [main] (SessionImpl.java:2794) - Scheduling collection removes/(re)creates/updates
2004-08-23 18:49:05,344 DEBUG [main] (SessionImpl.java:2270) - Flushed: 0 insertions, 1 updates, 0 deletions to 1 objects
2004-08-23 18:49:05,344 DEBUG [main] (SessionImpl.java:2275) - Flushed: 0 (re)creations, 0 updates, 0 removals to 1 collections
2004-08-23 18:49:05,344 DEBUG [main] (Printer.java:75) - listing entities:
2004-08-23 18:49:05,359 DEBUG [main] (Printer.java:82) - com....domain.actor.user.employee.EmployeeImpl{lastUpdatedBy=null, createdBy=EmployeeImpl#1, LCLSId=232323, id=1, createdTimestamp=2000-01-01 00:00:00, labCode=TG, lastPassword=null, roles=UserRoleAssociation$UserRoles{backingSet=uninitialized}, loginId=STAINBK, version=1, displayName=KENNY STAINBACK, firstName=KENNY, lastUpdatedTimestamp=null, lastName=STAINBACK}
2004-08-23 18:49:05,375 DEBUG [main] (SessionImpl.java:2359) - executing flush
2004-08-23 18:49:05,375 DEBUG [main] (EntityPersister.java:648) - Updating entity: [com....domain.actor.user.employee.EmployeeImpl#1]
2004-08-23 18:49:05,375 DEBUG [main] (EntityPersister.java:649) - Existing version: 1 -> New version: 2
2004-08-23 18:49:05,375 DEBUG [main] (BatcherImpl.java:200) - about to open: 0 open PreparedStatements, 0 open ResultSets
2004-08-23 18:49:05,375 DEBUG [main] (BatcherImpl.java:226) - update PGPDEV.PGA005_EMPLOYEE set First_Nm=?, Last_Nm=?, Lab_Cd=?, LCLS_Id=?, version=?, Logon_Id=?, Last_Password=?, Display_Nm=?, Create_EmpId=?, Create_Tmstp=?, Last_Change_EmpId=?, Last_Change_Tmstp=? where PGA005_Employee_Id=? and version=?
Hibernate: update PGPDEV.PGA005_EMPLOYEE set First_Nm=?, Last_Nm=?, Lab_Cd=?, LCLS_Id=?, version=?, Logon_Id=?, Last_Password=?, Display_Nm=?, Create_EmpId=?, Create_Tmstp=?, Last_Change_EmpId=?, Last_Change_Tmstp=? where PGA005_Employee_Id=? and version=?
2004-08-23 18:49:05,375 DEBUG [main] (BatcherImpl.java:249) - preparing statement
2004-08-23 18:49:05,375 DEBUG [main] (EntityPersister.java:388) - Dehydrating entity: [com....domain.actor.user.employee.EmployeeImpl#1]
2004-08-23 18:49:05,375 DEBUG [main] (BatcherImpl.java:207) - done closing: 0 open PreparedStatements, 0 open ResultSets
2004-08-23 18:49:05,375 DEBUG [main] (BatcherImpl.java:269) - closing statement
2004-08-23 18:49:05,375 DEBUG [main] (SessionImpl.java:2824) - post flush
2004-08-23 18:49:05,391 DEBUG [main] (SessionImpl.java:585) - transaction completion
2004-08-23 18:49:05,391 DEBUG [main] (SessionImpl.java:573) - closing session
2004-08-23 18:49:05,391 DEBUG [main] (SessionImpl.java:3336) - disconnecting session
2004-08-23 18:49:05,391 DEBUG [main] (SessionImpl.java:585) - transaction completion