Hi All, in my unit test below something weird is happening. When the test is run it fails at this line
Code:
assertFalse("account4 should not contain elist2",account4.getElists().contains(elist2));
However if I comment out the code marked
failure code & run the commented out code marked
successful code, the test passes. I don't really understand why it works if I reload my Account Object, but not when I re-attach the detached Account Object. I have been working through Hibernate in Action (which incidentally, Christian & Gavin is Awesome!! Thanks!) & have created this code after getting through Chapters 3 & 4, and I thought it should work. If anyone can please explain to me why it doesn't work I'd be really grateful, as it will really aid my understanding.
Please note that I do have a tearDown() method in my test which cleans up by deleting the Account, so the execution of this tearDown() method will be seen in the logs.
Many, many thanks
Paul :-)
Hibernate version: 2.1.6
Mapping documents:Code:
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 2.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd">
<hibernate-mapping>
<class name="com.easylists.Account" table="ACCOUNT">
<id name="hibernateId" type="long" column="HIBERNATE_ID">
<meta attribute="scope-set">protected</meta>
<generator class="native"/>
</id>
<version name="version" column="VERSION"/>
<property name="pk" type="long">
<column name="PK" not-null="true"/>
</property>
<property name="active" type="boolean">
<column name="ACTIVE" not-null="true"/>
</property>
<property name="hasBeenActivated" type="boolean">
<column name="HAS_BEEN_ACTIVATED" not-null="true"/>
</property>
<property name="createdDate" type="java.util.Date">
<column name="CREATED_DATE" not-null="true"/>
</property>
<property name="expiryDate" type="java.util.Date">
<column name="EXPIRY_DATE"/>
</property>
<property name="title" type="string">
<meta attribute="use-in-tostring">true</meta>
<column name="TITLE" not-null="true"/>
</property>
<property name="forename" type="string">
<meta attribute="use-in-tostring">true</meta>
<column name="FORENAME" not-null="true"/>
</property>
<property name="surname" type="string">
<column name="SURNAME" not-null="true"/>
</property>
<property name="emailAddress" type="string">
<column name="EMAIL_ADDRESS" not-null="true" unique="true" index="EMAIL_IDX"/>
</property>
<property name="password" type="string">
<column name="PASSWORD" not-null="true"/>
</property>
<property name="quota" type="int">
<column name="QUOTA"/>
</property>
<component name="address" class="com.easylists.Address">
<property name="houseNumber" type="string">
<column name="HOUSE_NUMBER" not-null="true"/>
</property>
<property name="houseName" type="string">
<column name="HOUSE_NAME" not-null="true"/>
</property>
<property name="street" type="string">
<meta attribute="use-in-toString">true</meta>
<column name="STREET" not-null="true"/>
</property>
<property name="townOrCity" type="string">
<column name="TOWN_OR_CITY" not-null="true"/>
</property>
<property name="countyOrState" type="string">
<column name="COUNTY_OR_STATE" not-null="true"/>
</property>
<property name="postcode" type="string">
<column name="POSTCODE" not-null="true"/>
</property>
<property name="country" type="string">
<column name="COUNTRY" not-null="true"/>
</property>
</component>
<set name="elists" table="ELIST" cascade="all-delete-orphan" inverse="true" >
<key column="ACCOUNT_ID"/>
<one-to-many class="com.easylists.EList"/>
</set>
<set name="emailAddressBooks" table="EMAIL_ADDRESS_BOOK" cascade="all-delete-orphan" >
<key column="ACCOUNT_ID"/>
<one-to-many class="com.easylists.EmailAddressBook"/>
</set>
</class>
</hibernate-mapping>
Code:
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 2.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd">
<hibernate-mapping>
<class name="com.easylists.EList" table="ELIST">
<id name="hibernateId" type="long" column="HIBERNATE_ID">
<meta attribute="scope-set">protected</meta>
<generator class="native"/>
</id>
<version name="version" column="VERSION"/>
<property name="pk" type="long">
<column name="PK" not-null="true"/>
</property>
<property name="elistName" type="string">
<column name="ELIST_NAME" not-null="true"/>
</property>
<property name="emailMessage" type="string">
<column name="EMAIL_MESSAGE"/>
</property>
<property name="password" type="string">
<column name="PASSWORD"/>
</property>
<property name="hideWhenPurchased" type="boolean">
<column name="HIDE_WHEN_PURCHASED" not-null="true"/>
</property>
<property name="sendEmailWhenItemPurchased" type="boolean">
<column name="EMAIL_WHEN_ITEM_PURCHASED" not-null="true"/>
</property>
<property name="expiryDate" type="java.util.Date">
<column name="EXPIRY_DATE"/>
</property>
<many-to-one name="account" class="com.easylists.Account" column="ACCOUNT_ID" not-null="true"/>
<set name="elistItems" table="ELIST_ITEM" cascade="all-delete-orphan">
<key column="ELIST_ID"/>
<one-to-many class="com.easylists.EListItem"/>
</set>
</class>
</hibernate-mapping>
Code between sessionFactory.openSession() and session.close():This is the code called from the test for PERSISTENCE_MANAGER.saveObject(Object ob)
Code:
public static void saveObject(Object ob) throws PersistenceException {
try {
Session session = null;
Transaction tx = null;
try {
session = HibernateUtil.openSession();
tx = session.beginTransaction();
session.saveOrUpdate(ob);
tx.commit();
}
catch (StaleObjectStateException e) {
if (tx != null) {
tx.rollback();
}
throw new StalePersistentObjectException(e);
}
catch (HibernateException e) {
if (tx != null) {
tx.rollback();
}
throw new PersistenceException(e);
}
finally {
closeSession(session);
}
}
catch (HibernateException e) {
throw new PersistenceException(e);
}
}
Full stack trace of any exception that occurs:
N/A
Name and version of the database you are using:
MySQL 4.0
The generated SQL (show_sql=true):
14-10-2004 20:48:43 INFO - Hibernate 2.1.6 (Environment.java:469)
14-10-2004 20:48:43 INFO - loaded properties from resource hibernate.properties: {hibernate.cglib.use_reflection_optimizer=true, hibernate.cache.provider_class=net.sf.ehcache.hibernate.Provider, hibernate.cache.use_query_cache=true, hibernate.max_fetch_depth=1, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.jdbc.use_streams_for_binary=true, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', hibernate.jdbc.batch_size=0, hibernate.proxool.pool_alias=pool1, hibernate.cache.region_prefix=hibernate.test, hibernate.connection.datasource=java:comp/env/jdbc/easytwo, hibernate.show_sql=true, hibernate.connection.pool_size=1} (Environment.java:503)
14-10-2004 20:48:43 INFO - using java.io streams to persist binary types (Environment.java:528)
14-10-2004 20:48:43 INFO - using CGLIB reflection optimizer (Environment.java:529)
14-10-2004 20:48:43 INFO - configuring from resource: /hibernate.cfg.xml (Configuration.java:895)
14-10-2004 20:48:43 INFO - Configuration resource: /hibernate.cfg.xml (Configuration.java:867)
14-10-2004 20:48:43 INFO - Mapping resource: com/easylists/Account.hbm.xml (Configuration.java:331)
14-10-2004 20:48:43 INFO - Mapping class: com.easylists.Account -> ACCOUNT (Binder.java:229)
14-10-2004 20:48:44 INFO - Mapping resource: com/easylists/EList.hbm.xml (Configuration.java:331)
14-10-2004 20:48:45 INFO - Mapping class: com.easylists.EList -> ELIST (Binder.java:229)
14-10-2004 20:48:45 INFO - Mapping resource: com/easylists/EListItem.hbm.xml (Configuration.java:331)
14-10-2004 20:48:45 INFO - Mapping class: com.easylists.EListItem -> ELIST_ITEM (Binder.java:229)
14-10-2004 20:48:45 INFO - Mapping resource: com/easylists/EmailAddress.hbm.xml (Configuration.java:331)
14-10-2004 20:48:45 INFO - Mapping class: com.easylists.EmailAddress -> EMAIL_ADDRESS (Binder.java:229)
14-10-2004 20:48:45 INFO - Mapping resource: com/easylists/EmailAddressBook.hbm.xml (Configuration.java:331)
14-10-2004 20:48:45 INFO - Mapping class: com.easylists.EmailAddressBook -> EMAIL_ADDRESS_BOOK (Binder.java:229)
14-10-2004 20:48:45 INFO - Mapping collection: com.easylists.EmailAddressBook.emailAddresses -> EML_ADDR_BKS_EMAIL_ADDRESSES (Binder.java:571)
14-10-2004 20:48:45 INFO - Configured SessionFactory: null (Configuration.java:1053)
14-10-2004 20:48:45 INFO - processing one-to-many association mappings (Configuration.java:627)
14-10-2004 20:48:45 INFO - Mapping collection: com.easylists.Account.elists -> ELIST (Binder.java:1181)
14-10-2004 20:48:45 INFO - Mapping collection: com.easylists.Account.emailAddressBooks -> EMAIL_ADDRESS_BOOK (Binder.java:1181)
14-10-2004 20:48:45 INFO - Mapping collection: com.easylists.EList.elistItems -> ELIST_ITEM (Binder.java:1181)
14-10-2004 20:48:45 INFO - processing one-to-one association property references (Configuration.java:636)
14-10-2004 20:48:45 INFO - processing foreign key constraints (Configuration.java:661)
14-10-2004 20:48:46 INFO - Using dialect: net.sf.hibernate.dialect.MySQLDialect (Dialect.java:82)
14-10-2004 20:48:46 INFO - Maximim outer join fetch depth: 1 (SettingsFactory.java:59)
14-10-2004 20:48:46 INFO - Use outer join fetching: true (SettingsFactory.java:63)
14-10-2004 20:48:46 INFO - JNDI InitialContext properties:{} (NamingHelper.java:26)
14-10-2004 20:48:46 INFO - Using datasource: java:comp/env/jdbc/easytwo (DatasourceConnectionProvider.java:51)
14-10-2004 20:48:46 INFO - No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended) (TransactionManagerLookupFactory.java:33)
14-10-2004 20:48:46 INFO - Use scrollable result sets: true (SettingsFactory.java:103)
14-10-2004 20:48:46 INFO - Use JDBC3 getGeneratedKeys(): true (SettingsFactory.java:106)
14-10-2004 20:48:46 INFO - Optimize cache for minimal puts: false (SettingsFactory.java:109)
14-10-2004 20:48:46 INFO - echoing all SQL to stdout (SettingsFactory.java:115)
14-10-2004 20:48:46 INFO - Query language substitutions: {no='N', true=1, yes='Y', false=0} (SettingsFactory.java:118)
14-10-2004 20:48:46 INFO - cache provider: net.sf.ehcache.hibernate.Provider (SettingsFactory.java:129)
14-10-2004 20:48:46 INFO - query cache factory: net.sf.hibernate.cache.StandardQueryCacheFactory (SettingsFactory.java:141)
14-10-2004 20:48:46 INFO - instantiating and configuring caches (Configuration.java:1116)
14-10-2004 20:48:46 INFO - building session factory (SessionFactoryImpl.java:118)
14-10-2004 20:48:47 INFO - reflection optimizer disabled for: com.easylists.EmailAddressBook, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
14-10-2004 20:48:47 INFO - reflection optimizer disabled for: com.easylists.Account, BulkBeanException: Property is private (property setElists) (ReflectHelper.java:186)
14-10-2004 20:48:47 INFO - reflection optimizer disabled for: com.easylists.EList, BulkBeanException: Property is private (property setElistItems) (ReflectHelper.java:186)
14-10-2004 20:48:47 INFO - reflection optimizer disabled for: com.easylists.EListItem, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
14-10-2004 20:48:47 INFO - reflection optimizer disabled for: com.easylists.EmailAddress, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
14-10-2004 20:48:48 INFO - Not binding factory to JNDI, no JNDI name configured (SessionFactoryObjectFactory.java:82)
14-10-2004 20:48:48 INFO - starting update timestamps cache at region: net.sf.hibernate.cache.UpdateTimestampsCache (UpdateTimestampsCache.java:35)
14-10-2004 20:48:48 INFO - starting query cache at region: net.sf.hibernate.cache.StandardQueryCache (StandardQueryCache.java:41)
14-10-2004 20:48:48 DEBUG - insert into ACCOUNT (VERSION, PK, ACTIVE, HAS_BEEN_ACTIVATED, CREATED_DATE, EXPIRY_DATE, TITLE, FORENAME, SURNAME, EMAIL_ADDRESS, PASSWORD, QUOTA, HOUSE_NUMBER, HOUSE_NAME, STREET, TOWN_OR_CITY, COUNTY_OR_STATE, POSTCODE, COUNTRY) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) (BatcherImpl.java:226)
14-10-2004 20:48:48 DEBUG - select this.HIBERNATE_ID as HIBERNAT1_0_, this.VERSION as VERSION0_, this.PK as PK0_, this.ACTIVE as ACTIVE0_, this.HAS_BEEN_ACTIVATED as HAS_BEEN5_0_, this.CREATED_DATE as CREATED_6_0_, this.EXPIRY_DATE as EXPIRY_D7_0_, this.TITLE as TITLE0_, this.FORENAME as FORENAME0_, this.SURNAME as SURNAME0_, this.EMAIL_ADDRESS as EMAIL_A11_0_, this.PASSWORD as PASSWORD0_, this.QUOTA as QUOTA0_, this.HOUSE_NUMBER as HOUSE_N14_0_, this.HOUSE_NAME as HOUSE_NAME0_, this.STREET as STREET0_, this.TOWN_OR_CITY as TOWN_OR17_0_, this.COUNTY_OR_STATE as COUNTY_18_0_, this.POSTCODE as POSTCODE0_, this.COUNTRY as COUNTRY0_ from ACCOUNT this where this.EMAIL_ADDRESS=? (BatcherImpl.java:226)
14-10-2004 20:48:48 DEBUG - select emailaddre0_.ACCOUNT_ID as ACCOUNT_ID__, emailaddre0_.HIBERNATE_ID as HIBERNAT1___, emailaddre0_.HIBERNATE_ID as HIBERNAT1_0_, emailaddre0_.VERSION as VERSION0_, emailaddre0_.PK as PK0_, emailaddre0_.NAME as NAME0_ from EMAIL_ADDRESS_BOOK emailaddre0_ where emailaddre0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select elists0_.ACCOUNT_ID as ACCOUNT_ID__, elists0_.HIBERNATE_ID as HIBERNAT1___, elists0_.HIBERNATE_ID as HIBERNAT1_0_, elists0_.VERSION as VERSION0_, elists0_.PK as PK0_, elists0_.ELIST_NAME as ELIST_NAME0_, elists0_.EMAIL_MESSAGE as EMAIL_ME5_0_, elists0_.PASSWORD as PASSWORD0_, elists0_.HIDE_WHEN_PURCHASED as HIDE_WHE7_0_, elists0_.EMAIL_WHEN_ITEM_PURCHASED as EMAIL_WH8_0_, elists0_.EXPIRY_DATE as EXPIRY_D9_0_, elists0_.ACCOUNT_ID as ACCOUNT_ID0_ from ELIST elists0_ where elists0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - insert into ELIST (VERSION, PK, ELIST_NAME, EMAIL_MESSAGE, PASSWORD, HIDE_WHEN_PURCHASED, EMAIL_WHEN_ITEM_PURCHASED, EXPIRY_DATE, ACCOUNT_ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?) (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - update ACCOUNT set VERSION=?, PK=?, ACTIVE=?, HAS_BEEN_ACTIVATED=?, CREATED_DATE=?, EXPIRY_DATE=?, TITLE=?, FORENAME=?, SURNAME=?, EMAIL_ADDRESS=?, PASSWORD=?, QUOTA=?, HOUSE_NUMBER=?, HOUSE_NAME=?, STREET=?, TOWN_OR_CITY=?, COUNTY_OR_STATE=?, POSTCODE=?, COUNTRY=? where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select this.HIBERNATE_ID as HIBERNAT1_0_, this.VERSION as VERSION0_, this.PK as PK0_, this.ACTIVE as ACTIVE0_, this.HAS_BEEN_ACTIVATED as HAS_BEEN5_0_, this.CREATED_DATE as CREATED_6_0_, this.EXPIRY_DATE as EXPIRY_D7_0_, this.TITLE as TITLE0_, this.FORENAME as FORENAME0_, this.SURNAME as SURNAME0_, this.EMAIL_ADDRESS as EMAIL_A11_0_, this.PASSWORD as PASSWORD0_, this.QUOTA as QUOTA0_, this.HOUSE_NUMBER as HOUSE_N14_0_, this.HOUSE_NAME as HOUSE_NAME0_, this.STREET as STREET0_, this.TOWN_OR_CITY as TOWN_OR17_0_, this.COUNTY_OR_STATE as COUNTY_18_0_, this.POSTCODE as POSTCODE0_, this.COUNTRY as COUNTRY0_ from ACCOUNT this where this.PK=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select emailaddre0_.ACCOUNT_ID as ACCOUNT_ID__, emailaddre0_.HIBERNATE_ID as HIBERNAT1___, emailaddre0_.HIBERNATE_ID as HIBERNAT1_0_, emailaddre0_.VERSION as VERSION0_, emailaddre0_.PK as PK0_, emailaddre0_.NAME as NAME0_ from EMAIL_ADDRESS_BOOK emailaddre0_ where emailaddre0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select elists0_.ACCOUNT_ID as ACCOUNT_ID__, elists0_.HIBERNATE_ID as HIBERNAT1___, elists0_.HIBERNATE_ID as HIBERNAT1_0_, elists0_.VERSION as VERSION0_, elists0_.PK as PK0_, elists0_.ELIST_NAME as ELIST_NAME0_, elists0_.EMAIL_MESSAGE as EMAIL_ME5_0_, elists0_.PASSWORD as PASSWORD0_, elists0_.HIDE_WHEN_PURCHASED as HIDE_WHE7_0_, elists0_.EMAIL_WHEN_ITEM_PURCHASED as EMAIL_WH8_0_, elists0_.EXPIRY_DATE as EXPIRY_D9_0_, elists0_.ACCOUNT_ID as ACCOUNT_ID0_ from ELIST elists0_ where elists0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select elistitems0_.ELIST_ID as ELIST_ID__, elistitems0_.HIBERNATE_ID as HIBERNAT1___, elistitems0_.HIBERNATE_ID as HIBERNAT1_0_, elistitems0_.VERSION as VERSION0_, elistitems0_.PK as PK0_, elistitems0_.DESCRIPTION as DESCRIPT4_0_, elistitems0_.WHERE_TO_BUY as WHERE_TO5_0_, elistitems0_.LINK as LINK0_, elistitems0_.PRICE as PRICE0_, elistitems0_.sortOrder as sortOrder0_, elistitems0_.DISPLAY as DISPLAY0_, elistitems0_.PURCHASED as PURCHASED0_ from ELIST_ITEM elistitems0_ where elistitems0_.ELIST_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select this.HIBERNATE_ID as HIBERNAT1_0_, this.VERSION as VERSION0_, this.PK as PK0_, this.ACTIVE as ACTIVE0_, this.HAS_BEEN_ACTIVATED as HAS_BEEN5_0_, this.CREATED_DATE as CREATED_6_0_, this.EXPIRY_DATE as EXPIRY_D7_0_, this.TITLE as TITLE0_, this.FORENAME as FORENAME0_, this.SURNAME as SURNAME0_, this.EMAIL_ADDRESS as EMAIL_A11_0_, this.PASSWORD as PASSWORD0_, this.QUOTA as QUOTA0_, this.HOUSE_NUMBER as HOUSE_N14_0_, this.HOUSE_NAME as HOUSE_NAME0_, this.STREET as STREET0_, this.TOWN_OR_CITY as TOWN_OR17_0_, this.COUNTY_OR_STATE as COUNTY_18_0_, this.POSTCODE as POSTCODE0_, this.COUNTRY as COUNTRY0_ from ACCOUNT this where this.EMAIL_ADDRESS=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select emailaddre0_.ACCOUNT_ID as ACCOUNT_ID__, emailaddre0_.HIBERNATE_ID as HIBERNAT1___, emailaddre0_.HIBERNATE_ID as HIBERNAT1_0_, emailaddre0_.VERSION as VERSION0_, emailaddre0_.PK as PK0_, emailaddre0_.NAME as NAME0_ from EMAIL_ADDRESS_BOOK emailaddre0_ where emailaddre0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select elists0_.ACCOUNT_ID as ACCOUNT_ID__, elists0_.HIBERNATE_ID as HIBERNAT1___, elists0_.HIBERNATE_ID as HIBERNAT1_0_, elists0_.VERSION as VERSION0_, elists0_.PK as PK0_, elists0_.ELIST_NAME as ELIST_NAME0_, elists0_.EMAIL_MESSAGE as EMAIL_ME5_0_, elists0_.PASSWORD as PASSWORD0_, elists0_.HIDE_WHEN_PURCHASED as HIDE_WHE7_0_, elists0_.EMAIL_WHEN_ITEM_PURCHASED as EMAIL_WH8_0_, elists0_.EXPIRY_DATE as EXPIRY_D9_0_, elists0_.ACCOUNT_ID as ACCOUNT_ID0_ from ELIST elists0_ where elists0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - select elistitems0_.ELIST_ID as ELIST_ID__, elistitems0_.HIBERNATE_ID as HIBERNAT1___, elistitems0_.HIBERNATE_ID as HIBERNAT1_0_, elistitems0_.VERSION as VERSION0_, elistitems0_.PK as PK0_, elistitems0_.DESCRIPTION as DESCRIPT4_0_, elistitems0_.WHERE_TO_BUY as WHERE_TO5_0_, elistitems0_.LINK as LINK0_, elistitems0_.PRICE as PRICE0_, elistitems0_.sortOrder as sortOrder0_, elistitems0_.DISPLAY as DISPLAY0_, elistitems0_.PURCHASED as PURCHASED0_ from ELIST_ITEM elistitems0_ where elistitems0_.ELIST_ID=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - delete from ELIST where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)
14-10-2004 20:48:49 DEBUG - delete from ACCOUNT where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)
Debug level Hibernate log excerpt:
14-10-2004 20:52:03 INFO - Hibernate 2.1.6 (Environment.java:469)
14-10-2004 20:52:03 INFO - loaded properties from resource hibernate.properties: {hibernate.cglib.use_reflection_optimizer=true, hibernate.cache.provider_class=net.sf.ehcache.hibernate.Provider, hibernate.cache.use_query_cache=true, hibernate.max_fetch_depth=1, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.jdbc.use_streams_for_binary=true, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', hibernate.jdbc.batch_size=0, hibernate.proxool.pool_alias=pool1, hibernate.cache.region_prefix=hibernate.test, hibernate.connection.datasource=java:comp/env/jdbc/easytwo, hibernate.show_sql=true, hibernate.connection.pool_size=1} (Environment.java:503)
14-10-2004 20:52:03 INFO - using java.io streams to persist binary types (Environment.java:528)
14-10-2004 20:52:03 INFO - using CGLIB reflection optimizer (Environment.java:529)
14-10-2004 20:52:03 INFO - configuring from resource: /hibernate.cfg.xml (Configuration.java:895)
14-10-2004 20:52:03 INFO - Configuration resource: /hibernate.cfg.xml (Configuration.java:867)
14-10-2004 20:52:03 DEBUG - trying to locate
http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
14-10-2004 20:52:04 DEBUG - found
http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath (DTDEntityResolver.java:29)
14-10-2004 20:52:04 DEBUG - show_sql=true (Configuration.java:853)
14-10-2004 20:52:04 DEBUG - connection.datasource=java:comp/env/jdbc/easytwo (Configuration.java:853)
14-10-2004 20:52:04 DEBUG - dialect=net.sf.hibernate.dialect.MySQLDialect (Configuration.java:853)
14-10-2004 20:52:04 DEBUG - null<-org.dom4j.tree.DefaultAttribute@8acfc3 [Attribute: name resource value "com/easylists/Account.hbm.xml"] (Configuration.java:1012)
14-10-2004 20:52:04 INFO - Mapping resource: com/easylists/Account.hbm.xml (Configuration.java:331)
14-10-2004 20:52:04 DEBUG - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
14-10-2004 20:52:04 DEBUG - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
14-10-2004 20:52:04 INFO - Mapping class: com.easylists.Account -> ACCOUNT (Binder.java:229)
14-10-2004 20:52:05 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: active -> ACTIVE, type: boolean (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: hasBeenActivated -> HAS_BEEN_ACTIVATED, type: boolean (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: createdDate -> CREATED_DATE, type: timestamp (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: expiryDate -> EXPIRY_DATE, type: timestamp (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: title -> TITLE, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: forename -> FORENAME, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: surname -> SURNAME, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: emailAddress -> EMAIL_ADDRESS, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: password -> PASSWORD, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: quota -> QUOTA, type: integer (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: houseNumber -> HOUSE_NUMBER, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: houseName -> HOUSE_NAME, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: street -> STREET, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: townOrCity -> TOWN_OR_CITY, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: countyOrState -> COUNTY_OR_STATE, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: postcode -> POSTCODE, type: string (Binder.java:486)
14-10-2004 20:52:05 DEBUG - Mapped property: country -> COUNTRY, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: address -> HOUSE_NUMBER, HOUSE_NAME, STREET, TOWN_OR_CITY, COUNTY_OR_STATE, POSTCODE, COUNTRY, type: com.easylists.Address (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: elists, type: java.util.Set (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: emailAddressBooks, type: java.util.Set (Binder.java:486)
14-10-2004 20:52:07 DEBUG - null<-org.dom4j.tree.DefaultAttribute@1df5f21 [Attribute: name resource value "com/easylists/EList.hbm.xml"] (Configuration.java:1012)
14-10-2004 20:52:07 INFO - Mapping resource: com/easylists/EList.hbm.xml (Configuration.java:331)
14-10-2004 20:52:07 DEBUG - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
14-10-2004 20:52:07 DEBUG - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
14-10-2004 20:52:07 INFO - Mapping class: com.easylists.EList -> ELIST (Binder.java:229)
14-10-2004 20:52:07 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: elistName -> ELIST_NAME, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: emailMessage -> EMAIL_MESSAGE, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: password -> PASSWORD, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: hideWhenPurchased -> HIDE_WHEN_PURCHASED, type: boolean (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: sendEmailWhenItemPurchased -> EMAIL_WHEN_ITEM_PURCHASED, type: boolean (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: expiryDate -> EXPIRY_DATE, type: timestamp (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: account -> ACCOUNT_ID, type: com.easylists.Account (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: elistItems, type: java.util.Set (Binder.java:486)
14-10-2004 20:52:07 DEBUG - null<-org.dom4j.tree.DefaultAttribute@87e704 [Attribute: name resource value "com/easylists/EListItem.hbm.xml"] (Configuration.java:1012)
14-10-2004 20:52:07 INFO - Mapping resource: com/easylists/EListItem.hbm.xml (Configuration.java:331)
14-10-2004 20:52:07 DEBUG - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
14-10-2004 20:52:07 DEBUG - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
14-10-2004 20:52:07 INFO - Mapping class: com.easylists.EListItem -> ELIST_ITEM (Binder.java:229)
14-10-2004 20:52:07 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: description -> DESCRIPTION, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: whereToBuy -> WHERE_TO_BUY, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: link -> LINK, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: price -> PRICE, type: string (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: sortOrder -> sortOrder, type: integer (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: display -> DISPLAY, type: boolean (Binder.java:486)
14-10-2004 20:52:07 DEBUG - Mapped property: purchased -> PURCHASED, type: boolean (Binder.java:486)
14-10-2004 20:52:07 DEBUG - null<-org.dom4j.tree.DefaultAttribute@135f44e [Attribute: name resource value "com/easylists/EmailAddress.hbm.xml"] (Configuration.java:1012)
14-10-2004 20:52:07 INFO - Mapping resource: com/easylists/EmailAddress.hbm.xml (Configuration.java:331)
14-10-2004 20:52:08 DEBUG - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
14-10-2004 20:52:08 DEBUG - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
14-10-2004 20:52:08 INFO - Mapping class: com.easylists.EmailAddress -> EMAIL_ADDRESS (Binder.java:229)
14-10-2004 20:52:08 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
14-10-2004 20:52:08 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
14-10-2004 20:52:08 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
14-10-2004 20:52:08 DEBUG - Mapped property: emailAddress -> EMAIL_ADDRESS, type: string (Binder.java:486)
14-10-2004 20:52:08 DEBUG - null<-org.dom4j.tree.DefaultAttribute@20c906 [Attribute: name resource value "com/easylists/EmailAddressBook.hbm.xml"] (Configuration.java:1012)
14-10-2004 20:52:08 INFO - Mapping resource: com/easylists/EmailAddressBook.hbm.xml (Configuration.java:331)
14-10-2004 20:52:08 DEBUG - trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
14-10-2004 20:52:08 DEBUG - found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
14-10-2004 20:52:08 INFO - Mapping class: com.easylists.EmailAddressBook -> EMAIL_ADDRESS_BOOK (Binder.java:229)
14-10-2004 20:52:08 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
14-10-2004 20:52:08 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
14-10-2004 20:52:08 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
14-10-2004 20:52:08 DEBUG - Mapped property: name -> NAME, type: string (Binder.java:486)
14-10-2004 20:52:08 INFO - Mapping collection: com.easylists.EmailAddressBook.emailAddresses -> EML_ADDR_BKS_EMAIL_ADDRESSES (Binder.java:571)
14-10-2004 20:52:08 DEBUG - Mapped property: emailAddresses, type: java.util.Set (Binder.java:486)
14-10-2004 20:52:08 INFO - Configured SessionFactory: null (Configuration.java:1053)
14-10-2004 20:52:08 DEBUG - properties: {show_sql=true, java.vendor=Sun Microsystems Inc., catalina.base=D:\Apache Software Foundation\Tomcat 5.0, connection.datasource=java:comp/env/jdbc/easytwo, catalina.useNaming=true, os.name=Windows XP, sun.boot.class.path=D:\Apache Software Foundation\Tomcat 5.0\common\endorsed\xercesImpl.jar;D:\Apache Software Foundation\Tomcat 5.0\common\endorsed\xmlParserAPIs.jar;D:\j2sdk1.4.2_01\jre\lib\rt.jar;D:\j2sdk1.4.2_01\jre\lib\i18n.jar;D:\j2sdk1.4.2_01\jre\lib\sunrsasign.jar;D:\j2sdk1.4.2_01\jre\lib\jsse.jar;D:\j2sdk1.4.2_01\jre\lib\jce.jar;D:\j2sdk1.4.2_01\jre\lib\charsets.jar;D:\j2sdk1.4.2_01\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_01-b06, hibernate.cache.provider_class=net.sf.ehcache.hibernate.Provider, user.name=SYSTEM, shared.loader=${catalina.base}/shared/classes,${catalina.base}/shared/lib/*.jar, user.language=en, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, cactus.contextURL=http://127.0.0.1:8080/easy-lists, sun.boot.library.path=D:\j2sdk1.4.2_01\jre\bin, dialect=net.sf.hibernate.dialect.MySQLDialect, java.version=1.4.2_01, user.timezone=Europe/London, sun.arch.data.model=32, java.endorsed.dirs=D:\Apache Software Foundation\Tomcat 5.0\common\endorsed, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, user.country=GB, java.home=D:\j2sdk1.4.2_01\jre, java.vm.info=mixed mode, os.version=5.1, hibernate.connection.datasource=java:comp/env/jdbc/easytwo, path.separator=;, java.vm.version=1.4.2_01-b06, hibernate.max_fetch_depth=1, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, user.variant=, hibernate.jdbc.batch_size=0, cactus.jspRedirectorName=JspRedirector, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., hibernate.cache.region_prefix=hibernate.test, java.naming.factory.url.pkgs=org.apache.naming, user.home=C:\WINDOWS\system32\config\systemprofile, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', java.specification.vendor=Sun Microsystems Inc., java.library.path=D:\Apache Software Foundation\Tomcat 5.0\bin;.;C:\WINDOWS\System32;C:\WINDOWS;D:\Perl\bin\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;D:\apache-ant-1.5.4\bin;D:\j2sdk1.4.2_01\bin;D:\j2sdkee1.3.1\bin;D:\gpg, java.vendor.url=http://java.sun.com/, hibernate.proxool.pool_alias=pool1, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, common.loader=${catalina.home}/common/classes,${catalina.home}/common/endorsed/*.jar,${catalina.home}/common/lib/*.jar, hibernate.jdbc.use_streams_for_binary=true, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=D:\Apache Software Foundation\Tomcat 5.0\bin\bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=D:\Apache Software Foundation\Tomcat 5.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 1, hibernate.cache.use_query_cache=true, java.io.tmpdir=D:\Apache Software Foundation\Tomcat 5.0, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=${catalina.home}/server/classes,${catalina.home}/server/lib/*.jar, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=D:\j2sdk1.4.2_01\jre\lib\ext, cactus.filterRedirectorName=test/filterRedirector.jsp, user.dir=C:\WINDOWS\system32, line.separator=
, java.vm.name=Java HotSpot(TM) Server VM, cactus.servletRedirectorName=ServletRedirector, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.pool_size=1, hibernate.show_sql=true} (Configuration.java:1054)
14-10-2004 20:52:08 INFO - processing one-to-many association mappings (Configuration.java:627)
14-10-2004 20:52:08 DEBUG - Second pass for collection: com.easylists.Account.elists (Binder.java:1353)
14-10-2004 20:52:08 INFO - Mapping collection: com.easylists.Account.elists -> ELIST (Binder.java:1181)
14-10-2004 20:52:08 DEBUG - Mapped collection key: ACCOUNT_ID, one-to-many: com.easylists.EList (Binder.java:1368)
14-10-2004 20:52:08 DEBUG - Second pass for collection: com.easylists.Account.emailAddressBooks (Binder.java:1353)
14-10-2004 20:52:08 INFO - Mapping collection: com.easylists.Account.emailAddressBooks -> EMAIL_ADDRESS_BOOK (Binder.java:1181)
14-10-2004 20:52:08 DEBUG - Mapped collection key: ACCOUNT_ID, one-to-many: com.easylists.EmailAddressBook (Binder.java:1368)
14-10-2004 20:52:08 DEBUG - Second pass for collection: com.easylists.EList.elistItems (Binder.java:1353)
14-10-2004 20:52:08 INFO - Mapping collection: com.easylists.EList.elistItems -> ELIST_ITEM (Binder.java:1181)
14-10-2004 20:52:08 DEBUG - Mapped collection key: ELIST_ID, one-to-many: com.easylists.EListItem (Binder.java:1368)
14-10-2004 20:52:08 DEBUG - Second pass for collection: com.easylists.EmailAddressBook.emailAddresses (Binder.java:1353)
14-10-2004 20:52:08 DEBUG - Mapped collection key: EMAIL_ADDR_BOOK_ID, element: EMAIL_ADDRESS_ID, type: com.easylists.EmailAddress (Binder.java:1368)
14-10-2004 20:52:08 INFO - processing one-to-one association property references (Configuration.java:636)
14-10-2004 20:52:08 INFO - processing foreign key constraints (Configuration.java:661)
14-10-2004 20:52:08 DEBUG - resolving reference to class: com.easylists.EmailAddress (Configuration.java:678)
14-10-2004 20:52:08 DEBUG - resolving reference to class: com.easylists.EmailAddressBook (Configuration.java:678)
14-10-2004 20:52:08 DEBUG - resolving reference to class: com.easylists.Account (Configuration.java:678)
14-10-2004 20:52:08 DEBUG - resolving reference to class: com.easylists.Account (Configuration.java:678)
14-10-2004 20:52:08 DEBUG - resolving reference to class: com.easylists.EList (Configuration.java:678)
14-10-2004 20:52:09 INFO - Using dialect: net.sf.hibernate.dialect.MySQLDialect (Dialect.java:82)
14-10-2004 20:52:09 INFO - Maximim outer join fetch depth: 1 (SettingsFactory.java:59)
14-10-2004 20:52:09 INFO - Use outer join fetching: true (SettingsFactory.java:63)
14-10-2004 20:52:09 INFO - JNDI InitialContext properties:{} (NamingHelper.java:26)
14-10-2004 20:52:09 INFO - Using datasource: java:comp/env/jdbc/easytwo (DatasourceConnectionProvider.java:51)
14-10-2004 20:52:09 INFO - No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended) (TransactionManagerLookupFactory.java:33)
14-10-2004 20:52:09 INFO - Use scrollable result sets: true (SettingsFactory.java:103)
14-10-2004 20:52:09 INFO - Use JDBC3 getGeneratedKeys(): true (SettingsFactory.java:106)
14-10-2004 20:52:09 INFO - Optimize cache for minimal puts: false (SettingsFactory.java:109)
14-10-2004 20:52:09 INFO - echoing all SQL to stdout (SettingsFactory.java:115)
14-10-2004 20:52:09 INFO - Query language substitutions: {no='N', true=1, yes='Y', false=0} (SettingsFactory.java:118)
14-10-2004 20:52:09 INFO - cache provider: net.sf.ehcache.hibernate.Provider (SettingsFactory.java:129)
14-10-2004 20:52:09 INFO - query cache factory: net.sf.hibernate.cache.StandardQueryCacheFactory (SettingsFactory.java:141)
14-10-2004 20:52:09 INFO - instantiating and configuring caches (Configuration.java:1116)
14-10-2004 20:52:09 INFO - building session factory (SessionFactoryImpl.java:118)
14-10-2004 20:52:09 DEBUG - instantiating session factory with properties: {show_sql=true, java.vendor=Sun Microsystems Inc., catalina.base=D:\Apache Software Foundation\Tomcat 5.0, connection.datasource=java:comp/env/jdbc/easytwo, catalina.useNaming=true, os.name=Windows XP, sun.boot.class.path=D:\Apache Software Foundation\Tomcat 5.0\common\endorsed\xercesImpl.jar;D:\Apache Software Foundation\Tomcat 5.0\common\endorsed\xmlParserAPIs.jar;D:\j2sdk1.4.2_01\jre\lib\rt.jar;D:\j2sdk1.4.2_01\jre\lib\i18n.jar;D:\j2sdk1.4.2_01\jre\lib\sunrsasign.jar;D:\j2sdk1.4.2_01\jre\lib\jsse.jar;D:\j2sdk1.4.2_01\jre\lib\jce.jar;D:\j2sdk1.4.2_01\jre\lib\charsets.jar;D:\j2sdk1.4.2_01\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_01-b06, hibernate.cache.provider_class=net.sf.ehcache.hibernate.Provider, user.name=SYSTEM, shared.loader=${catalina.base}/shared/classes,${catalina.base}/shared/lib/*.jar, user.language=en, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, cactus.contextURL=http://127.0.0.1:8080/easy-lists, sun.boot.library.path=D:\j2sdk1.4.2_01\jre\bin, dialect=net.sf.hibernate.dialect.MySQLDialect, java.version=1.4.2_01, user.timezone=Europe/London, sun.arch.data.model=32, java.endorsed.dirs=D:\Apache Software Foundation\Tomcat 5.0\common\endorsed, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, user.country=GB, java.home=D:\j2sdk1.4.2_01\jre, java.vm.info=mixed mode, os.version=5.1, hibernate.connection.datasource=java:comp/env/jdbc/easytwo, path.separator=;, java.vm.version=1.4.2_01-b06, hibernate.max_fetch_depth=1, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, user.variant=, hibernate.jdbc.batch_size=0, cactus.jspRedirectorName=JspRedirector, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., hibernate.cache.region_prefix=hibernate.test, java.naming.factory.url.pkgs=org.apache.naming, user.home=C:\WINDOWS\system32\config\systemprofile, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', java.specification.vendor=Sun Microsystems Inc., java.library.path=D:\Apache Software Foundation\Tomcat 5.0\bin;.;C:\WINDOWS\System32;C:\WINDOWS;D:\Perl\bin\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;D:\apache-ant-1.5.4\bin;D:\j2sdk1.4.2_01\bin;D:\j2sdkee1.3.1\bin;D:\gpg, java.vendor.url=http://java.sun.com/, hibernate.proxool.pool_alias=pool1, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, common.loader=${catalina.home}/common/classes,${catalina.home}/common/endorsed/*.jar,${catalina.home}/common/lib/*.jar, hibernate.jdbc.use_streams_for_binary=true, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=D:\Apache Software Foundation\Tomcat 5.0\bin\bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=D:\Apache Software Foundation\Tomcat 5.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 1, hibernate.cache.use_query_cache=true, java.io.tmpdir=D:\Apache Software Foundation\Tomcat 5.0, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=${catalina.home}/server/classes,${catalina.home}/server/lib/*.jar, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=D:\j2sdk1.4.2_01\jre\lib\ext, cactus.filterRedirectorName=test/filterRedirector.jsp, user.dir=C:\WINDOWS\system32, line.separator=
, java.vm.name=Java HotSpot(TM) Server VM, cactus.servletRedirectorName=ServletRedirector, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.pool_size=1, hibernate.show_sql=true} (SessionFactoryImpl.java:124)
14-10-2004 20:52:10 INFO - reflection optimizer disabled for: com.easylists.EList, BulkBeanException: Property is private (property setElistItems) (ReflectHelper.java:186)
14-10-2004 20:52:10 INFO - reflection optimizer disabled for: com.easylists.Account, BulkBeanException: Property is private (property setElists) (ReflectHelper.java:186)
14-10-2004 20:52:10 INFO - reflection optimizer disabled for: com.easylists.EmailAddress, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
14-10-2004 20:52:10 INFO - reflection optimizer disabled for: com.easylists.EListItem, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
14-10-2004 20:52:10 INFO - reflection optimizer disabled for: com.easylists.EmailAddressBook, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
14-10-2004 20:52:10 DEBUG - initializing class SessionFactoryObjectFactory (SessionFactoryObjectFactory.java:39)
14-10-2004 20:52:10 DEBUG - registered: d2a1cd8bff98ff5000ff98ff553a0000 (unnamed) (SessionFactoryObjectFactory.java:76)
14-10-2004 20:52:10 INFO - Not binding factory to JNDI, no JNDI name configured (SessionFactoryObjectFactory.java:82)
14-10-2004 20:52:10 DEBUG - instantiated session factory (SessionFactoryImpl.java:195)
14-10-2004 20:52:10 INFO - starting update timestamps cache at region: net.sf.hibernate.cache.UpdateTimestampsCache (UpdateTimestampsCache.java:35)
14-10-2004 20:52:10 INFO - starting query cache at region: net.sf.hibernate.cache.StandardQueryCache (StandardQueryCache.java:41)
14-10-2004 20:52:11 DEBUG - opened session (SessionImpl.java:555)
14-10-2004 20:52:11 DEBUG - begin (JDBCTransaction.java:37)
14-10-2004 20:52:11 DEBUG - current autocommit status:true (JDBCTransaction.java:41)
14-10-2004 20:52:11 DEBUG - disabling autocommit (JDBCTransaction.java:43)
14-10-2004 20:52:11 DEBUG - saveOrUpdate() unsaved instance (SessionImpl.java:1387)
14-10-2004 20:52:11 DEBUG - saving [com.easylists.Account#<null>] (SessionImpl.java:825)
14-10-2004 20:52:11 DEBUG - executing insertions (SessionImpl.java:2309)
14-10-2004 20:52:11 DEBUG - processing cascades for: com.easylists.Account (Cascades.java:497)
14-10-2004 20:52:11 DEBUG - done processing cascades for: com.easylists.Account (Cascades.java:506)
14-10-2004 20:52:11 DEBUG - using initial version: 0 (Versioning.java:56)
14-10-2004 20:52:11 DEBUG - Wrapped collection in role: com.easylists.Account.elists (WrapVisitor.java:81)
14-10-2004 20:52:11 DEBUG - Wrapped collection in role: com.easylists.Account.emailAddressBooks (WrapVisitor.java:81)
14-10-2004 20:52:11 DEBUG - Inserting entity: com.easylists.Account (native id) (EntityPersister.java:490)
14-10-2004 20:52:11 DEBUG - Version: 0 (EntityPersister.java:491)
14-10-2004 20:52:11 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
14-10-2004 20:52:11 DEBUG - insert into ACCOUNT (VERSION, PK, ACTIVE, HAS_BEEN_ACTIVATED, CREATED_DATE, EXPIRY_DATE, TITLE, FORENAME, SURNAME, EMAIL_ADDRESS, PASSWORD, QUOTA, HOUSE_NUMBER, HOUSE_NAME, STREET, TOWN_OR_CITY, COUNTY_OR_STATE, POSTCODE, COUNTRY) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) (BatcherImpl.java:226)
14-10-2004 20:52:11 DEBUG - preparing statement (BatcherImpl.java:249)
14-10-2004 20:52:11 DEBUG - Dehydrating entity: [com.easylists.Account#<null>] (EntityPersister.java:388)
14-10-2004 20:52:11 DEBUG - Natively generated identity: 118 (AbstractEntityPersister.java:1236)
14-10-2004 20:52:11 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
14-10-2004 20:52:11 DEBUG - closing statement (BatcherImpl.java:269)
14-10-2004 20:52:11 DEBUG - processing cascades for: com.easylists.Account (Cascades.java:497)
14-10-2004 20:52:11 DEBUG - cascading to collection: com.easylists.Account.elists (Cascades.java:524)
14-10-2004 20:52:11 DEBUG - cascading to collection: com.easylists.Account.emailAddressBooks (Cascades.java:524)
14-10-2004 20:52:11 DEBUG - done processing cascades for: com.easylists.Account (Cascades.java:506)
14-10-2004 20:52:11 DEBUG - commit (JDBCTransaction.java:59)
14-10-2004 20:52:11 DEBUG - flushing session (SessionImpl.java:2246)
14-10-2004 20:52:11 DEBUG - processing cascades for: com.easylists.Account (Cascades.java:497)
14-10-2004 20:52:11 DEBUG - cascading to collection: com.easylists.Account.elists (Cascades.java:524)
14-10-2004 20:52:11 DEBUG - cascading to collection: com.easylists.Account.emailAddressBooks (Cascades.java:524)
14-10-2004 20:52:11 DEBUG - done processing cascades for: com.easylists.Account (Cascades.java:506)
14-10-2004 20:52:11 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
14-10-2004 20:52:11 DEBUG - Collection found: [com.easylists.Account.elists#118], was: [<unreferenced>] (SessionImpl.java:2884)
14-10-2004 20:52:11 DEBUG - Collection found: [com.easylists.Account.emailAddressBooks#118], was: [<unreferenced>] (SessionImpl.java:2884)
14-10-2004 20:52:11 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
14-10-2004 20:52:11 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
14-10-2004 20:52:11 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects (SessionImpl.java:2270)
14-10-2004 20:52:11 DEBUG - Flushed: 2 (re)creations, 0 updates, 0 removals to 2 collections (SessionImpl.java:2275)
14-10-2004 20:52:11 DEBUG - listing entities: (Printer.java:75)
14-10-2004 20:52:11 DEBUG - com.easylists.Account{active=false, createdDate=2004-10-14 20:52:02, forename=Paul, password=ImbFcQhSD+0=, hasBeenActivated=false, title=Mr, address=Address{country=UK, street=Chapel Street, postcode=BA128BZ, houseName=, countyOrState=Wiltshire, townOrCity=Warminster, houseNumber=15}, hibernateId=118, pk=688, version=0, expiryDate=null, surname=Taylor, emailAddressBooks=[], emailAddress=test@javacoda.com, quota=0, elists=[]} (Printer.java:82)
14-10-2004 20:52:11 DEBUG - executing flush (SessionImpl.java:2359)
14-10-2004 20:52:11 DEBUG - Inserting collection: [com.easylists.Account.emailAddressBooks#118] (AbstractCollectionPersister.java:508)
14-10-2004 20:52:11 DEBUG - collection was empty (AbstractCollectionPersister.java:539)
14-10-2004 20:52:11 DEBUG - post flush (SessionImpl.java:2824)
14-10-2004 20:52:11 DEBUG - transaction completion (SessionImpl.java:585)
14-10-2004 20:52:11 DEBUG - Invalidating space [ACCOUNT] (UpdateTimestampsCache.java:51)
14-10-2004 20:52:11 DEBUG - Invalidating space [ELIST] (UpdateTimestampsCache.java:51)
14-10-2004 20:52:11 DEBUG - Invalidating space [EMAIL_ADDRESS_BOOK] (UpdateTimestampsCache.java:51)
14-10-2004 20:52:11 DEBUG - re-enabling autocommit (JDBCTransaction.java:103)
14-10-2004 20:52:11 DEBUG - closing session (SessionImpl.java:573)
14-10-2004 20:52:11 DEBUG - disconnecting session (SessionImpl.java:3336)
14-10-2004 20:52:11 DEBUG - transaction completion (SessionImpl.java:585)
14-10-2004 20:52:11 DEBUG - opened session (SessionImpl.java:555)
14-10-2004 20:52:12 DEBUG - flushing session (SessionImpl.java:2246)
14-10-2004 20:52:12 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
14-10-2004 20:52:12 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
14-10-2004 20:52:12 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
14-10-2004 20:52:12 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects (SessionImpl.java:2270)
14-10-2004 20:52:12 DEBUG - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections (SessionImpl.java:2275)
14-10-2004 20:52:12 DEBUG - Dont need to execute flush (SessionImpl.java:1818)
14-10-2004 20:52:12 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
14-10-2004 20:52:12 DEBUG - select this.HIBERNATE_ID as HIBERNAT1_0_, this.VERSION as VERSION0_, this.PK as PK0_, this.ACTIVE as ACTIVE0_, this.HAS_BEEN_ACTIVATED as HAS_BEEN5_0_, this.CREATED_DATE as CREATED_6_0_, this.EXPIRY_DATE as EXPIRY_D7_0_, this.TITLE as TITLE0_, this.FORENAME as FORENAME0_, this.SURNAME as SURNAME0_, this.EMAIL_ADDRESS as EMAIL_A11_0_, this.PASSWORD as PASSWORD0_, this.QUOTA as QUOTA0_, this.HOUSE_NUMBER as HOUSE_N14_0_, this.HOUSE_NAME as HOUSE_NAME0_, this.STREET as STREET0_, this.TOWN_OR_CITY as TOWN_OR17_0_, this.COUNTY_OR_STATE as COUNTY_18_0_, this.POSTCODE as POSTCODE0_, this.COUNTRY as COUNTRY0_ from ACCOUNT this where this.EMAIL_ADDRESS=? (BatcherImpl.java:226)
14-10-2004 20:52:12 DEBUG - preparing statement (BatcherImpl.java:249)
14-10-2004 20:52:12 DEBUG - processing result set (Loader.java:277)
14-10-2004 20:52:12 DEBUG - result row: 118 (Loader.java:480)
14-10-2004 20:52:12 DEBUG - Initializing object from ResultSet: 118 (Loader.java:611)
14-10-2004 20:52:12 DEBUG - Hydrating entity: com.easylists.Account#118 (Loader.java:680)
14-10-2004 20:52:12 DEBUG - Version: 0 (SessionImpl.java:1910)
14-10-2004 20:52:12 DEBUG - done processing result set (1 rows) (Loader.java:294)
14-10-2004 20:52:12 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
14-10-2004 20:52:12 DEBUG - closing statement (BatcherImpl.java:269)
14-10-2004 20:52:12 DEBUG - total objects hydrated: 1 (Loader.java:314)
14-10-2004 20:52:12 DEBUG - resolving associations for [com.easylists.Account#118] (SessionImpl.java:2202)
14-10-2004 20:52:12 DEBUG - creating collection wrapper:[com.easylists.Account.elists#118] (SessionImpl.java:3933)
14-10-2004 20:52:12 DEBUG - creating collection wrapper:[com.easylists.Account.emailAddressBooks#118] (SessionImpl.java:3933)
14-10-2004 20:52:12 DEBUG - done materializing entity [com.easylists.Account#118] (SessionImpl.java:2226)
14-10-2004 20:52:12 DEBUG - initializing non-lazy collections (SessionImpl.java:3116)
14-10-2004 20:52:12 DEBUG - initializing collection [com.easylists.Account.emailAddressBooks#118] (SessionImpl.java:3260)
14-10-2004 20:52:12 DEBUG - checking second-level cache (SessionImpl.java:3261)
14-10-2004 20:52:12 DEBUG - collection not cached (SessionImpl.java:3267)
14-10-2004 20:52:12 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
14-10-2004 20:52:12 DEBUG - select emailaddre0_.ACCOUNT_ID as ACCOUNT_ID__, emailaddre0_.HIBERNATE_ID as HIBERNAT1___, emailaddre0_.HIBERNATE_ID as HIBERNAT1_0_, emailaddre0_.VERSION as VERSION0_, emailaddre0_.PK as PK0_, emailaddre0_.NAME as NAME0_ from EMAIL_ADDRESS_BOOK emailaddre0_ where emailaddre0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:52:12 DEBUG - preparing statement (BatcherImpl.java:249)
14-10-2004 20:52:12 DEBUG - result set contains (possibly empty) collection: [com.easylists.Account.emailAddressBooks#118] (Loader.java:402)
14-10-2004 20:52:12 DEBUG - uninitialized collection: initializing (SessionImpl.java:3018)
14-10-2004 20:52:12 DEBUG - processing result set (Loader.java:277)
14-10-2004 20:52:12 DEBUG - done processing result set (0 rows) (Loader.java:294)
14-10-2004 20:52:12 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
14-10-2004 20:52:12 DEBUG - closing statement (BatcherImpl.java:269)
14-10-2004 20:52:12 DEBUG - total objects hydrated: 0 (Loader.java:314)
14-10-2004 20:52:12 DEBUG - 1 collections were found in result set (SessionImpl.java:3077)
14-10-2004 20:52:12 DEBUG - collection fully initialized: [com.easylists.Account.emailAddressBooks#118] (SessionImpl.java:3095)
14-10-2004 20:52:12 DEBUG - 1 collections initialized (SessionImpl.java:3098)
14-10-2004 20:52:12 DEBUG - collection initialized (SessionImpl.java:3269)
14-10-2004 20:52:12 DEBUG - initializing collection [com.easylists.Account.elists#118] (SessionImpl.java:3260)
14-10-2004 20:52:12 DEBUG - checking second-level cache (SessionImpl.java:3261)
14-10-2004 20:52:12 DEBUG - collection not cached (SessionImpl.java:3267)
14-10-2004 20:52:12 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
14-10-2004 20:52:12 DEBUG - select elists0_.ACCOUNT_ID as ACCOUNT_ID__, elists0_.HIBERNATE_ID as HIBERNAT1___, elists0_.HIBERNATE_ID as HIBERNAT1_0_, elists0_.VERSION as VERSION0_, elists0_.PK as PK0_, elists0_.ELIST_NAME as ELIST_NAME0_, elists0_.EMAIL_MESSAGE as EMAIL_ME5_0_, elists0_.PASSWORD as PASSWORD0_, elists0_.HIDE_WHEN_PURCHASED as HIDE_WHE7_0_, elists0_.EMAIL_WHEN_ITEM_PURCHASED as EMAIL_WH8_0_, elists0_.EXPIRY_DATE as EXPIRY_D9_0_, elists0_.ACCOUNT_ID as ACCOUNT_ID0_ from ELIST elists0_ where elists0_.ACCOUNT_ID=? (BatcherImpl.java:226)
14-10-2004 20:52:12 DEBUG - preparing statement (BatcherImpl.java:249)
14-10-2004 20:52:12 DEBUG - result set contains (possibly empty) collection: [com.easylists.Account.elists#118] (Loader.java:402)
14-10-2004 20:52:12 DEBUG - uninitialized collection: initializing (SessionImpl.java:3018)
14-10-2004 20:52:12 DEBUG - processing result set (Loader.java:277)
14-10-2004 20:52:12 DEBUG - done processing result set (0 rows) (Loader.java:294)
14-10-2004 20:52:12 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
14-10-2004 20:52:12 DEBUG - closing statement (BatcherImpl.java:269)
14-10-2004 20:52:12 DEBUG - total objects hydrated: 0 (Loader.java:314)
14-10-2004 20:52:12 DEBUG - 1 collections were found in result set (SessionImpl.java:3077)
14-10-2004 20:52:12 DEBUG - collection fully initialized: [com.easylists.Account.elists#118] (SessionImpl.java:3095)
14-10-2004 20:52:12 DEBUG - 1 collections initialized (SessionImpl.java:3098)
14-10-2004 20:52:12 DEBUG - collection initialized (SessionImpl.java:3269)
14-10-2004 20:52:12 DEBUG - closing session (SessionImpl.java:573)
14-10-2004 20:52:12 DEBUG - disconnecting session (SessionImpl.java:3336)
14-10-2004 20:52:12 DEBUG - transaction completion (SessionImpl.java:585)
14-10-2004 20:52:12 DEBUG - opened session (SessionImpl.java:555)
14-10-2004 20:52:12 DEBUG - begin (JDBCTransaction.java:37)
14-10-2004 20:52:12 DEBUG - current autocommit status:true (JDBCTransaction.java:41)
14-10-2004 20:52:12 DEBUG - disabling autocommit (JDBCTransaction.java:43)
14-10-2004 20:52:12 DEBUG - saveOrUpdate() unsaved instance (SessionImpl.java:1387)
14-10-2004 20:52:12 DEBUG - saving [com.easylists.EList#<null>] (SessionImpl.java:825)
14-10-2004 20:52:12 DEBUG - executing insertions (SessionImpl.java:2309)
14-10-2004 20:52:12 DEBUG - processing cascades for: com.easylists.EList (Cascades.java:497)
14-10-2004 20:52:12 DEBUG - done processing cascades for: com.easylists.EList (Cascades.java:506)
14-10-2004 20:52:12 DEBUG - using initial version: 0 (Versioning.java:56)
14-10-2004 20:52:12 DEBUG - Wrapped collection in role: com.easylists.EList.elistItems (WrapVisitor.java:81)
14-10-2004 20:52:12 DEBUG - version unsaved-value strategy UNDEFINED (Cascades.java:396)
14-10-2004 20:52:12 DEBUG - id unsaved-value strategy NULL (Cascades.java:341)
14-10-2004 20:52:12 DEBUG - Inserting entity: com.easylists.EList (native id) (EntityPersister.java:490)
14-10-2004 20:52:12 DEBUG - Version: 0 (EntityPersister.java:491)
14-10-2004 20:52:12 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
14-10-2004 20:52:12 DEBUG - insert into ELIST (VERSION, PK, ELIST_NAME, EMAIL_MESSAGE, PASSWORD, HIDE_WHEN_PURCHASED, EMAIL_WHEN_ITEM_PURCHASED, EXPIRY_DATE, ACCOUNT_ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?) (BatcherImpl.java:226)
14-10-2004 20:52:12 DEBUG - preparing statement (BatcherImpl.java:249)
14-10-2004 20:52:12 DEBUG - Dehydrating entity: [com.easylists.EList#<null>] (EntityPersister.java:388)
14-10-2004 20:52:12 DEBUG - version unsaved-value strategy UNDEFINED (Cascades.java:396)
14-10-2004 20:52:12 DEBUG - id unsaved-value strategy NULL (Cascades.java:341)
14-10-2004 20:52:12 DEBUG - Natively generated identity: 20 (AbstractEntityPersister.java:1236)
14-10-2004 20:52:12 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
14-10-2004 20:52:12 DEBUG - closing statement (BatcherImpl.java:269)
14-10-2004 20:52:12 DEBUG - processing cascades for: com.easylists.EList (Cascades.java:497)
14-10-2004 20:52:12 DEBUG - cascading to collection: com.easylists.EList.elistItems (Cascades.java:524)
14-10-2004 20:52:12 DEBUG - done processing cascades for: com.easylists.EList (Cascades.java:506)
14-10-2004 20:52:12 DEBUG - commit (JDBCTransaction.java:59)
14-10-2004 20:52:12 DEBUG - flushing session (SessionImpl.java:2246)
14-10-2004 20:52:12 DEBUG - processing cascades for: com.easylists.EList (Cascades.java:497)
14-10-2004 20:52:12 DEBUG - cascading to collection: com.easylists.EList.elistItems (Cascades.java:524)
14-10-2004 20:52:12 DEBUG - done processing cascades for: com.easylists.EList (Cascades.java:506)
14-10-2004 20:52:12 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
14-10-2004 20:52:12 DEBUG - Collection found: [com.easylists.EList.elistItems#20], was: [<unreferenced>] (SessionImpl.java:2884)
14-10-2004 20:52:12 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
14-10-2004 20:52:12 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
14-10-2004 20:52:12 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects (SessionImpl.java:2270)
14-10-2004 20:52:12 DEBUG - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections (SessionImpl.java:2275)
14-10-2004 20:52:12 DEBUG - listing entities: (Printer.java:75)
14-10-2004 20:52:12 DEBUG - com.easylists.EList{password=null, expiryDate=null, sendEmailWhenItemPurchased=false, elistItems=[], account=Account#118, emailMessage=null, hideWhenPurchased=false, elistName=TEST, hibernateId=20, pk=584, version=0} (Printer.java:82)
14-10-2004 20:52:12 DEBUG - executing flush (SessionImpl.java:2359)
14-10-2004 20:52:12 DEBUG - Inserting collection: [com.easylists.EList.elistItems#20] (AbstractCollectionPersister.java:508)
14-10-2004 20:52:12 DEBUG - collection was empty (AbstractCollectionPersister.java:539)
14-10-2004 20:52:12 DEBUG - post flush (SessionImpl.java:2824)
14-10-2004 20:52:12 DEBUG - transaction completion (SessionImpl.java:585)
14-10-2004 20:52:12 DEBUG - Invalidating space [ELIST] (UpdateTimestampsCache.java:51)
14-10-2004 20:52:12 DEBUG - Invalidating space [ELIST_ITEM] (UpdateTimestampsCache.java:51)
14-10-2004 20:52:12 DEBUG - re-enabling autocommit (JDBCTransaction.java:103)
14-10-2004 20:52:12 DEBUG - closing session (SessionImpl.java:573)
14-10-2004 20:52:12 DEBUG - disconnecting session (SessionImpl.java:3336)
14-10-2004 20:52:12 DEBUG - transaction completion (SessionImpl.java:585)
14-10-2004 20:52:12 DEBUG - opened session (SessionImpl.java:555)
14-10-2004 20:52:12 DEBUG - beg