Too many SQL statements when loading graph

pellep · **Joined:** Tue Nov 11, 2003 3:13 pm **Posts:** 10

Hello!

We're having some performance problems when loading 100+ entities along with their associated objects. So I started to look at the generated SQL statements. It looks like there are far more statements than necessary.

I have, for example, a recursive "folder" structure mapped as follows:
<hibernate-mapping>
<class name="VoAlertRuleGroupImpl"
table="alert_rule_group_t">
<id name="groupId" column="alert_rule_group_id" unsaved-value="any" length="10">
<generator class="assigned"/>
</id>
<property name="name" length="35"/>
...
<bag name="children"
inverse="true"
cascade="all-delete-orphan">
<key column="alert_rule_group_parent_id"/>
<one-to-many class="VoAlertRuleGroupImpl"/>
</bag>
<many-to-one name="parent" column="alert_rule_group_parent_id"/>
</class>
</hibernate-mapping>

The table definition:
CREATE TABLE ALERT_RULE_GROUP_T
(
ALERT_RULE_GROUP_ID VARCHAR2(10) NOT NULL,
NAME VARCHAR2(35),
ALERT_RULE_GROUP_PARENT_ID VARCHAR2(10),
...
)

When I execute the following code, I get a parent with five children back:
vAlertRuleGroup = (VoAlertRuleGroup)vSession.get(VoAlertRuleGroup.class,pGroupId);

But Hibernate generates seven SQL queries to retrieve this, one to retrieve the parent and the other (it seems) to retrieve the children. And each of these six queries return five rows (ie all of the children).

Surely, something is wrong? In my configurations I hope. Outer-join fetching is true. I'm using Oracle 8 and Hibernate 2.1b5.

Any ideas anyone?

Regards,
Pelle P

emmanuel · **Posted:** Thu Jan 22, 2004 8:43 am

http://www.hibernate.org/ForumMailinglists/HowToAskForHelp

pellep · **Joined:** Tue Nov 11, 2003 3:13 pm **Posts:** 10

Please, what's wrong with my question? I've read the manual and faq:s and searched the posts for similar questions. With no result.

Regards,
Pelle P

emmanuel · **Posted:** Thu Jan 22, 2004 10:25 am

I was refering this part

Quote:

Give as much information as possible. Most of the forum posts do not give enough information, and we have to cobble together an answer out of half-guesses about what the user is doing. Give at least: the Hibernate version, your mapping documents, the Java code between sessionFactory.openSession() and session.close(), the full stack trace of any exception that occurs, and the name and version of the database you are using. A debug level Hibernate log excerpt is also appreciated (you have one already, right? you shouldn't be posting yet if you don't!).

jfifield · **Joined:** Tue Aug 26, 2003 3:09 pm **Posts:** 58

What about grandchildren?

I bet only the first of those 6 queries is getting the children of the parent, and the other five are getting the grandchildren (one for each of the 5 first generation children).

Joe

pellep · **Joined:** Tue Nov 11, 2003 3:13 pm **Posts:** 10

Ok. The code is really simple:
Session vSession = HibernateEngine.getInstance().getSession();
vAlertRuleGroup = (VoAlertRuleGroupImpl)vSession.get(VoAlertRuleGroupImpl.class,pGroupId);
HibernateEngine.getInstance().disconnect();

(HibernateEngine is no more than a threadlocal holder of Sessions)

It results in the following sql statements:
15:42:44,469 INFO [STDOUT] Hibernate: select voalertr1_.alert_rule_group_id as alert_rule_group_id0_, voalertr1_.ver_no as ver_no0_, voalertr1_.name as name0_, voalertr1_.user_code_ins as user_cod4_0_, voalertr1_.ins_date as ins_date0_, voalertr1_.user_code_upd as user_cod6_0_, voalertr1_.upd_date as upd_date0_, voalertr1_.alert_rule_group_parent_id as alert_ru8_0_, voalertr0_.alert_rule_group_id as alert_rule_group_id1_, voalertr0_.ver_no as ver_no1_, voalertr0_.name as name1_, voalertr0_.user_code_ins as user_cod4_1_, voalertr0_.ins_date as ins_date1_, voalertr0_.user_code_upd as user_cod6_1_, voalertr0_.upd_date as upd_date1_, voalertr0_.alert_rule_group_parent_id as alert_ru8_1_ from alert_rule_group_t voalertr0_, alert_rule_group_t voalertr1_ where voalertr0_.alert_rule_group_id=? and voalertr0_.alert_rule_group_parent_id=voalertr1_.alert_rule_group_id(+)
15:42:44,489 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
15:42:44,529 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
15:42:44,549 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
15:42:44,559 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
15:42:44,569 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
15:42:44,599 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?

And the debug log. Its huge but here it comes:

2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.SessionImpl] opened session
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.SessionImpl] object not resolved in any cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.persister.EntityPersister] Materializing entity: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select voalertr1_.alert_rule_group_id as alert_rule_group_id0_, voalertr1_.ver_no as ver_no0_, voalertr1_.name as name0_, voalertr1_.user_code_ins as user_cod4_0_, voalertr1_.ins_date as ins_date0_, voalertr1_.user_code_upd as user_cod6_0_, voalertr1_.upd_date as upd_date0_, voalertr1_.alert_rule_group_parent_id as alert_ru8_0_, voalertr0_.alert_rule_group_id as alert_rule_group_id1_, voalertr0_.ver_no as ver_no1_, voalertr0_.name as name1_, voalertr0_.user_code_ins as user_cod4_1_, voalertr0_.ins_date as ins_date1_, voalertr0_.user_code_upd as user_cod6_1_, voalertr0_.upd_date as upd_date1_, voalertr0_.alert_rule_group_parent_id as alert_ru8_1_ from alert_rule_group_t voalertr0_, alert_rule_group_t voalertr1_ where voalertr0_.alert_rule_group_id=? and voalertr0_.alert_rule_group_parent_id=voalertr1_.alert_rule_group_id(+)
2004-01-22 15:42:44,469 INFO [STDOUT] Hibernate: select voalertr1_.alert_rule_group_id as alert_rule_group_id0_, voalertr1_.ver_no as ver_no0_, voalertr1_.name as name0_, voalertr1_.user_code_ins as user_cod4_0_, voalertr1_.ins_date as ins_date0_, voalertr1_.user_code_upd as user_cod6_0_, voalertr1_.upd_date as upd_date0_, voalertr1_.alert_rule_group_parent_id as alert_ru8_0_, voalertr0_.alert_rule_group_id as alert_rule_group_id1_, voalertr0_.ver_no as ver_no1_, voalertr0_.name as name1_, voalertr0_.user_code_ins as user_cod4_1_, voalertr0_.ins_date as ins_date1_, voalertr0_.user_code_upd as user_cod6_1_, voalertr0_.upd_date as upd_date1_, voalertr0_.alert_rule_group_parent_id as alert_ru8_1_ from alert_rule_group_t voalertr0_, alert_rule_group_t voalertr1_ where voalertr0_.alert_rule_group_id=? and voalertr0_.alert_rule_group_parent_id=voalertr1_.alert_rule_group_id(+)
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,469 DEBUG [net.sf.hibernate.type.StringType] binding '1' to parameter: 1
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.StringType] returning null as column: alert_rule_group_id0_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.loader.Loader] result row: null, 1
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.loader.Loader] Initializing object from ResultSet: 1
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.loader.Loader] Hydrating entity: com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.IntegerType] returning '1' as column: ver_no1_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.StringType] returning 'roten' as column: name1_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.StringType] returning 'PUHA' as column: user_cod4_1_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.TimestampType] returning '12 december 2003 12:00:00' as column: ins_date1_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.StringType] returning 'PUHA' as column: user_cod6_1_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.TimestampType] returning '12 december 2002 12:00:00' as column: upd_date1_
2004-01-22 15:42:44,479 DEBUG [net.sf.hibernate.type.StringType] returning null as column: alert_ru8_1_
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (1 rows)
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 1
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.SessionImpl] resolving associations for [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.SessionImpl] collection not cached
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing collection [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,489 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,489 DEBUG [net.sf.hibernate.type.StringType] binding '1' to parameter: 1
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] uninitialized collection: initializing
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '2' as column: alert_rule_group_id0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] result row: 2
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] Initializing object from ResultSet: 2
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] Hydrating entity: com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#2
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.IntegerType] returning '5' as column: ver_no0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning 'opfhghgtz' as column: name0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning 'PUHA' as column: user_cod4_0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.TimestampType] returning '07 november 2003 12:00:00' as column: ins_date0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning 'KARB' as column: user_cod6_0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.TimestampType] returning '13 januari 2004 02:17:04' as column: upd_date0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8_0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8___
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] found row of collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] reading row
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '2' as column: alert_ru1___
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#2]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#2]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#2]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '574' as column: alert_rule_group_id0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] result row: 574
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] Initializing object from ResultSet: 574
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] Hydrating entity: com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#574
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.IntegerType] returning '0' as column: ver_no0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning 'JOHAN' as column: name0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning 'JNND' as column: user_cod4_0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.TimestampType] returning '08 januari 2004 02:48:00' as column: ins_date0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning 'JNND' as column: user_cod6_0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.TimestampType] returning '08 januari 2004 02:48:00' as column: upd_date0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8_0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8___
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] found row of collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] reading row
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '574' as column: alert_ru1___
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#574]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#574]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#574]
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.type.StringType] returning '579' as column: alert_rule_group_id0_
2004-01-22 15:42:44,499 DEBUG [net.sf.hibernate.loader.Loader] result row: 579
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.loader.Loader] Initializing object from ResultSet: 579
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.loader.Loader] Hydrating entity: com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#579
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.type.IntegerType] returning '0' as column: ver_no0_
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.type.StringType] returning 'Outgoing FY04' as column: name0_
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.type.StringType] returning 'mona' as column: user_cod4_0_
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.type.TimestampType] returning '09 januari 2004 03:24:16' as column: ins_date0_
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.type.StringType] returning 'mona' as column: user_cod6_0_
2004-01-22 15:42:44,509 DEBUG [net.sf.hibernate.type.TimestampType] returning '09 januari 2004 03:24:16' as column: upd_date0_
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8_0_
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8___
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.loader.Loader] found row of collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.impl.SessionImpl] reading row
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.type.StringType] returning '579' as column: alert_ru1___
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#579]
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#579]
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#579]
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.type.StringType] returning '572' as column: alert_rule_group_id0_
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.loader.Loader] result row: 572
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.loader.Loader] Initializing object from ResultSet: 572
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.loader.Loader] Hydrating entity: com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#572
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.type.IntegerType] returning '2' as column: ver_no0_
2004-01-22 15:42:44,519 DEBUG [net.sf.hibernate.type.StringType] returning 'bad boys and girls' as column: name0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning 'ALPR' as column: user_cod4_0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.TimestampType] returning '08 januari 2004 02:11:13' as column: ins_date0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning 'mona' as column: user_cod6_0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.TimestampType] returning '09 januari 2004 02:46:35' as column: upd_date0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8_0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8___
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] found row of collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] reading row
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '572' as column: alert_ru1___
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#572]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#572]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#572]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '873' as column: alert_rule_group_id0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] result row: 873
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] Initializing object from ResultSet: 873
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] Hydrating entity: com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#873
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.IntegerType] returning '0' as column: ver_no0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning 'UMAT28' as column: name0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning 'UMAT' as column: user_cod4_0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.TimestampType] returning '14 januari 2004 12:05:31' as column: ins_date0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning 'UMAT' as column: user_cod6_0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.TimestampType] returning '14 januari 2004 12:05:31' as column: upd_date0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8_0_
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '1' as column: alert_ru8___
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] found row of collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] reading row
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.type.StringType] returning '873' as column: alert_ru1___
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#873]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#873]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#873]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (5 rows)
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 5
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] resolving associations for [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#2]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] collection not cached
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing collection [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#2]
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,529 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,529 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.type.StringType] binding '2' to parameter: 1
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#2]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] uninitialized collection: initializing
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (0 rows)
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 0
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] collection fully initialized: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#2]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] 1 collections initialized
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] calling onLoad()
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] done materializing entity [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#2]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] resolving associations for [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#574]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] collection not cached
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing collection [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#574]
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,539 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,549 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,549 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,549 DEBUG [net.sf.hibernate.type.StringType] binding '574' to parameter: 1
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#574]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] uninitialized collection: initializing
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (0 rows)
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 0
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] collection fully initialized: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#574]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] 1 collections initialized
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] calling onLoad()
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] done materializing entity [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#574]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] resolving associations for [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#579]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] collection not cached
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing collection [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#579]
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,559 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,559 DEBUG [net.sf.hibernate.type.StringType] binding '579' to parameter: 1
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#579]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] uninitialized collection: initializing
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (0 rows)
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 0
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] collection fully initialized: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#579]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] 1 collections initialized
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] calling onLoad()
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] done materializing entity [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#579]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] resolving associations for [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#572]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] collection not cached
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing collection [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#572]
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,569 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,569 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,579 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,579 DEBUG [net.sf.hibernate.type.StringType] binding '572' to parameter: 1
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#572]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] uninitialized collection: initializing
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (0 rows)
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 0
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] collection fully initialized: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#572]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] 1 collections initialized
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] calling onLoad()
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] done materializing entity [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#572]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] resolving associations for [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#873]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] collection not cached
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] loading [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] attempting to resolve [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] resolved object in session cache [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing collection [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#873]
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.BatcherImpl] prepared statement get: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,599 INFO [STDOUT] Hibernate: select alert_ru0_.alert_rule_group_id as alert_ru1___, alert_ru0_.alert_rule_group_parent_id as alert_ru8___, alert_ru0_.alert_rule_group_id as alert_rule_group_id0_, alert_ru0_.ver_no as ver_no0_, alert_ru0_.name as name0_, alert_ru0_.user_code_ins as user_cod4_0_, alert_ru0_.ins_date as ins_date0_, alert_ru0_.user_code_upd as user_cod6_0_, alert_ru0_.upd_date as upd_date0_, alert_ru0_.alert_rule_group_parent_id as alert_ru8_0_ from alert_rule_group_t alert_ru0_ where alert_ru0_.alert_rule_group_parent_id=?
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.impl.BatcherImpl] preparing statement
2004-01-22 15:42:44,599 DEBUG [net.sf.hibernate.type.StringType] binding '873' to parameter: 1
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#873]
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] uninitialized collection: initializing
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.loader.Loader] processing result set
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.loader.Loader] done processing result set (0 rows)
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.BatcherImpl] closing statement
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.loader.Loader] total objects hydrated: 0
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] collection fully initialized: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#873]
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] 1 collections initialized
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] calling onLoad()
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] done materializing entity [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#873]
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] collection fully initialized: [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl.children#1]
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] 1 collections initialized
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] calling onLoad()
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] done materializing entity [com.ikea.ebcopfc01.opfc.vo.impl.VoAlertRuleGroupImpl#1]
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] initializing non-lazy collections
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] disconnecting session
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] transaction completion
2004-01-22 15:42:44,609 DEBUG [net.sf.hibernate.impl.SessionImpl] closing session

emmanuel · **Posted:** Thu Jan 22, 2004 11:07 am

It's probably always a good idea to proxy a recursive class.

In your case, Hibernate has to load parent and all of it Granpa

michael · **Posted:** Thu Jan 22, 2004 12:14 pm

The statement is not the same in every case, as you can see when you look at the " binding 'xxx' to parameter: 1" part in the logs. If you dont use lazy loading and load the parent, Hibernate will load the parent, the children and try to load the grandchildren.