problem with cascade-all-delete-orphan

javacoda · **Posted:** Sat Oct 16, 2004 11:18 am

Hi All, I am really puzzled as to why the testSucceeds test passes & correctly removes the orphan, but the testFails code doesn't work. The difference between the two tests is that in the successful test I reload the Account Object from the database before removing the EList. Why should I have to reload the parent Object (the Account) from the database before removing the child Object (the EList) for the "delete-orphan" to work?

Any help appreciated. I have been reading Hibernate In Action & wrote my code based on what I have learned from the book & I thought that both of these tests should work! I'm really puzzled. Please help :-)

Thanks
Paul

Code:

public void testSucceeds() throws Exception {
        // get test Account
        Account account1 = TestConstants.getTestAccount();
        //save the account
        HibernateUtil.saveObject(account1);

        // reload the account
        accountPK = account1.getPk();
        Account account2 = (Account) HibernateUtil.findByPk(accountPK, Account.class);
        // get a test EList
        EList elist1 = TestConstants.getTestEList("TEST");
        // add it to the account
        account2.addEList(elist1);
        // save the elist
        HibernateUtil.saveObject(elist1);
        // account should contain 1 EList
        assertTrue(account2.getElists().contains(elist1));

        // reload the account again
        Account account3 = (Account) HibernateUtil.findByPk(account1.getPk(), Account.class);
        // remove the EList
        assertTrue(account3.getElists().remove(elist1));
        // save the account
        HibernateUtil.saveObject(account3);
        // try to load the EList
        EList elist2 = (EList) HibernateUtil.findByPk(elist1.getPk(), EList.class);
        // the elist should be null as it should have been deleted
        assertNull(elist2);
    }

    public void testFails() throws Exception {
        // get test Account
        Account account1 = TestConstants.getTestAccount();
        //save the account
        HibernateUtil.saveObject(account1);

        // reload the account
        accountPK = account1.getPk();
        Account account2 = (Account) HibernateUtil.findByPk(accountPK, Account.class);
        // get a test EList
        EList elist1 = TestConstants.getTestEList("TEST");
        // add it to the account
        account2.addEList(elist1);
        // save the elist
        HibernateUtil.saveObject(elist1);
        // account should contain 1 EList
        assertTrue(account2.getElists().contains(elist1));

        // remove the EList without having reloaded the account
        assertTrue(account2.getElists().remove(elist1));
        // save the account
        HibernateUtil.saveObject(account2);
        // try to load the EList
        EList elist2 = (EList) HibernateUtil.findByPk(elist1.getPk(), EList.class);
        // the elist should be null as it should have been deleted
        assertNull(elist2);
    }

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():
Methods From HibernateUtil Class

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);
        }
    }

    public static void deleteObject(Object ob) throws PersistenceException {
        try {
            Session session = null;
            Transaction tx = null;
            try {
                session = HibernateUtil.openSession();
                tx = session.beginTransaction();
                session.delete(ob);
                tx.commit();
            }
            catch (HibernateException e) {
                if (tx != null) {
                    tx.rollback();
                }
                throw new PersistenceException(e);
            }
            finally {
                closeSession(session);
            }
        }
        catch (HibernateException e) {
            throw new PersistenceException(e);
        }
    }


    public static Object findByPk(Long pk, Class type) throws PersistenceException {
        LOGGER.debug("HibernateUtil.findByPk");
        LOGGER.debug("pk = " + pk);
        LOGGER.debug("type = " + type);
        Session session = null;
        try {
            session = HibernateUtil.openSession();
            Criteria criteria = session.createCriteria(type);
            criteria.add(Expression.eq("pk", pk));
            return criteria.uniqueResult();
        } catch (HibernateException e) {
            throw new PersistenceException(e);
        } finally {
            HibernateUtil.closeSession(session);
        }
    }

Full stack trace of any exception that occurs:

Name and version of the database you are using:
MySQL 4.0

The generated SQL (show_sql=true):
attached to following post

Debug level Hibernate log excerpt:
attached to following post

javacoda · **Posted:** Sat Oct 16, 2004 11:20 am

The generated SQL (show_sql=true):
16-10-2004 16:13:09 INFO - Hibernate 2.1.6 (Environment.java:469)
16-10-2004 16:13:09 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)
16-10-2004 16:13:09 INFO - using java.io streams to persist binary types (Environment.java:528)
16-10-2004 16:13:09 INFO - using CGLIB reflection optimizer (Environment.java:529)
16-10-2004 16:13:09 INFO - configuring from resource: /hibernate.cfg.xml (Configuration.java:895)
16-10-2004 16:13:09 INFO - Configuration resource: /hibernate.cfg.xml (Configuration.java:867)
16-10-2004 16:13:09 INFO - Mapping resource: com/easylists/Account.hbm.xml (Configuration.java:331)
16-10-2004 16:13:10 INFO - Mapping class: com.easylists.Account -> ACCOUNT (Binder.java:229)
16-10-2004 16:13:10 INFO - Mapping resource: com/easylists/EList.hbm.xml (Configuration.java:331)
16-10-2004 16:13:11 INFO - Mapping class: com.easylists.EList -> ELIST (Binder.java:229)
16-10-2004 16:13:11 INFO - Mapping resource: com/easylists/EListItem.hbm.xml (Configuration.java:331)
16-10-2004 16:13:11 INFO - Mapping class: com.easylists.EListItem -> ELIST_ITEM (Binder.java:229)
16-10-2004 16:13:11 INFO - Mapping resource: com/easylists/EmailAddress.hbm.xml (Configuration.java:331)
16-10-2004 16:13:11 INFO - Mapping class: com.easylists.EmailAddress -> EMAIL_ADDRESS (Binder.java:229)
16-10-2004 16:13:11 INFO - Mapping resource: com/easylists/EmailAddressBook.hbm.xml (Configuration.java:331)
16-10-2004 16:13:11 INFO - Mapping class: com.easylists.EmailAddressBook -> EMAIL_ADDRESS_BOOK (Binder.java:229)
16-10-2004 16:13:11 INFO - Mapping collection: com.easylists.EmailAddressBook.emailAddresses ->

EML_ADDR_BKS_EMAIL_ADDRESSES (Binder.java:571)
16-10-2004 16:13:11 INFO - Configured SessionFactory: null (Configuration.java:1053)
16-10-2004 16:13:11 INFO - processing one-to-many association mappings (Configuration.java:627)
16-10-2004 16:13:11 INFO - Mapping collection: com.easylists.Account.elists -> ELIST (Binder.java:1181)
16-10-2004 16:13:11 INFO - Mapping collection: com.easylists.Account.emailAddressBooks -> EMAIL_ADDRESS_BOOK

(Binder.java:1181)
16-10-2004 16:13:11 INFO - Mapping collection: com.easylists.EList.elistItems -> ELIST_ITEM (Binder.java:1181)
16-10-2004 16:13:11 INFO - processing one-to-one association property references (Configuration.java:636)
16-10-2004 16:13:11 INFO - processing foreign key constraints (Configuration.java:661)
16-10-2004 16:13:11 INFO - Using dialect: net.sf.hibernate.dialect.MySQLDialect (Dialect.java:82)
16-10-2004 16:13:11 INFO - Maximim outer join fetch depth: 1 (SettingsFactory.java:59)
16-10-2004 16:13:11 INFO - Use outer join fetching: true (SettingsFactory.java:63)
16-10-2004 16:13:11 INFO - JNDI InitialContext properties:{} (NamingHelper.java:26)
16-10-2004 16:13:11 INFO - Using datasource: java:comp/env/jdbc/easytwo (DatasourceConnectionProvider.java:51)
16-10-2004 16:13:12 INFO - No TransactionManagerLookup configured (in JTA environment, use of process level

read-write cache is not recommended) (TransactionManagerLookupFactory.java:33)
16-10-2004 16:13:12 INFO - Use scrollable result sets: true (SettingsFactory.java:103)
16-10-2004 16:13:12 INFO - Use JDBC3 getGeneratedKeys(): true (SettingsFactory.java:106)
16-10-2004 16:13:12 INFO - Optimize cache for minimal puts: false (SettingsFactory.java:109)
16-10-2004 16:13:12 INFO - echoing all SQL to stdout (SettingsFactory.java:115)
16-10-2004 16:13:12 INFO - Query language substitutions: {no='N', true=1, yes='Y', false=0}

(SettingsFactory.java:118)
16-10-2004 16:13:12 INFO - cache provider: net.sf.ehcache.hibernate.Provider (SettingsFactory.java:129)
16-10-2004 16:13:12 INFO - query cache factory: net.sf.hibernate.cache.StandardQueryCacheFactory

(SettingsFactory.java:141)
16-10-2004 16:13:12 INFO - instantiating and configuring caches (Configuration.java:1116)
16-10-2004 16:13:12 INFO - building session factory (SessionFactoryImpl.java:118)
16-10-2004 16:13:13 INFO - reflection optimizer disabled for: com.easylists.EmailAddressBook,

IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
16-10-2004 16:13:13 INFO - reflection optimizer disabled for: com.easylists.EmailAddress, IllegalArgumentException:

Cannot find matching method/constructor (ReflectHelper.java:186)
16-10-2004 16:13:13 INFO - reflection optimizer disabled for: com.easylists.Account, BulkBeanException: Property is

private (property setElists) (ReflectHelper.java:186)
16-10-2004 16:13:13 INFO - reflection optimizer disabled for: com.easylists.EList, BulkBeanException: Property is

private (property setElistItems) (ReflectHelper.java:186)
16-10-2004 16:13:13 INFO - reflection optimizer disabled for: com.easylists.EListItem, IllegalArgumentException:

Cannot find matching method/constructor (ReflectHelper.java:186)
16-10-2004 16:13:14 INFO - Not binding factory to JNDI, no JNDI name configured

(SessionFactoryObjectFactory.java:82)
16-10-2004 16:13:14 INFO - starting update timestamps cache at region: net.sf.hibernate.cache.UpdateTimestampsCache

(UpdateTimestampsCache.java:35)
16-10-2004 16:13:14 INFO - starting query cache at region: net.sf.hibernate.cache.StandardQueryCache

(StandardQueryCache.java:41)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:14 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 DEBUG - delete from ELIST where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)
16-10-2004 16:13:15 DEBUG - select this.HIBERNATE_ID as HIBERNAT1_1_, this.VERSION as VERSION1_, this.PK as PK1_,

this.ELIST_NAME as ELIST_NAME1_, this.EMAIL_MESSAGE as EMAIL_ME5_1_, this.PASSWORD as PASSWORD1_,

this.HIDE_WHEN_PURCHASED as HIDE_WHE7_1_, this.EMAIL_WHEN_ITEM_PURCHASED as EMAIL_WH8_1_, this.EXPIRY_DATE as

EXPIRY_D9_1_, this.ACCOUNT_ID as ACCOUNT_ID1_, account1_.HIBERNATE_ID as HIBERNAT1_0_, account1_.VERSION as

VERSION0_, account1_.PK as PK0_, account1_.ACTIVE as ACTIVE0_, account1_.HAS_BEEN_ACTIVATED as HAS_BEEN5_0_,

account1_.CREATED_DATE as CREATED_6_0_, account1_.EXPIRY_DATE as EXPIRY_D7_0_, account1_.TITLE as TITLE0_,

account1_.FORENAME as FORENAME0_, account1_.SURNAME as SURNAME0_, account1_.EMAIL_ADDRESS as EMAIL_A11_0_,

account1_.PASSWORD as PASSWORD0_, account1_.QUOTA as QUOTA0_, account1_.HOUSE_NUMBER as HOUSE_N14_0_,

account1_.HOUSE_NAME as HOUSE_NAME0_, account1_.STREET as STREET0_, account1_.TOWN_OR_CITY as TOWN_OR17_0_,

account1_.COUNTY_OR_STATE as COUNTY_18_0_, account1_.POSTCODE as POSTCODE0_, account1_.COUNTRY as COUNTRY0_ from

ELIST this left outer join ACCOUNT account1_ on this.ACCOUNT_ID=account1_.HIBERNATE_ID where this.PK=?

(BatcherImpl.java:226)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 DEBUG - delete from ACCOUNT where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 DEBUG - select this.HIBERNATE_ID as HIBERNAT1_1_, this.VERSION as VERSION1_, this.PK as PK1_,

this.ELIST_NAME as ELIST_NAME1_, this.EMAIL_MESSAGE as EMAIL_ME5_1_, this.PASSWORD as PASSWORD1_,

this.HIDE_WHEN_PURCHASED as HIDE_WHE7_1_, this.EMAIL_WHEN_ITEM_PURCHASED as EMAIL_WH8_1_, this.EXPIRY_DATE as

EXPIRY_D9_1_, this.ACCOUNT_ID as ACCOUNT_ID1_, account1_.HIBERNATE_ID as HIBERNAT1_0_, account1_.VERSION as

VERSION0_, account1_.PK as PK0_, account1_.ACTIVE as ACTIVE0_, account1_.HAS_BEEN_ACTIVATED as HAS_BEEN5_0_,

account1_.CREATED_DATE as CREATED_6_0_, account1_.EXPIRY_DATE as EXPIRY_D7_0_, account1_.TITLE as TITLE0_,

account1_.FORENAME as FORENAME0_, account1_.SURNAME as SURNAME0_, account1_.EMAIL_ADDRESS as EMAIL_A11_0_,

account1_.PASSWORD as PASSWORD0_, account1_.QUOTA as QUOTA0_, account1_.HOUSE_NUMBER as HOUSE_N14_0_,

account1_.HOUSE_NAME as HOUSE_NAME0_, account1_.STREET as STREET0_, account1_.TOWN_OR_CITY as TOWN_OR17_0_,

account1_.COUNTY_OR_STATE as COUNTY_18_0_, account1_.POSTCODE as POSTCODE0_, account1_.COUNTRY as COUNTRY0_ from

ELIST this left outer join ACCOUNT account1_ on this.ACCOUNT_ID=account1_.HIBERNATE_ID where this.PK=?

(BatcherImpl.java:226)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 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)
16-10-2004 16:13:15 DEBUG - delete from ELIST where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)
16-10-2004 16:13:15 DEBUG - delete from ACCOUNT where HIBERNATE_ID=? and VERSION=? (BatcherImpl.java:226)

Debug level Hibernate log excerpt:

16-10-2004 16:18:08 INFO - Hibernate 2.1.6 (Environment.java:469)
16-10-2004 16:18:08 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)
16-10-2004 16:18:08 INFO - using java.io streams to persist binary types (Environment.java:528)
16-10-2004 16:18:08 INFO - using CGLIB reflection optimizer (Environment.java:529)
16-10-2004 16:18:08 INFO - configuring from resource: /hibernate.cfg.xml (Configuration.java:895)
16-10-2004 16:18:08 INFO - Configuration resource: /hibernate.cfg.xml (Configuration.java:867)
16-10-2004 16:18:08 DEBUG - trying to locate http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
16-10-2004 16:18:08 DEBUG - found http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath (DTDEntityResolver.java:29)
16-10-2004 16:18:08 DEBUG - show_sql=true (Configuration.java:853)
16-10-2004 16:18:08 DEBUG - connection.datasource=java:comp/env/jdbc/easytwo (Configuration.java:853)
16-10-2004 16:18:08 DEBUG - dialect=net.sf.hibernate.dialect.MySQLDialect (Configuration.java:853)
16-10-2004 16:18:08 DEBUG - null<-org.dom4j.tree.DefaultAttribute@1672bbb [Attribute: name resource value "com/easylists/Account.hbm.xml"] (Configuration.java:1012)
16-10-2004 16:18:08 INFO - Mapping resource: com/easylists/Account.hbm.xml (Configuration.java:331)
16-10-2004 16:18:08 DEBUG - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
16-10-2004 16:18:08 DEBUG - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
16-10-2004 16:18:09 INFO - Mapping class: com.easylists.Account -> ACCOUNT (Binder.java:229)
16-10-2004 16:18:09 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: active -> ACTIVE, type: boolean (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: hasBeenActivated -> HAS_BEEN_ACTIVATED, type: boolean (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: createdDate -> CREATED_DATE, type: timestamp (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: expiryDate -> EXPIRY_DATE, type: timestamp (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: title -> TITLE, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: forename -> FORENAME, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: surname -> SURNAME, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: emailAddress -> EMAIL_ADDRESS, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: password -> PASSWORD, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: quota -> QUOTA, type: integer (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: houseNumber -> HOUSE_NUMBER, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: houseName -> HOUSE_NAME, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: street -> STREET, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: townOrCity -> TOWN_OR_CITY, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: countyOrState -> COUNTY_OR_STATE, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: postcode -> POSTCODE, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: country -> COUNTRY, type: string (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: address -> HOUSE_NUMBER, HOUSE_NAME, STREET, TOWN_OR_CITY, COUNTY_OR_STATE, POSTCODE, COUNTRY, type: com.easylists.Address (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: elists, type: java.util.Set (Binder.java:486)
16-10-2004 16:18:09 DEBUG - Mapped property: emailAddressBooks, type: java.util.Set (Binder.java:486)
16-10-2004 16:18:09 DEBUG - null<-org.dom4j.tree.DefaultAttribute@c1a0eb [Attribute: name resource value "com/easylists/EList.hbm.xml"] (Configuration.java:1012)
16-10-2004 16:18:09 INFO - Mapping resource: com/easylists/EList.hbm.xml (Configuration.java:331)
16-10-2004 16:18:09 DEBUG - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
16-10-2004 16:18:09 DEBUG - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
16-10-2004 16:18:10 INFO - Mapping class: com.easylists.EList -> ELIST (Binder.java:229)
16-10-2004 16:18:10 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: elistName -> ELIST_NAME, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: emailMessage -> EMAIL_MESSAGE, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: password -> PASSWORD, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: hideWhenPurchased -> HIDE_WHEN_PURCHASED, type: boolean (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: sendEmailWhenItemPurchased -> EMAIL_WHEN_ITEM_PURCHASED, type: boolean (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: expiryDate -> EXPIRY_DATE, type: timestamp (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: account -> ACCOUNT_ID, type: com.easylists.Account (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: elistItems, type: java.util.Set (Binder.java:486)
16-10-2004 16:18:10 DEBUG - null<-org.dom4j.tree.DefaultAttribute@1be8711 [Attribute: name resource value "com/easylists/EListItem.hbm.xml"] (Configuration.java:1012)
16-10-2004 16:18:10 INFO - Mapping resource: com/easylists/EListItem.hbm.xml (Configuration.java:331)
16-10-2004 16:18:10 DEBUG - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
16-10-2004 16:18:10 DEBUG - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
16-10-2004 16:18:10 INFO - Mapping class: com.easylists.EListItem -> ELIST_ITEM (Binder.java:229)
16-10-2004 16:18:10 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: description -> DESCRIPTION, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: whereToBuy -> WHERE_TO_BUY, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: link -> LINK, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: price -> PRICE, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: sortOrder -> sortOrder, type: integer (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: display -> DISPLAY, type: boolean (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: purchased -> PURCHASED, type: boolean (Binder.java:486)
16-10-2004 16:18:10 DEBUG - null<-org.dom4j.tree.DefaultAttribute@10e687b [Attribute: name resource value "com/easylists/EmailAddress.hbm.xml"] (Configuration.java:1012)
16-10-2004 16:18:10 INFO - Mapping resource: com/easylists/EmailAddress.hbm.xml (Configuration.java:331)
16-10-2004 16:18:10 DEBUG - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
16-10-2004 16:18:10 DEBUG - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
16-10-2004 16:18:10 INFO - Mapping class: com.easylists.EmailAddress -> EMAIL_ADDRESS (Binder.java:229)
16-10-2004 16:18:10 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: emailAddress -> EMAIL_ADDRESS, type: string (Binder.java:486)
16-10-2004 16:18:10 DEBUG - null<-org.dom4j.tree.DefaultAttribute@1e91a4d [Attribute: name resource value "com/easylists/EmailAddressBook.hbm.xml"] (Configuration.java:1012)
16-10-2004 16:18:10 INFO - Mapping resource: com/easylists/EmailAddressBook.hbm.xml (Configuration.java:331)
16-10-2004 16:18:10 DEBUG - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/ (DTDEntityResolver.java:20)
16-10-2004 16:18:10 DEBUG - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath (DTDEntityResolver.java:29)
16-10-2004 16:18:10 INFO - Mapping class: com.easylists.EmailAddressBook -> EMAIL_ADDRESS_BOOK (Binder.java:229)
16-10-2004 16:18:10 DEBUG - Mapped property: hibernateId -> HIBERNATE_ID, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: version -> VERSION, type: integer (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: pk -> PK, type: long (Binder.java:486)
16-10-2004 16:18:10 DEBUG - Mapped property: name -> NAME, type: string (Binder.java:486)
16-10-2004 16:18:10 INFO - Mapping collection: com.easylists.EmailAddressBook.emailAddresses -> EML_ADDR_BKS_EMAIL_ADDRESSES (Binder.java:571)
16-10-2004 16:18:10 DEBUG - Mapped property: emailAddresses, type: java.util.Set (Binder.java:486)
16-10-2004 16:18:10 INFO - Configured SessionFactory: null (Configuration.java:1053)
16-10-2004 16:18:10 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)
16-10-2004 16:18:10 INFO - processing one-to-many association mappings (Configuration.java:627)
16-10-2004 16:18:10 DEBUG - Second pass for collection: com.easylists.Account.elists (Binder.java:1353)
16-10-2004 16:18:10 INFO - Mapping collection: com.easylists.Account.elists -> ELIST (Binder.java:1181)
16-10-2004 16:18:10 DEBUG - Mapped collection key: ACCOUNT_ID, one-to-many: com.easylists.EList (Binder.java:1368)
16-10-2004 16:18:10 DEBUG - Second pass for collection: com.easylists.Account.emailAddressBooks (Binder.java:1353)
16-10-2004 16:18:10 INFO - Mapping collection: com.easylists.Account.emailAddressBooks -> EMAIL_ADDRESS_BOOK (Binder.java:1181)
16-10-2004 16:18:10 DEBUG - Mapped collection key: ACCOUNT_ID, one-to-many: com.easylists.EmailAddressBook (Binder.java:1368)
16-10-2004 16:18:10 DEBUG - Second pass for collection: com.easylists.EList.elistItems (Binder.java:1353)
16-10-2004 16:18:10 INFO - Mapping collection: com.easylists.EList.elistItems -> ELIST_ITEM (Binder.java:1181)
16-10-2004 16:18:10 DEBUG - Mapped collection key: ELIST_ID, one-to-many: com.easylists.EListItem (Binder.java:1368)
16-10-2004 16:18:10 DEBUG - Second pass for collection: com.easylists.EmailAddressBook.emailAddresses (Binder.java:1353)
16-10-2004 16:18:10 DEBUG - Mapped collection key: EMAIL_ADDR_BOOK_ID, element: EMAIL_ADDRESS_ID, type: com.easylists.EmailAddress (Binder.java:1368)
16-10-2004 16:18:10 INFO - processing one-to-one association property references (Configuration.java:636)
16-10-2004 16:18:10 INFO - processing foreign key constraints (Configuration.java:661)
16-10-2004 16:18:10 DEBUG - resolving reference to class: com.easylists.EmailAddress (Configuration.java:678)
16-10-2004 16:18:10 DEBUG - resolving reference to class: com.easylists.EmailAddressBook (Configuration.java:678)
16-10-2004 16:18:10 DEBUG - resolving reference to class: com.easylists.Account (Configuration.java:678)
16-10-2004 16:18:10 DEBUG - resolving reference to class: com.easylists.Account (Configuration.java:678)
16-10-2004 16:18:10 DEBUG - resolving reference to class: com.easylists.EList (Configuration.java:678)
16-10-2004 16:18:10 INFO - Using dialect: net.sf.hibernate.dialect.MySQLDialect (Dialect.java:82)
16-10-2004 16:18:10 INFO - Maximim outer join fetch depth: 1 (SettingsFactory.java:59)
16-10-2004 16:18:10 INFO - Use outer join fetching: true (SettingsFactory.java:63)
16-10-2004 16:18:10 INFO - JNDI InitialContext properties:{} (NamingHelper.java:26)
16-10-2004 16:18:10 INFO - Using datasource: java:comp/env/jdbc/easytwo (DatasourceConnectionProvider.java:51)
16-10-2004 16:18:10 INFO - No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended) (TransactionManagerLookupFactory.java:33)
16-10-2004 16:18:10 INFO - Use scrollable result sets: true (SettingsFactory.java:103)
16-10-2004 16:18:10 INFO - Use JDBC3 getGeneratedKeys(): true (SettingsFactory.java:106)
16-10-2004 16:18:10 INFO - Optimize cache for minimal puts: false (SettingsFactory.java:109)
16-10-2004 16:18:10 INFO - echoing all SQL to stdout (SettingsFactory.java:115)
16-10-2004 16:18:10 INFO - Query language substitutions: {no='N', true=1, yes='Y', false=0} (SettingsFactory.java:118)
16-10-2004 16:18:10 INFO - cache provider: net.sf.ehcache.hibernate.Provider (SettingsFactory.java:129)
16-10-2004 16:18:11 INFO - query cache factory: net.sf.hibernate.cache.StandardQueryCacheFactory (SettingsFactory.java:141)
16-10-2004 16:18:11 INFO - instantiating and configuring caches (Configuration.java:1116)
16-10-2004 16:18:11 INFO - building session factory (SessionFactoryImpl.java:118)
16-10-2004 16:18:11 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)
16-10-2004 16:18:11 INFO - reflection optimizer disabled for: com.easylists.Account, BulkBeanException: Property is private (property setElists) (ReflectHelper.java:186)
16-10-2004 16:18:11 INFO - reflection optimizer disabled for: com.easylists.EListItem, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
16-10-2004 16:18:12 INFO - reflection optimizer disabled for: com.easylists.EmailAddress, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
16-10-2004 16:18:12 INFO - reflection optimizer disabled for: com.easylists.EList, BulkBeanException: Property is private (property setElistItems) (ReflectHelper.java:186)
16-10-2004 16:18:12 INFO - reflection optimizer disabled for: com.easylists.EmailAddressBook, IllegalArgumentException: Cannot find matching method/constructor (ReflectHelper.java:186)
16-10-2004 16:18:12 DEBUG - initializing class SessionFactoryObjectFactory (SessionFactoryObjectFactory.java:39)
16-10-2004 16:18:12 DEBUG - registered: d2a1cd8bffa2513300ffa2513a5d0000 (unnamed) (SessionFactoryObjectFactory.java:76)
16-10-2004 16:18:12 INFO - Not binding factory to JNDI, no JNDI name configured (SessionFactoryObjectFactory.java:82)
16-10-2004 16:18:12 DEBUG - instantiated session factory (SessionFactoryImpl.java:195)
16-10-2004 16:18:12 INFO - starting update timestamps cache at region: net.sf.hibernate.cache.UpdateTimestampsCache (UpdateTimestampsCache.java:35)
16-10-2004 16:18:13 INFO - starting query cache at region: net.sf.hibernate.cache.StandardQueryCache (StandardQueryCache.java:41)
16-10-2004 16:18:13 DEBUG - opened session (SessionImpl.java:555)
16-10-2004 16:18:13 DEBUG - begin (JDBCTransaction.java:37)
16-10-2004 16:18:13 DEBUG - current autocommit status:true (JDBCTransaction.java:41)
16-10-2004 16:18:13 DEBUG - disabling autocommit (JDBCTransaction.java:43)
16-10-2004 16:18:13 DEBUG - saveOrUpdate() unsaved instance (SessionImpl.java:1387)
16-10-2004 16:18:13 DEBUG - saving [com.easylists.Account#<null>] (SessionImpl.java:825)
16-10-2004 16:18:13 DEBUG - executing insertions (SessionImpl.java:2309)
16-10-2004 16:18:13 DEBUG - processing cascades for: com.easylists.Account (Cascades.java:497)
16-10-2004 16:18:13 DEBUG - done processing cascades for: com.easylists.Account (Cascades.java:506)
16-10-2004 16:18:13 DEBUG - using initial version: 0 (Versioning.java:56)
16-10-2004 16:18:13 DEBUG - Wrapped collection in role: com.easylists.Account.elists (WrapVisitor.java:81)
16-10-2004 16:18:13 DEBUG - Wrapped collection in role: com.easylists.Account.emailAddressBooks (WrapVisitor.java:81)
16-10-2004 16:18:13 DEBUG - Inserting entity: com.easylists.Account (native id) (EntityPersister.java:490)
16-10-2004 16:18:13 DEBUG - Version: 0 (EntityPersister.java:491)
16-10-2004 16:18:13 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
16-10-2004 16:18:13 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)
16-10-2004 16:18:13 DEBUG - preparing statement (BatcherImpl.java:249)
16-10-2004 16:18:13 DEBUG - Dehydrating entity: [com.easylists.Account#<null>] (EntityPersister.java:388)
16-10-2004 16:18:13 DEBUG - Natively generated identity: 130 (AbstractEntityPersister.java:1236)
16-10-2004 16:18:13 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
16-10-2004 16:18:13 DEBUG - closing statement (BatcherImpl.java:269)
16-10-2004 16:18:13 DEBUG - processing cascades for: com.easylists.Account (Cascades.java:497)
16-10-2004 16:18:13 DEBUG - cascading to collection: com.easylists.Account.elists (Cascades.java:524)
16-10-2004 16:18:13 DEBUG - cascading to collection: com.easylists.Account.emailAddressBooks (Cascades.java:524)
16-10-2004 16:18:13 DEBUG - done processing cascades for: com.easylists.Account (Cascades.java:506)
16-10-2004 16:18:13 DEBUG - commit (JDBCTransaction.java:59)
16-10-2004 16:18:13 DEBUG - flushing session (SessionImpl.java:2246)
16-10-2004 16:18:13 DEBUG - processing cascades for: com.easylists.Account (Cascades.java:497)
16-10-2004 16:18:13 DEBUG - cascading to collection: com.easylists.Account.elists (Cascades.java:524)
16-10-2004 16:18:13 DEBUG - cascading to collection: com.easylists.Account.emailAddressBooks (Cascades.java:524)
16-10-2004 16:18:13 DEBUG - done processing cascades for: com.easylists.Account (Cascades.java:506)
16-10-2004 16:18:13 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
16-10-2004 16:18:13 DEBUG - Collection found: [com.easylists.Account.elists#130], was: [<unreferenced>] (SessionImpl.java:2884)
16-10-2004 16:18:13 DEBUG - Collection found: [com.easylists.Account.emailAddressBooks#130], was: [<unreferenced>] (SessionImpl.java:2884)
16-10-2004 16:18:13 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
16-10-2004 16:18:13 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
16-10-2004 16:18:13 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects (SessionImpl.java:2270)
16-10-2004 16:18:13 DEBUG - Flushed: 2 (re)creations, 0 updates, 0 removals to 2 collections (SessionImpl.java:2275)
16-10-2004 16:18:13 DEBUG - listing entities: (Printer.java:75)
16-10-2004 16:18:13 DEBUG - com.easylists.Account{active=false, createdDate=2004-10-16 16:18:07, 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=130, pk=702, version=0, expiryDate=null, surname=Taylor, emailAddressBooks=[], emailAddress=test@javacoda.com, quota=0, elists=[]} (Printer.java:82)
16-10-2004 16:18:13 DEBUG - executing flush (SessionImpl.java:2359)
16-10-2004 16:18:13 DEBUG - Inserting collection: [com.easylists.Account.emailAddressBooks#130] (AbstractCollectionPersister.java:508)
16-10-2004 16:18:13 DEBUG - collection was empty (AbstractCollectionPersister.java:539)
16-10-2004 16:18:13 DEBUG - post flush (SessionImpl.java:2824)
16-10-2004 16:18:13 DEBUG - transaction completion (SessionImpl.java:585)
16-10-2004 16:18:13 DEBUG - Invalidating space [ACCOUNT] (UpdateTimestampsCache.java:51)
16-10-2004 16:18:13 DEBUG - Invalidating space [ELIST] (UpdateTimestampsCache.java:51)
16-10-2004 16:18:13 DEBUG - Invalidating space [EMAIL_ADDRESS_BOOK] (UpdateTimestampsCache.java:51)
16-10-2004 16:18:13 DEBUG - re-enabling autocommit (JDBCTransaction.java:103)
16-10-2004 16:18:13 DEBUG - closing session (SessionImpl.java:573)
16-10-2004 16:18:13 DEBUG - disconnecting session (SessionImpl.java:3336)
16-10-2004 16:18:13 DEBUG - transaction completion (SessionImpl.java:585)
16-10-2004 16:18:13 DEBUG - opened session (SessionImpl.java:555)
16-10-2004 16:18:13 DEBUG - flushing session (SessionImpl.java:2246)
16-10-2004 16:18:13 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
16-10-2004 16:18:13 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
16-10-2004 16:18:13 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
16-10-2004 16:18:13 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects (SessionImpl.java:2270)
16-10-2004 16:18:13 DEBUG - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections (SessionImpl.java:2275)
16-10-2004 16:18:13 DEBUG - Dont need to execute flush (SessionImpl.java:1818)
16-10-2004 16:18:13 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
16-10-2004 16:18:13 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)
16-10-2004 16:18:13 DEBUG - preparing statement (BatcherImpl.java:249)
16-10-2004 16:18:13 DEBUG - processing result set (Loader.java:277)
16-10-2004 16:18:13 DEBUG - result row: 130 (Loader.java:480)
16-10-2004 16:18:13 DEBUG - Initializing object from ResultSet: 130 (Loader.java:611)
16-10-2004 16:18:13 DEBUG - Hydrating entity: com.easylists.Account#130 (Loader.java:680)
16-10-2004 16:18:13 DEBUG - Version: 0 (SessionImpl.java:1910)
16-10-2004 16:18:13 DEBUG - done processing result set (1 rows) (Loader.java:294)
16-10-2004 16:18:13 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
16-10-2004 16:18:13 DEBUG - closing statement (BatcherImpl.java:269)
16-10-2004 16:18:13 DEBUG - total objects hydrated: 1 (Loader.java:314)
16-10-2004 16:18:13 DEBUG - resolving associations for [com.easylists.Account#130] (SessionImpl.java:2202)
16-10-2004 16:18:13 DEBUG - creating collection wrapper:[com.easylists.Account.elists#130] (SessionImpl.java:3933)
16-10-2004 16:18:13 DEBUG - creating collection wrapper:[com.easylists.Account.emailAddressBooks#130] (SessionImpl.java:3933)
16-10-2004 16:18:13 DEBUG - done materializing entity [com.easylists.Account#130] (SessionImpl.java:2226)
16-10-2004 16:18:13 DEBUG - initializing non-lazy collections (SessionImpl.java:3116)
16-10-2004 16:18:13 DEBUG - initializing collection [com.easylists.Account.emailAddressBooks#130] (SessionImpl.java:3260)
16-10-2004 16:18:13 DEBUG - checking second-level cache (SessionImpl.java:3261)
16-10-2004 16:18:13 DEBUG - collection not cached (SessionImpl.java:3267)
16-10-2004 16:18:13 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
16-10-2004 16:18:13 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)
16-10-2004 16:18:13 DEBUG - preparing statement (BatcherImpl.java:249)
16-10-2004 16:18:13 DEBUG - result set contains (possibly empty) collection: [com.easylists.Account.emailAddressBooks#130] (Loader.java:402)
16-10-2004 16:18:13 DEBUG - uninitialized collection: initializing (SessionImpl.java:3018)
16-10-2004 16:18:13 DEBUG - processing result set (Loader.java:277)
16-10-2004 16:18:13 DEBUG - done processing result set (0 rows) (Loader.java:294)
16-10-2004 16:18:13 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
16-10-2004 16:18:13 DEBUG - closing statement (BatcherImpl.java:269)
16-10-2004 16:18:13 DEBUG - total objects hydrated: 0 (Loader.java:314)
16-10-2004 16:18:13 DEBUG - 1 collections were found in result set (SessionImpl.java:3077)
16-10-2004 16:18:13 DEBUG - collection fully initialized: [com.easylists.Account.emailAddressBooks#130] (SessionImpl.java:3095)
16-10-2004 16:18:13 DEBUG - 1 collections initialized (SessionImpl.java:3098)
16-10-2004 16:18:13 DEBUG - collection initialized (SessionImpl.java:3269)
16-10-2004 16:18:13 DEBUG - initializing collection [com.easylists.Account.elists#130] (SessionImpl.java:3260)
16-10-2004 16:18:13 DEBUG - checking second-level cache (SessionImpl.java:3261)
16-10-2004 16:18:13 DEBUG - collection not cached (SessionImpl.java:3267)
16-10-2004 16:18:13 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
16-10-2004 16:18:13 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)
16-10-2004 16:18:13 DEBUG - preparing statement (BatcherImpl.java:249)
16-10-2004 16:18:13 DEBUG - result set contains (possibly empty) collection: [com.easylists.Account.elists#130] (Loader.java:402)
16-10-2004 16:18:13 DEBUG - uninitialized collection: initializing (SessionImpl.java:3018)
16-10-2004 16:18:13 DEBUG - processing result set (Loader.java:277)
16-10-2004 16:18:13 DEBUG - done processing result set (0 rows) (Loader.java:294)
16-10-2004 16:18:13 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
16-10-2004 16:18:13 DEBUG - closing statement (BatcherImpl.java:269)
16-10-2004 16:18:13 DEBUG - total objects hydrated: 0 (Loader.java:314)
16-10-2004 16:18:13 DEBUG - 1 collections were found in result set (SessionImpl.java:3077)
16-10-2004 16:18:13 DEBUG - collection fully initialized: [com.easylists.Account.elists#130] (SessionImpl.java:3095)
16-10-2004 16:18:13 DEBUG - 1 collections initialized (SessionImpl.java:3098)
16-10-2004 16:18:13 DEBUG - collection initialized (SessionImpl.java:3269)
16-10-2004 16:18:13 DEBUG - closing session (SessionImpl.java:573)
16-10-2004 16:18:13 DEBUG - disconnecting session (SessionImpl.java:3336)
16-10-2004 16:18:13 DEBUG - transaction completion (SessionImpl.java:585)
16-10-2004 16:18:14 DEBUG - opened session (SessionImpl.java:555)
16-10-2004 16:18:14 DEBUG - begin (JDBCTransaction.java:37)
16-10-2004 16:18:14 DEBUG - current autocommit status:true (JDBCTransaction.java:41)
16-10-2004 16:18:14 DEBUG - disabling autocommit (JDBCTransaction.java:43)
16-10-2004 16:18:14 DEBUG - saveOrUpdate() unsaved instance (SessionImpl.java:1387)
16-10-2004 16:18:14 DEBUG - saving [com.easylists.EList#<null>] (SessionImpl.java:825)
16-10-2004 16:18:14 DEBUG - executing insertions (SessionImpl.java:2309)
16-10-2004 16:18:14 DEBUG - processing cascades for: com.easylists.EList (Cascades.java:497)
16-10-2004 16:18:14 DEBUG - done processing cascades for: com.easylists.EList (Cascades.java:506)
16-10-2004 16:18:14 DEBUG - using initial version: 0 (Versioning.java:56)
16-10-2004 16:18:14 DEBUG - Wrapped collection in role: com.easylists.EList.elistItems (WrapVisitor.java:81)
16-10-2004 16:18:14 DEBUG - version unsaved-value strategy UNDEFINED (Cascades.java:396)
16-10-2004 16:18:14 DEBUG - id unsaved-value strategy NULL (Cascades.java:341)
16-10-2004 16:18:14 DEBUG - Inserting entity: com.easylists.EList (native id) (EntityPersister.java:490)
16-10-2004 16:18:14 DEBUG - Version: 0 (EntityPersister.java:491)
16-10-2004 16:18:14 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
16-10-2004 16:18:14 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)
16-10-2004 16:18:14 DEBUG - preparing statement (BatcherImpl.java:249)
16-10-2004 16:18:14 DEBUG - Dehydrating entity: [com.easylists.EList#<null>] (EntityPersister.java:388)
16-10-2004 16:18:14 DEBUG - version unsaved-value strategy UNDEFINED (Cascades.java:396)
16-10-2004 16:18:14 DEBUG - id unsaved-value strategy NULL (Cascades.java:341)
16-10-2004 16:18:14 DEBUG - Natively generated identity: 9 (AbstractEntityPersister.java:1236)
16-10-2004 16:18:14 DEBUG - done closing: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:207)
16-10-2004 16:18:14 DEBUG - closing statement (BatcherImpl.java:269)
16-10-2004 16:18:14 DEBUG - processing cascades for: com.easylists.EList (Cascades.java:497)
16-10-2004 16:18:14 DEBUG - cascading to collection: com.easylists.EList.elistItems (Cascades.java:524)
16-10-2004 16:18:14 DEBUG - done processing cascades for: com.easylists.EList (Cascades.java:506)
16-10-2004 16:18:14 DEBUG - commit (JDBCTransaction.java:59)
16-10-2004 16:18:14 DEBUG - flushing session (SessionImpl.java:2246)
16-10-2004 16:18:14 DEBUG - processing cascades for: com.easylists.EList (Cascades.java:497)
16-10-2004 16:18:14 DEBUG - cascading to collection: com.easylists.EList.elistItems (Cascades.java:524)
16-10-2004 16:18:14 DEBUG - done processing cascades for: com.easylists.EList (Cascades.java:506)
16-10-2004 16:18:14 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
16-10-2004 16:18:14 DEBUG - Collection found: [com.easylists.EList.elistItems#9], was: [<unreferenced>] (SessionImpl.java:2884)
16-10-2004 16:18:14 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
16-10-2004 16:18:14 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
16-10-2004 16:18:14 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects (SessionImpl.java:2270)
16-10-2004 16:18:14 DEBUG - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections (SessionImpl.java:2275)
16-10-2004 16:18:14 DEBUG - listing entities: (Printer.java:75)
16-10-2004 16:18:14 DEBUG - com.easylists.EList{password=null, expiryDate=null, sendEmailWhenItemPurchased=false, elistItems=[], account=Account#130, emailMessage=null, hideWhenPurchased=false, elistName=TEST, hibernateId=9, pk=596, version=0} (Printer.java:82)
16-10-2004 16:18:14 DEBUG - executing flush (SessionImpl.java:2359)
16-10-2004 16:18:14 DEBUG - Inserting collection: [com.easylists.EList.elistItems#9] (AbstractCollectionPersister.java:508)
16-10-2004 16:18:14 DEBUG - collection was empty (AbstractCollectionPersister.java:539)
16-10-2004 16:18:14 DEBUG - post flush (SessionImpl.java:2824)
16-10-2004 16:18:14 DEBUG - transaction completion (SessionImpl.java:585)
16-10-2004 16:18:14 DEBUG - Invalidating space [ELIST] (UpdateTimestampsCache.java:51)
16-10-2004 16:18:14 DEBUG - Invalidating space [ELIST_ITEM] (UpdateTimestampsCache.java:51)
16-10-2004 16:18:14 DEBUG - re-enabling autocommit (JDBCTransaction.java:103)
16-10-2004 16:18:14 DEBUG - closing session (SessionImpl.java:573)
16-10-2004 16:18:14 DEBUG - disconnecting session (SessionImpl.java:3336)
16-10-2004 16:18:14 DEBUG - transaction completion (SessionImpl.java:585)
16-10-2004 16:18:14 DEBUG - opened session (SessionImpl.java:555)
16-10-2004 16:18:14 DEBUG - flushing session (SessionImpl.java:2246)
16-10-2004 16:18:14 DEBUG - Flushing entities and processing referenced collections (SessionImpl.java:2439)
16-10-2004 16:18:14 DEBUG - Processing unreferenced collections (SessionImpl.java:2780)
16-10-2004 16:18:14 DEBUG - Scheduling collection removes/(re)creates/updates (SessionImpl.java:2794)
16-10-2004 16:18:14 DEBUG - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects (SessionImpl.java:2270)
16-10-2004 16:18:14 DEBUG - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections (SessionImpl.java:2275)
16-10-2004 16:18:14 DEBUG - Dont need to execute flush (SessionImpl.java:1818)
16-10-2004 16:18:14 DEBUG - about to open: 0 open PreparedStatements, 0 open ResultSets (BatcherImpl.java:200)
16-10-2004 16:18:14 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)
16-10-2004 16:18:14 DEBUG - preparing statement (BatcherImpl.java:249)
16-10-2004 16:18:14 DEBUG - processing result set (Loader.java:277)
16-10-2004 16:18:14 DEBUG - result row: 130 (Loader.java:480)
16-10-2004 16:18:14 DEBUG - Initializing object from ResultSet: 130 (Loader.java:611)
1

anthony · **Posted:** Sat Oct 16, 2004 11:25 am

simplify and re post

javacoda · **Posted:** Sat Oct 16, 2004 12:29 pm

Hi Anthony, thanks for your reply. I cannot simplify the tests as they are the minimum needed to reproduce the conditions. I have simplified the mapping documents here. Is that enough? The test cases are both commented at each step. If this still isn't simplified enough could you please give me a hint as to what else I need to simplify?

Many Thanks
Paul :-)

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>
       

        <set name="elists" table="ELIST" cascade="all-delete-orphan" inverse="true" >
            <key column="ACCOUNT_ID"/>
            <one-to-many class="com.easylists.EList"/>
        </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>        

        <many-to-one name="account" class="com.easylists.Account" column="ACCOUNT_ID" not-null="true"/>        

    </class>

</hibernate-mapping>

[/list]