I am setting a hibernate filter on a collection of target objects to filter by effective date. I am using a criteria query to bring back my graph of objects and when the SQL is generated, an extra question mark appears after the where keyword causing a grammar exception:

select targets0_.MEASURE_ID as MEASURE13_7_, targets0_.MEASURE_VERSION as MEASURE14_7_, targets0_.TARGET_ID as TARGET1_7_, targets0_.TARGET_VERSION as TARGET2_7_, targets0_.TARGET_ID as TARGET1_6_, targets0_.TARGET_VERSION as TARGET2_6_, targets0_.TARGET_DESCRIPTION as TARGET3_21_6_, targets0_.TARGET_FREQUENCY as TARGET4_21_6_, targets0_.TARGET_RANGE_START_DATE as TARGET5_21_6_, targets0_.TARGET_RANGE_END_DATE as TARGET6_21_6_, targets0_.TARGET_START_DATE as TARGET7_21_6_, targets0_.TARGET_END_DATE as TARGET8_21_6_, targets0_.TARGET_START_ACTUAL_ID as TARGET9_21_6_, targets0_.TARGET_END_ACTUAL_ID as TARGET10_21_6_, targets0_.TARGET_SEGMENT_NAME as TARGET11_21_6_, targets0_.COMMENT_ID as COMMENT12_21_6_, actual1_.ACTUAL_ID as ACTUAL1_0_, actual1_.VALUE_ID as VALUE2_1_0_, actual1_.UNIT_ID as UNIT3_1_0_, actual1_.COMMENT_ID as COMMENT4_1_0_, abstractva2_.VALUE_ID as VALUE1_1_, abstractva2_.QUANTITATIVE_VALUE as QUANTITA3_0_1_, abstractva2_.QUALITATIVE_VALUE as QUALITAT4_0_1_, abstractva2_.DISCRIMINATOR as DISCRIMI2_1_, unit3_.UNIT_ID as UNIT1_2_, unit3_.UNIT_LAST_UPDATE as UNIT2_15_2_, unit3_.UNIT_NAME as UNIT3_15_2_, unit3_.UNIT_ABBREVIATION as UNIT4_15_2_, comment4_.COMMENT_ID as COMMENT1_3_, comment4_.COMMENT_TITLE as COMMENT2_10_3_, comment4_.COMMENT_TEXT as COMMENT3_10_3_, actual5_.ACTUAL_ID as ACTUAL1_4_, actual5_.VALUE_ID as VALUE2_1_4_, actual5_.UNIT_ID as UNIT3_1_4_, actual5_.COMMENT_ID as COMMENT4_1_4_, comment6_.COMMENT_ID as COMMENT1_5_, comment6_.COMMENT_TITLE as COMMENT2_10_5_, comment6_.COMMENT_TEXT as COMMENT3_10_5_ from TARGET targets0_ inner join ACTUALS actual1_ on targets0_.TARGET_START_ACTUAL_ID=actual1_.ACTUAL_ID left outer join ACTUAL_VALUES abstractva2_ on actual1_.VALUE_ID=abstractva2_.VALUE_ID left outer join UNIT unit3_ on actual1_.UNIT_ID=unit3_.UNIT_ID left outer join COMMENTS comment4_ on actual1_.COMMENT_ID=comment4_.COMMENT_ID inner join ACTUALS actual5_ on targets0_.TARGET_END_ACTUAL_ID=actual5_.ACTUAL_ID left outer join COMMENTS comment6_ on targets0_.COMMENT_ID=comment6_.COMMENT_ID where ? targets0_.TARGET_START_DATE and targets0_.TARGET_END_DATE and targets0_.MEASURE_ID=? and targets0_.MEASURE_VERSION=?

Has anyone encountered this issue before or is there a bug in the dialect?

Thanks for your help.

Hibernate version: 3.0.5

Mapping documents:

Code:

<?xml version="1.0" encoding="UTF-8"?>
<!DOCTYPE hibernate-mapping PUBLIC
   "-//Hibernate/Hibernate Mapping DTD 3.0//EN"
   "http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">

<hibernate-mapping>
   <class name="net.hcsc.service.perfmngmt.measure.Measure" table="MEASURE" select-before-update="true" lazy="false">
      <composite-id name="measureId" class="net.hcsc.service.perfmngmt.measure.MeasureId" unsaved-value="undefined">
         <key-property name="id" column="MEASURE_ID" type="string"/>
         <key-property name="version" column="MEASURE_VERSION" type="integer"/>
      </composite-id>
      <!--<timestamp name="updateDateTime" column="MEASURE_LAST_UPDATE" unsaved-value="undefined"/>-->
      <property name="name" column="MEASURE_NAME" type="string" />
      <!--<property name="definition" column="MEASURE_DEFINITION" type="net.hcsc.service.perfmngmt.persistance.TextType" length="8000"/>
      <property name="definitionFootnote" column="MEASURE_DEFINITION_FOOTNOTE" type="net.hcsc.service.perfmngmt.persistance.TextType" length="8000"/>-->
      <property name="frequency" column="MEASURE_FREQUENCY">
         <type name="net.hcsc.service.perfmngmt.persistance.EnumUserType">
            <param name="targetClass">net.hcsc.service.perfmngmt.frequency.Frequency</param>
         </type>
      </property>
      <component name="effectiveDates" class="net.hcsc.util.id.EffectiveDates">
         <property name="startDate" column="MEASURE_START_DATE" type="calendar" />
         <property name="endDate" column="MEASURE_END_DATE" type="calendar" />
      </component>
      <map name="indicators" table="INDICATORS" cascade="all" lazy="false">
         <key>
            <column name="MEASURE_ID"/>
            <column name="MEASURE_VERSION"/>
         </key>
         <map-key column="INDICATOR" type="string"/>
           <one-to-many class="net.hcsc.service.perfmngmt.indicator.MeasureIndicator"/>
      </map>
      <map name="properties" table="PROPERTIES" cascade="all" lazy="false">
         <key>
            <column name="MEASURE_ID"/>
            <column name="MEASURE_VERSION"/>
         </key>
         <map-key column="PROPERTY_NAME" type="string"/>
           <one-to-many class="net.hcsc.service.perfmngmt.properties.Property"/>
      </map>      
      [b]<set name="targets" table="TARGETS" cascade="save-update,merge" lazy="false">
         <key>
            <column name="MEASURE_ID" />
            <column name="MEASURE_VERSION" />
         </key>
         <one-to-many class="net.hcsc.service.perfmngmt.target.Target" />
         <filter name="effectiveDate" condition=":asOfDate TARGET_START_DATE and TARGET_END_DATE"/>[/b]
      </set>
      <many-to-one name="kpi" unique="true" not-null="true" class="net.hcsc.service.perfmngmt.kpi.Kpi" cascade="save-update,merge" lazy="false">
         <column name="KPI_ID" not-null="true"/>
         <column name="KPI_VERSION" not-null="true"/>
      </many-to-one>
      <many-to-one name="definition" class="net.hcsc.service.perfmngmt.measure.MeasureDefinitionText" column="MEASURE_DEFINITION_ID" cascade="all" unique="true" not-null="false" lazy="false"/>
      <many-to-one name="definitionFootnote" class="net.hcsc.service.perfmngmt.measure.MeasureFootnote" column="MEASURE_FOOTNOTE_ID" cascade="all" unique="false"  not-null="false" lazy="false"/>
      <filter name="effectiveDate"
            condition=":asOfDate MEASURE_START_DATE and MEASURE_END_DATE"/>   
   </class>
   <filter-def name="effectiveDate">
       <filter-param name="asOfDate" type="calendar"/>
   </filter-def>
</hibernate-mapping>

Code between sessionFactory.openSession() and session.close():

Code:

public Scorecard loadScorecardHierarchy(ScorecardName scorecardName,Calendar date) throws UnableToRetrieveObjectException {
      Scorecard root = null;      
      //String query = "from Scorecard scorecard where scorecard.name.name = :name and :date between scorecard.effectiveDates.startDate and scorecard.effectiveDates.endDate";
      Session session = getSession();
      
      try {
         log.info("Loading scorecard by Name and Date....");
         /*root = (Scorecard)session.createQuery(query)
                              .setParameter("name",scorecardName.getName())
                              .setParameter("date", date)
                           .uniqueResult();*/
         SimpleDateFormat df = new SimpleDateFormat("MM/dd/yyyy HH:mm:ss a");
         log.info("get Current View - retrieveView(ScorecardName, Calendar) - Period:  : period=" + df.format(date.getTime()));
         
         session.enableFilter("effectiveDate").setParameter("asOfDate", date);
         root = (Scorecard)session.createCriteria(Scorecard.class)
                                      .add(Restrictions.eq("name",scorecardName))                                     
                                     // .setFetchMode("childScorecards", FetchMode.JOIN)
                                      //.setFetchMode("measures", FetchMode.JOIN)
                                      .uniqueResult();
                                     
         if (root != null) {               
            Hibernate.initialize(root.getIndicators());
            Hibernate.initialize(root.getProperties());
            Hibernate.initialize(root.getComment());            
            //loadChildScorecards(session,root,date);    
            Hibernate.initialize(root.getChildScorecards());
            Hibernate.initialize(root.getMeasures());
            

            if (log.isDebugEnabled()) {
               log.debug("loadScorecardHierarchy(ScorecardName, Calendar) - Hierarchy to return: - root=" + root.toStringFullObject());
            }
         } else {
            throw new UnableToRetrieveObjectException("Unable to Locate the View you are looking for");
         }
      } catch (DataAccessResourceFailureException darfe) {
         throw new UnableToRetrieveObjectException(new TraceableException(darfe));
      } catch (HibernateException he) {
         throw new UnableToRetrieveObjectException(new TraceableException(he));
      }  catch (IllegalStateException ise) {
         throw new UnableToRetrieveObjectException(new TraceableException(ise));
      }
      log.info("Loading scorecard by Name and Date complete...");
      return root;
   }

Full stack trace of any exception that occurs:

Code:

2006-03-01 08:48:15,218  INFO [RMI TCP Connection(4)-10.126.154.78] (RemoteInvocationTraceInterceptor.java:84) - Processing of RmiServiceExporter remote call resulted in exception: net.hcsc.service.perfmngmt.service.kpi.IKpiService.retrieveView
net.hcsc.util.exception.UnableToRetrieveObjectException: net.hcsc.util.exception.TraceableException: org.hibernate.exception.SQLGrammarException: could not initialize a collection: [net.hcsc.service.perfmngmt.measure.Measure.targets#component[id,version]{id=v1idbb6c0bc00eab3481:2b0fb67a:103a33da212:-7fc5COR1, version=1}]
   at net.hcsc.service.perfmngmt.persistance.dao.ScorecardDAO.loadScorecardHierarchy(ScorecardDAO.java:345)
   at net.hcsc.service.perfmngmt.businessobjects.ViewBusinessObject.retrieveViewForPeriod(ViewBusinessObject.java:116)
   at net.hcsc.service.perfmngmt.service.kpi.KpiServiceImpl.retrieveView(KpiServiceImpl.java:192)
   at net.hcsc.service.perfmngmt.service.kpi.KpiServiceImpl$$FastClassByCGLIB$$543fc029.invoke(<generated>)
   at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:149)
   at org.springframework.aop.framework.Cglib2AopProxy$CglibMethodInvocation.invokeJoinpoint(Cglib2AopProxy.java:698)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:122)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
   at org.springframework.aop.framework.Cglib2AopProxy$DynamicAdvisedInterceptor.intercept(Cglib2AopProxy.java:643)
   at net.hcsc.service.perfmngmt.service.kpi.KpiServiceImpl$$EnhancerByCGLIB$$63e5af7c.retrieveView(<generated>)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:292)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:155)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:122)
   at org.springframework.remoting.support.RemoteInvocationTraceInterceptor.invoke(RemoteInvocationTraceInterceptor.java:68)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:174)
   at $Proxy1.retrieveView(Unknown Source)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at org.springframework.remoting.support.RemoteInvocation.invoke(RemoteInvocation.java:179)
   at org.springframework.remoting.support.DefaultRemoteInvocationExecutor.invoke(DefaultRemoteInvocationExecutor.java:33)
   at org.springframework.remoting.support.RemoteInvocationBasedExporter.invoke(RemoteInvocationBasedExporter.java:76)
   at org.springframework.remoting.rmi.RmiBasedExporter.invoke(RmiBasedExporter.java:72)
   at org.springframework.remoting.rmi.RmiInvocationWrapper.invoke(RmiInvocationWrapper.java:62)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at sun.rmi.server.UnicastServerRef.dispatch(Unknown Source)
   at sun.rmi.transport.Transport$1.run(Unknown Source)
   at java.security.AccessController.doPrivileged(Native Method)
   at sun.rmi.transport.Transport.serviceCall(Unknown Source)
   at sun.rmi.transport.tcp.TCPTransport.handleMessages(Unknown Source)
   at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(Unknown Source)
   at java.lang.Thread.run(Unknown Source)
Caused by: net.hcsc.util.exception.TraceableException: org.hibernate.exception.SQLGrammarException: could not initialize a collection: [net.hcsc.service.perfmngmt.measure.Measure.targets#component[id,version]{id=v1idbb6c0bc00eab3481:2b0fb67a:103a33da212:-7fc5COR1, version=1}]
   ... 42 more
Caused by: org.hibernate.exception.SQLGrammarException: could not initialize a collection: [net.hcsc.service.perfmngmt.measure.Measure.targets#component[id,version]{id=v1idbb6c0bc00eab3481:2b0fb67a:103a33da212:-7fc5COR1, version=1}]
   at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:59)
   at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
   at org.hibernate.loader.Loader.loadCollection(Loader.java:1441)
   at org.hibernate.loader.collection.OneToManyLoader.initialize(OneToManyLoader.java:111)
   at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:488)
   at org.hibernate.event.def.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:60)
   at org.hibernate.impl.SessionImpl.initializeCollection(SessionImpl.java:1430)
   at org.hibernate.collection.AbstractPersistentCollection.forceInitialization(AbstractPersistentCollection.java:280)
   at org.hibernate.engine.PersistenceContext.initializeNonLazyCollections(PersistenceContext.java:796)
   at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:223)
   at org.hibernate.loader.Loader.doList(Loader.java:1593)
   at org.hibernate.loader.Loader.list(Loader.java:1577)
   at org.hibernate.loader.criteria.CriteriaLoader.list(CriteriaLoader.java:111)
   at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1322)
   at org.hibernate.impl.CriteriaImpl.list(CriteriaImpl.java:300)
   at org.hibernate.impl.CriteriaImpl.uniqueResult(CriteriaImpl.java:433)
   at net.hcsc.service.perfmngmt.persistance.dao.ScorecardDAO.loadScorecardHierarchy(ScorecardDAO.java:325)
   ... 41 more
Caused by: java.sql.SQLException: Line 1: Incorrect syntax near 'targets0_'.
   at net.sourceforge.jtds.jdbc.SQLDiagnostic.addDiagnostic(SQLDiagnostic.java:365)
   at net.sourceforge.jtds.jdbc.TdsCore.tdsErrorToken(TdsCore.java:2781)
   at net.sourceforge.jtds.jdbc.TdsCore.nextToken(TdsCore.java:2224)
   at net.sourceforge.jtds.jdbc.TdsCore.getMoreResults(TdsCore.java:628)
   at net.sourceforge.jtds.jdbc.JtdsStatement.executeSQLQuery(JtdsStatement.java:418)
   at net.sourceforge.jtds.jdbc.JtdsPreparedStatement.executeQuery(JtdsPreparedStatement.java:693)
   at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:75)
   at org.hibernate.jdbc.AbstractBatcher.getResultSet(AbstractBatcher.java:120)
   at org.hibernate.loader.Loader.getResultSet(Loader.java:1272)
   at org.hibernate.loader.Loader.doQuery(Loader.java:391)
   at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:218)
   at org.hibernate.loader.Loader.loadCollection(Loader.java:1434)
   ... 55 more

Name and version of the database you are using: SQL Server 2000

The generated SQL (show_sql=true):

Code:

Hibernate: select this_.SCORECARD_ID as SCORECARD1_1_, this_.SCORECARD_VERSION as SCORECARD2_1_, this_.SCORECARD_NAME as SCORECARD3_17_1_, this_.SCORECARD_DESCRIPTION as SCORECARD4_17_1_, this_.SCORECARD_ORDER as SCORECARD5_17_1_, this_.SCORECARD_START_DATE as SCORECARD6_17_1_, this_.SCORECARD_END_DATE as SCORECARD7_17_1_, this_.COMMENT_ID as COMMENT8_17_1_, comment2_.COMMENT_ID as COMMENT1_0_, comment2_.COMMENT_TITLE as COMMENT2_10_0_, comment2_.COMMENT_TEXT as COMMENT3_10_0_ from SCORECARD this_ left outer join COMMENTS comment2_ on this_.COMMENT_ID=comment2_.COMMENT_ID where this_.SCORECARD_NAME=?
Hibernate: select properties0_.SCORECARD_ID as SCORECARD9_1_, properties0_.SCORECARD_VERSION as SCORECARD10_1_, properties0_.PROPERTY_ID as PROPERTY1_1_, properties0_.PROPERTY_NAME as PROPERTY3_1_, properties0_.PROPERTY_ID as PROPERTY1_0_, properties0_.PROPERTY_LAST_UPDATE as PROPERTY2_8_0_, properties0_.PROPERTY_NAME as PROPERTY3_8_0_, properties0_.PROPERTY_VALUE as PROPERTY4_8_0_ from PROPERTIES properties0_ where properties0_.SCORECARD_ID=? and properties0_.SCORECARD_VERSION=?
Hibernate: select measures0_.SCORECARD_ID as SCORECARD1_7_, measures0_.SCORECARD_VERSION as SCORECARD2_7_, measures0_.ASSOC_MEASURE_ID as ASSOC3_7_, measures0_.ASSOC_MEASURE_VERSION as ASSOC4_7_, associated1_.ASSOC_MEASURE_ID as ASSOC1_0_, associated1_.ASSOC_MEASURE_VERSION as ASSOC2_0_, associated1_.ASSOC_MEASURE_PRECISION as ASSOC3_16_0_, associated1_.ASSOC_MEASURE_ACTUAL_FORMAT as ASSOC4_16_0_, associated1_.ASSOC_MEASURE_SHARED as ASSOC5_16_0_, associated1_.ASSOC_MEASURE_WEIGHT as ASSOC6_16_0_, associated1_.ASSOCIATED_MEASURE_START_DATE as ASSOCIATED7_16_0_, associated1_.ASSOCIATED_MEASURE_END_DATE as ASSOCIATED8_16_0_, associated1_.ASSOC_MEASURE_PERIOD_FREQUENCY as ASSOC9_16_0_, associated1_.ASSOC_MEASURE_PERIOD_START_DATE as ASSOC10_16_0_, associated1_.ASSOC_MEASURE_PERIOD_END_DATE as ASSOC11_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_FREQUENCY as ASSOC12_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_START_DATE as ASSOC13_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_END_DATE as ASSOC14_16_0_, associated1_.MEASURE_ID as MEASURE15_16_0_, associated1_.MEASURE_VERSION as MEASURE16_16_0_, associated1_.SCALE_ID as SCALE17_16_0_, measure2_.MEASURE_ID as MEASURE1_1_, measure2_.MEASURE_VERSION as MEASURE2_1_, measure2_.MEASURE_NAME as MEASURE3_5_1_, measure2_.MEASURE_FREQUENCY as MEASURE4_5_1_, measure2_.MEASURE_START_DATE as MEASURE5_5_1_, measure2_.MEASURE_END_DATE as MEASURE6_5_1_, measure2_.KPI_ID as KPI7_5_1_, measure2_.KPI_VERSION as KPI8_5_1_, measure2_.MEASURE_DEFINITION_ID as MEASURE9_5_1_, measure2_.MEASURE_FOOTNOTE_ID as MEASURE10_5_1_, kpi3_.KPI_ID as KPI1_2_, kpi3_.KPI_VERSION as KPI2_2_, kpi3_.KPI_DESCRIPTION as KPI3_4_2_, kpi3_.KPI_NAME as KPI4_4_2_, kpi3_.KPI_START_DATE as KPI5_4_2_, kpi3_.KPI_END_DATE as KPI6_4_2_, kpi3_.UNIT_ID as UNIT7_4_2_, unit4_.UNIT_ID as UNIT1_3_, unit4_.UNIT_LAST_UPDATE as UNIT2_15_3_, unit4_.UNIT_NAME as UNIT3_15_3_, unit4_.UNIT_ABBREVIATION as UNIT4_15_3_, measuredef5_.MEASURE_DEFINITION_ID as MEASURE1_4_, measuredef5_.MEASURE_DEFINITION as MEASURE2_6_4_, measurefoo6_.MEASURE_FOOTNOTE_ID as MEASURE1_5_, measurefoo6_.MEASURE_FOOTNOTE as MEASURE2_7_5_, scale7_.SCALE_ID as SCALE1_6_, scale7_.SCALE_LAST_UPDATE as SCALE2_14_6_, scale7_.SCALE_NAME as SCALE3_14_6_, scale7_.SCALE_LONG_NAME as SCALE4_14_6_, scale7_.SCALE_ABBREVIATION as SCALE5_14_6_, scale7_.SCALE_SHORT_NAME as SCALE6_14_6_ from SCORECARD_MEASURE measures0_ inner join ASSOCIATED_MEASURE associated1_ on measures0_.ASSOC_MEASURE_ID=associated1_.ASSOC_MEASURE_ID and measures0_.ASSOC_MEASURE_VERSION=associated1_.ASSOC_MEASURE_VERSION left outer join MEASURE measure2_ on associated1_.MEASURE_ID=measure2_.MEASURE_ID and associated1_.MEASURE_VERSION=measure2_.MEASURE_VERSION left outer join KPI kpi3_ on measure2_.KPI_ID=kpi3_.KPI_ID and measure2_.KPI_VERSION=kpi3_.KPI_VERSION left outer join UNIT unit4_ on kpi3_.UNIT_ID=unit4_.UNIT_ID left outer join MEASURE_DEFINITION measuredef5_ on measure2_.MEASURE_DEFINITION_ID=measuredef5_.MEASURE_DEFINITION_ID left outer join MEASURE_FOOTNOTE measurefoo6_ on measure2_.MEASURE_FOOTNOTE_ID=measurefoo6_.MEASURE_FOOTNOTE_ID left outer join SCALE scale7_ on associated1_.SCALE_ID=scale7_.SCALE_ID where  ? BETWEEN associated1_.ASSOCIATED_MEASURE_START_DATE and associated1_.ASSOCIATED_MEASURE_END_DATE and measures0_.SCORECARD_ID=? and measures0_.SCORECARD_VERSION=?
Hibernate: select parents0_.CHILD_SCORECARD_ID as CHILD3_2_, parents0_.CHILD_SCORECARD_VERSION as CHILD4_2_, parents0_.PARENT_SCORECARD_ID as PARENT1_2_, parents0_.PARENT_SCORECARD_VERSION as PARENT2_2_, scorecard1_.SCORECARD_ID as SCORECARD1_0_, scorecard1_.SCORECARD_VERSION as SCORECARD2_0_, scorecard1_.SCORECARD_NAME as SCORECARD3_17_0_, scorecard1_.SCORECARD_DESCRIPTION as SCORECARD4_17_0_, scorecard1_.SCORECARD_ORDER as SCORECARD5_17_0_, scorecard1_.SCORECARD_START_DATE as SCORECARD6_17_0_, scorecard1_.SCORECARD_END_DATE as SCORECARD7_17_0_, scorecard1_.COMMENT_ID as COMMENT8_17_0_, comment2_.COMMENT_ID as COMMENT1_1_, comment2_.COMMENT_TITLE as COMMENT2_10_1_, comment2_.COMMENT_TEXT as COMMENT3_10_1_ from SCORECARD_CHILDREN parents0_ inner join SCORECARD scorecard1_ on parents0_.PARENT_SCORECARD_ID=scorecard1_.SCORECARD_ID and parents0_.PARENT_SCORECARD_VERSION=scorecard1_.SCORECARD_VERSION left outer join COMMENTS comment2_ on scorecard1_.COMMENT_ID=comment2_.COMMENT_ID where  ? BETWEEN scorecard1_.SCORECARD_START_DATE and scorecard1_.SCORECARD_END_DATE and parents0_.CHILD_SCORECARD_ID=? and parents0_.CHILD_SCORECARD_VERSION=?
Hibernate: select childscore0_.PARENT_SCORECARD_ID as PARENT1_2_, childscore0_.PARENT_SCORECARD_VERSION as PARENT2_2_, childscore0_.CHILD_SCORECARD_ID as CHILD3_2_, childscore0_.CHILD_SCORECARD_VERSION as CHILD4_2_, scorecard1_.SCORECARD_ID as SCORECARD1_0_, scorecard1_.SCORECARD_VERSION as SCORECARD2_0_, scorecard1_.SCORECARD_NAME as SCORECARD3_17_0_, scorecard1_.SCORECARD_DESCRIPTION as SCORECARD4_17_0_, scorecard1_.SCORECARD_ORDER as SCORECARD5_17_0_, scorecard1_.SCORECARD_START_DATE as SCORECARD6_17_0_, scorecard1_.SCORECARD_END_DATE as SCORECARD7_17_0_, scorecard1_.COMMENT_ID as COMMENT8_17_0_, comment2_.COMMENT_ID as COMMENT1_1_, comment2_.COMMENT_TITLE as COMMENT2_10_1_, comment2_.COMMENT_TEXT as COMMENT3_10_1_ from SCORECARD_CHILDREN childscore0_ inner join SCORECARD scorecard1_ on childscore0_.CHILD_SCORECARD_ID=scorecard1_.SCORECARD_ID and childscore0_.CHILD_SCORECARD_VERSION=scorecard1_.SCORECARD_VERSION left outer join COMMENTS comment2_ on scorecard1_.COMMENT_ID=comment2_.COMMENT_ID where  ? BETWEEN scorecard1_.SCORECARD_START_DATE and scorecard1_.SCORECARD_END_DATE and childscore0_.PARENT_SCORECARD_ID=? and childscore0_.PARENT_SCORECARD_VERSION=?
Hibernate: select properties0_.SCORECARD_ID as SCORECARD9_1_, properties0_.SCORECARD_VERSION as SCORECARD10_1_, properties0_.PROPERTY_ID as PROPERTY1_1_, properties0_.PROPERTY_NAME as PROPERTY3_1_, properties0_.PROPERTY_ID as PROPERTY1_0_, properties0_.PROPERTY_LAST_UPDATE as PROPERTY2_8_0_, properties0_.PROPERTY_NAME as PROPERTY3_8_0_, properties0_.PROPERTY_VALUE as PROPERTY4_8_0_ from PROPERTIES properties0_ where properties0_.SCORECARD_ID=? and properties0_.SCORECARD_VERSION=?
Hibernate: select measures0_.SCORECARD_ID as SCORECARD1_7_, measures0_.SCORECARD_VERSION as SCORECARD2_7_, measures0_.ASSOC_MEASURE_ID as ASSOC3_7_, measures0_.ASSOC_MEASURE_VERSION as ASSOC4_7_, associated1_.ASSOC_MEASURE_ID as ASSOC1_0_, associated1_.ASSOC_MEASURE_VERSION as ASSOC2_0_, associated1_.ASSOC_MEASURE_PRECISION as ASSOC3_16_0_, associated1_.ASSOC_MEASURE_ACTUAL_FORMAT as ASSOC4_16_0_, associated1_.ASSOC_MEASURE_SHARED as ASSOC5_16_0_, associated1_.ASSOC_MEASURE_WEIGHT as ASSOC6_16_0_, associated1_.ASSOCIATED_MEASURE_START_DATE as ASSOCIATED7_16_0_, associated1_.ASSOCIATED_MEASURE_END_DATE as ASSOCIATED8_16_0_, associated1_.ASSOC_MEASURE_PERIOD_FREQUENCY as ASSOC9_16_0_, associated1_.ASSOC_MEASURE_PERIOD_START_DATE as ASSOC10_16_0_, associated1_.ASSOC_MEASURE_PERIOD_END_DATE as ASSOC11_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_FREQUENCY as ASSOC12_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_START_DATE as ASSOC13_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_END_DATE as ASSOC14_16_0_, associated1_.MEASURE_ID as MEASURE15_16_0_, associated1_.MEASURE_VERSION as MEASURE16_16_0_, associated1_.SCALE_ID as SCALE17_16_0_, measure2_.MEASURE_ID as MEASURE1_1_, measure2_.MEASURE_VERSION as MEASURE2_1_, measure2_.MEASURE_NAME as MEASURE3_5_1_, measure2_.MEASURE_FREQUENCY as MEASURE4_5_1_, measure2_.MEASURE_START_DATE as MEASURE5_5_1_, measure2_.MEASURE_END_DATE as MEASURE6_5_1_, measure2_.KPI_ID as KPI7_5_1_, measure2_.KPI_VERSION as KPI8_5_1_, measure2_.MEASURE_DEFINITION_ID as MEASURE9_5_1_, measure2_.MEASURE_FOOTNOTE_ID as MEASURE10_5_1_, kpi3_.KPI_ID as KPI1_2_, kpi3_.KPI_VERSION as KPI2_2_, kpi3_.KPI_DESCRIPTION as KPI3_4_2_, kpi3_.KPI_NAME as KPI4_4_2_, kpi3_.KPI_START_DATE as KPI5_4_2_, kpi3_.KPI_END_DATE as KPI6_4_2_, kpi3_.UNIT_ID as UNIT7_4_2_, unit4_.UNIT_ID as UNIT1_3_, unit4_.UNIT_LAST_UPDATE as UNIT2_15_3_, unit4_.UNIT_NAME as UNIT3_15_3_, unit4_.UNIT_ABBREVIATION as UNIT4_15_3_, measuredef5_.MEASURE_DEFINITION_ID as MEASURE1_4_, measuredef5_.MEASURE_DEFINITION as MEASURE2_6_4_, measurefoo6_.MEASURE_FOOTNOTE_ID as MEASURE1_5_, measurefoo6_.MEASURE_FOOTNOTE as MEASURE2_7_5_, scale7_.SCALE_ID as SCALE1_6_, scale7_.SCALE_LAST_UPDATE as SCALE2_14_6_, scale7_.SCALE_NAME as SCALE3_14_6_, scale7_.SCALE_LONG_NAME as SCALE4_14_6_, scale7_.SCALE_ABBREVIATION as SCALE5_14_6_, scale7_.SCALE_SHORT_NAME as SCALE6_14_6_ from SCORECARD_MEASURE measures0_ inner join ASSOCIATED_MEASURE associated1_ on measures0_.ASSOC_MEASURE_ID=associated1_.ASSOC_MEASURE_ID and measures0_.ASSOC_MEASURE_VERSION=associated1_.ASSOC_MEASURE_VERSION left outer join MEASURE measure2_ on associated1_.MEASURE_ID=measure2_.MEASURE_ID and associated1_.MEASURE_VERSION=measure2_.MEASURE_VERSION left outer join KPI kpi3_ on measure2_.KPI_ID=kpi3_.KPI_ID and measure2_.KPI_VERSION=kpi3_.KPI_VERSION left outer join UNIT unit4_ on kpi3_.UNIT_ID=unit4_.UNIT_ID left outer join MEASURE_DEFINITION measuredef5_ on measure2_.MEASURE_DEFINITION_ID=measuredef5_.MEASURE_DEFINITION_ID left outer join MEASURE_FOOTNOTE measurefoo6_ on measure2_.MEASURE_FOOTNOTE_ID=measurefoo6_.MEASURE_FOOTNOTE_ID left outer join SCALE scale7_ on associated1_.SCALE_ID=scale7_.SCALE_ID where  ? BETWEEN associated1_.ASSOCIATED_MEASURE_START_DATE and associated1_.ASSOCIATED_MEASURE_END_DATE and measures0_.SCORECARD_ID=? and measures0_.SCORECARD_VERSION=?
Hibernate: select indicators0_.KPI_ID as KPI4_1_, indicators0_.KPI_VERSION as KPI5_1_, indicators0_.ID as ID1_, indicators0_.INDICATOR as INDICATOR1_, indicators0_.ID as ID0_, indicators0_.INDICATOR as INDICATOR2_0_ from INDICATORS indicators0_ where indicators0_.KPI_ID=? and indicators0_.KPI_VERSION=?
Hibernate: select targets0_.MEASURE_ID as MEASURE13_7_, targets0_.MEASURE_VERSION as MEASURE14_7_, targets0_.TARGET_ID as TARGET1_7_, targets0_.TARGET_VERSION as TARGET2_7_, targets0_.TARGET_ID as TARGET1_6_, targets0_.TARGET_VERSION as TARGET2_6_, targets0_.TARGET_DESCRIPTION as TARGET3_21_6_, targets0_.TARGET_FREQUENCY as TARGET4_21_6_, targets0_.TARGET_RANGE_START_DATE as TARGET5_21_6_, targets0_.TARGET_RANGE_END_DATE as TARGET6_21_6_, targets0_.TARGET_START_DATE as TARGET7_21_6_, targets0_.TARGET_END_DATE as TARGET8_21_6_, targets0_.TARGET_START_ACTUAL_ID as TARGET9_21_6_, targets0_.TARGET_END_ACTUAL_ID as TARGET10_21_6_, targets0_.TARGET_SEGMENT_NAME as TARGET11_21_6_, targets0_.COMMENT_ID as COMMENT12_21_6_, actual1_.ACTUAL_ID as ACTUAL1_0_, actual1_.VALUE_ID as VALUE2_1_0_, actual1_.UNIT_ID as UNIT3_1_0_, actual1_.COMMENT_ID as COMMENT4_1_0_, abstractva2_.VALUE_ID as VALUE1_1_, abstractva2_.QUANTITATIVE_VALUE as QUANTITA3_0_1_, abstractva2_.QUALITATIVE_VALUE as QUALITAT4_0_1_, abstractva2_.DISCRIMINATOR as DISCRIMI2_1_, unit3_.UNIT_ID as UNIT1_2_, unit3_.UNIT_LAST_UPDATE as UNIT2_15_2_, unit3_.UNIT_NAME as UNIT3_15_2_, unit3_.UNIT_ABBREVIATION as UNIT4_15_2_, comment4_.COMMENT_ID as COMMENT1_3_, comment4_.COMMENT_TITLE as COMMENT2_10_3_, comment4_.COMMENT_TEXT as COMMENT3_10_3_, actual5_.ACTUAL_ID as ACTUAL1_4_, actual5_.VALUE_ID as VALUE2_1_4_, actual5_.UNIT_ID as UNIT3_1_4_, actual5_.COMMENT_ID as COMMENT4_1_4_, comment6_.COMMENT_ID as COMMENT1_5_, comment6_.COMMENT_TITLE as COMMENT2_10_5_, comment6_.COMMENT_TEXT as COMMENT3_10_5_ from TARGET targets0_ inner join ACTUALS actual1_ on targets0_.TARGET_START_ACTUAL_ID=actual1_.ACTUAL_ID left outer join ACTUAL_VALUES abstractva2_ on actual1_.VALUE_ID=abstractva2_.VALUE_ID left outer join UNIT unit3_ on actual1_.UNIT_ID=unit3_.UNIT_ID left outer join COMMENTS comment4_ on actual1_.COMMENT_ID=comment4_.COMMENT_ID inner join ACTUALS actual5_ on targets0_.TARGET_END_ACTUAL_ID=actual5_.ACTUAL_ID left outer join COMMENTS comment6_ on targets0_.COMMENT_ID=comment6_.COMMENT_ID where  ? targets0_.TARGET_START_DATE and targets0_.TARGET_END_DATE and targets0_.MEASURE_ID=? and targets0_.MEASURE_VERSION=?

Debug level Hibernate log excerpt:
[code]

here is the debug log excerpt:
[code]2006-03-01 08:59:43,812 INFO [main] (TestRetrieveView.java:99) - get Previous View - retrieveView(ScorecardName, Calendar) - Period: : period=03/01/2005 08:59:41 AM
2006-03-01 08:59:43,812 DEBUG [RMI TCP Connection(5)-10.126.154.78] (SessionImpl.java:250) - opened session at timestamp: 11412251838
2006-03-01 08:59:43,828 DEBUG [RMI TCP Connection(5)-10.126.154.78] (ConnectionManager.java:296) - opening JDBC connection
2006-03-01 08:59:43,859 DEBUG [RMI TCP Connection(5)-10.126.154.78] (JDBCTransaction.java:46) - begin
2006-03-01 08:59:43,859 DEBUG [RMI TCP Connection(5)-10.126.154.78] (JDBCTransaction.java:50) - current autocommit status: false
2006-03-01 08:59:43,859 INFO [RMI TCP Connection(5)-10.126.154.78] (KpiServiceImpl.java:175) - Retrieving view...
2006-03-01 08:59:43,937 INFO [RMI TCP Connection(5)-10.126.154.78] (ScorecardDAO.java:312) - Loading scorecard by Name and Date....
2006-03-01 08:59:43,937 INFO [RMI TCP Connection(5)-10.126.154.78] (ScorecardDAO.java:318) - get Current View - retrieveView(ScorecardName, Calendar) - Period: : period=03/01/2005 08:59:41 AM
2006-03-01 08:59:43,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:290) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-03-01 08:59:43,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:324) - select this_.SCORECARD_ID as SCORECARD1_1_, this_.SCORECARD_VERSION as SCORECARD2_1_, this_.SCORECARD_NAME as SCORECARD3_17_1_, this_.SCORECARD_DESCRIPTION as SCORECARD4_17_1_, this_.SCORECARD_ORDER as SCORECARD5_17_1_, this_.SCORECARD_START_DATE as SCORECARD6_17_1_, this_.SCORECARD_END_DATE as SCORECARD7_17_1_, this_.COMMENT_ID as COMMENT8_17_1_, comment2_.COMMENT_ID as COMMENT1_0_, comment2_.COMMENT_TITLE as COMMENT2_10_0_, comment2_.COMMENT_TEXT as COMMENT3_10_0_ from SCORECARD this_ left outer join COMMENTS comment2_ on this_.COMMENT_ID=comment2_.COMMENT_ID where this_.SCORECARD_NAME=?
2006-03-01 08:59:43,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:378) - preparing statement
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding 'Contingent Compensation' to parameter: 1
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:306) - about to open ResultSet (open ResultSets: 0, globally: 0)
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:405) - processing result set
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:410) - result set row: 0
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT1_0_
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' as column: SCORECARD1_1_
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: SCORECARD2_1_
2006-03-01 08:59:43,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:828) - result row: null, EntityKey[net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:43,968 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:978) - Initializing object from ResultSet: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:43,968 DEBUG [RMI TCP Connection(5)-10.126.154.78] (BasicEntityPersister.java:1651) - Hydrating entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:44,609 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Contingent Compensation' as column: SCORECARD3_17_1_
2006-03-01 08:59:44,609 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Compensation that is Contingent' as column: SCORECARD4_17_1_
2006-03-01 08:59:44,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '0' as column: SCORECARD5_17_1_
2006-03-01 08:59:44,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-01-01 00:00:00' as column: SCORECARD6_17_1_
2006-03-01 08:59:44,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2010-01-01 00:00:00' as column: SCORECARD7_17_1_
2006-03-01 08:59:44,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT8_17_1_
2006-03-01 08:59:44,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:429) - done processing result set (1 rows)
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:313) - about to close ResultSet (open ResultSets: 1, globally: 1)
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:298) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:416) - closing statement
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:528) - total objects hydrated: 1
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (TwoPhaseLoad.java:96) - resolving associations for [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.indicators#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.parents#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,437 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.measures#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.properties#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (TwoPhaseLoad.java:167) - done materializing entity [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (PersistenceContext.java:789) - initializing non-lazy collections
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:42) - initializing collection [net.hcsc.service.perfmngmt.scorecard.Scorecard.properties#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:47) - checking second-level cache
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:59) - collection not cached
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (OneToManyLoader.java:106) - Static select for one-to-many net.hcsc.service.perfmngmt.scorecard.Scorecard.properties: select properties0_.SCORECARD_ID as SCORECARD9_1_, properties0_.SCORECARD_VERSION as SCORECARD10_1_, properties0_.PROPERTY_ID as PROPERTY1_1_, properties0_.PROPERTY_NAME as PROPERTY3_1_, properties0_.PROPERTY_ID as PROPERTY1_0_, properties0_.PROPERTY_LAST_UPDATE as PROPERTY2_8_0_, properties0_.PROPERTY_NAME as PROPERTY3_8_0_, properties0_.PROPERTY_VALUE as PROPERTY4_8_0_ from PROPERTIES properties0_ where properties0_.SCORECARD_ID=? and properties0_.SCORECARD_VERSION=?
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1426) - loading collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.properties#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:290) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:324) - select properties0_.SCORECARD_ID as SCORECARD9_1_, properties0_.SCORECARD_VERSION as SCORECARD10_1_, properties0_.PROPERTY_ID as PROPERTY1_1_, properties0_.PROPERTY_NAME as PROPERTY3_1_, properties0_.PROPERTY_ID as PROPERTY1_0_, properties0_.PROPERTY_LAST_UPDATE as PROPERTY2_8_0_, properties0_.PROPERTY_NAME as PROPERTY3_8_0_, properties0_.PROPERTY_VALUE as PROPERTY4_8_0_ from PROPERTIES properties0_ where properties0_.SCORECARD_ID=? and properties0_.SCORECARD_VERSION=?
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:378) - preparing statement
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' to parameter: 1
2006-03-01 08:59:45,453 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '1' to parameter: 2
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:306) - about to open ResultSet (open ResultSets: 0, globally: 0)
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:718) - result set contains (possibly empty) collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.properties#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:85) - uninitialized collection: initializing
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:405) - processing result set
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:429) - done processing result set (0 rows)
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:313) - about to close ResultSet (open ResultSets: 1, globally: 1)
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:298) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-03-01 08:59:45,468 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:416) - closing statement
2006-03-01 08:59:45,515 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:528) - total objects hydrated: 0
2006-03-01 08:59:45,515 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:262) - 1 collections were found in result set for role: net.hcsc.service.perfmngmt.scorecard.Scorecard.properties
2006-03-01 08:59:45,515 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:206) - collection fully initialized: [net.hcsc.service.perfmngmt.scorecard.Scorecard.properties#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:46,140 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:272) - 1 collections initialized for role: net.hcsc.service.perfmngmt.scorecard.Scorecard.properties
2006-03-01 08:59:46,140 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1450) - done loading collection
2006-03-01 08:59:46,140 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:61) - collection initialized
2006-03-01 08:59:46,140 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:42) - initializing collection [net.hcsc.service.perfmngmt.scorecard.Scorecard.measures#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:46,140 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:47) - checking second-level cache
2006-03-01 08:59:46,156 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:83) - disregarding cached version (if any) of collection due to enabled filters
2006-03-01 08:59:46,156 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:59) - collection not cached
2006-03-01 08:59:46,156 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoader.java:94) - Static select for collection net.hcsc.service.perfmngmt.scorecard.Scorecard.measures: select measures0_.SCORECARD_ID as SCORECARD1_7_, measures0_.SCORECARD_VERSION as SCORECARD2_7_, measures0_.ASSOC_MEASURE_ID as ASSOC3_7_, measures0_.ASSOC_MEASURE_VERSION as ASSOC4_7_, associated1_.ASSOC_MEASURE_ID as ASSOC1_0_, associated1_.ASSOC_MEASURE_VERSION as ASSOC2_0_, associated1_.ASSOC_MEASURE_PRECISION as ASSOC3_16_0_, associated1_.ASSOC_MEASURE_ACTUAL_FORMAT as ASSOC4_16_0_, associated1_.ASSOC_MEASURE_SHARED as ASSOC5_16_0_, associated1_.ASSOC_MEASURE_WEIGHT as ASSOC6_16_0_, associated1_.ASSOCIATED_MEASURE_START_DATE as ASSOCIATED7_16_0_, associated1_.ASSOCIATED_MEASURE_END_DATE as ASSOCIATED8_16_0_, associated1_.ASSOC_MEASURE_PERIOD_FREQUENCY as ASSOC9_16_0_, associated1_.ASSOC_MEASURE_PERIOD_START_DATE as ASSOC10_16_0_, associated1_.ASSOC_MEASURE_PERIOD_END_DATE as ASSOC11_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_FREQUENCY as ASSOC12_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_START_DATE as ASSOC13_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_END_DATE as ASSOC14_16_0_, associated1_.MEASURE_ID as MEASURE15_16_0_, associated1_.MEASURE_VERSION as MEASURE16_16_0_, associated1_.SCALE_ID as SCALE17_16_0_, measure2_.MEASURE_ID as MEASURE1_1_, measure2_.MEASURE_VERSION as MEASURE2_1_, measure2_.MEASURE_NAME as MEASURE3_5_1_, measure2_.MEASURE_FREQUENCY as MEASURE4_5_1_, measure2_.MEASURE_START_DATE as MEASURE5_5_1_, measure2_.MEASURE_END_DATE as MEASURE6_5_1_, measure2_.KPI_ID as KPI7_5_1_, measure2_.KPI_VERSION as KPI8_5_1_, measure2_.MEASURE_DEFINITION_ID as MEASURE9_5_1_, measure2_.MEASURE_FOOTNOTE_ID as MEASURE10_5_1_, kpi3_.KPI_ID as KPI1_2_, kpi3_.KPI_VERSION as KPI2_2_, kpi3_.KPI_DESCRIPTION as KPI3_4_2_, kpi3_.KPI_NAME as KPI4_4_2_, kpi3_.KPI_START_DATE as KPI5_4_2_, kpi3_.KPI_END_DATE as KPI6_4_2_, kpi3_.UNIT_ID as UNIT7_4_2_, unit4_.UNIT_ID as UNIT1_3_, unit4_.UNIT_LAST_UPDATE as UNIT2_15_3_, unit4_.UNIT_NAME as UNIT3_15_3_, unit4_.UNIT_ABBREVIATION as UNIT4_15_3_, measuredef5_.MEASURE_DEFINITION_ID as MEASURE1_4_, measuredef5_.MEASURE_DEFINITION as MEASURE2_6_4_, measurefoo6_.MEASURE_FOOTNOTE_ID as MEASURE1_5_, measurefoo6_.MEASURE_FOOTNOTE as MEASURE2_7_5_, scale7_.SCALE_ID as SCALE1_6_, scale7_.SCALE_LAST_UPDATE as SCALE2_14_6_, scale7_.SCALE_NAME as SCALE3_14_6_, scale7_.SCALE_LONG_NAME as SCALE4_14_6_, scale7_.SCALE_ABBREVIATION as SCALE5_14_6_, scale7_.SCALE_SHORT_NAME as SCALE6_14_6_ from SCORECARD_MEASURE measures0_ inner join ASSOCIATED_MEASURE associated1_ on measures0_.ASSOC_MEASURE_ID=associated1_.ASSOC_MEASURE_ID and measures0_.ASSOC_MEASURE_VERSION=associated1_.ASSOC_MEASURE_VERSION left outer join MEASURE measure2_ on associated1_.MEASURE_ID=measure2_.MEASURE_ID and associated1_.MEASURE_VERSION=measure2_.MEASURE_VERSION left outer join KPI kpi3_ on measure2_.KPI_ID=kpi3_.KPI_ID and measure2_.KPI_VERSION=kpi3_.KPI_VERSION left outer join UNIT unit4_ on kpi3_.UNIT_ID=unit4_.UNIT_ID left outer join MEASURE_DEFINITION measuredef5_ on measure2_.MEASURE_DEFINITION_ID=measuredef5_.MEASURE_DEFINITION_ID left outer join MEASURE_FOOTNOTE measurefoo6_ on measure2_.MEASURE_FOOTNOTE_ID=measurefoo6_.MEASURE_FOOTNOTE_ID left outer join SCALE scale7_ on associated1_.SCALE_ID=scale7_.SCALE_ID where :effectiveDate.asOfDate BETWEEN associated1_.ASSOCIATED_MEASURE_START_DATE and associated1_.ASSOCIATED_MEASURE_END_DATE and measures0_.SCORECARD_ID=? and measures0_.SCORECARD_VERSION=?
2006-03-01 08:59:46,156 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1426) - loading collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.measures#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:46,156 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:290) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-03-01 08:59:46,156 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:324) - select measures0_.SCORECARD_ID as SCORECARD1_7_, measures0_.SCORECARD_VERSION as SCORECARD2_7_, measures0_.ASSOC_MEASURE_ID as ASSOC3_7_, measures0_.ASSOC_MEASURE_VERSION as ASSOC4_7_, associated1_.ASSOC_MEASURE_ID as ASSOC1_0_, associated1_.ASSOC_MEASURE_VERSION as ASSOC2_0_, associated1_.ASSOC_MEASURE_PRECISION as ASSOC3_16_0_, associated1_.ASSOC_MEASURE_ACTUAL_FORMAT as ASSOC4_16_0_, associated1_.ASSOC_MEASURE_SHARED as ASSOC5_16_0_, associated1_.ASSOC_MEASURE_WEIGHT as ASSOC6_16_0_, associated1_.ASSOCIATED_MEASURE_START_DATE as ASSOCIATED7_16_0_, associated1_.ASSOCIATED_MEASURE_END_DATE as ASSOCIATED8_16_0_, associated1_.ASSOC_MEASURE_PERIOD_FREQUENCY as ASSOC9_16_0_, associated1_.ASSOC_MEASURE_PERIOD_START_DATE as ASSOC10_16_0_, associated1_.ASSOC_MEASURE_PERIOD_END_DATE as ASSOC11_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_FREQUENCY as ASSOC12_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_START_DATE as ASSOC13_16_0_, associated1_.ASSOC_MEASURE_PENDING_PERIOD_END_DATE as ASSOC14_16_0_, associated1_.MEASURE_ID as MEASURE15_16_0_, associated1_.MEASURE_VERSION as MEASURE16_16_0_, associated1_.SCALE_ID as SCALE17_16_0_, measure2_.MEASURE_ID as MEASURE1_1_, measure2_.MEASURE_VERSION as MEASURE2_1_, measure2_.MEASURE_NAME as MEASURE3_5_1_, measure2_.MEASURE_FREQUENCY as MEASURE4_5_1_, measure2_.MEASURE_START_DATE as MEASURE5_5_1_, measure2_.MEASURE_END_DATE as MEASURE6_5_1_, measure2_.KPI_ID as KPI7_5_1_, measure2_.KPI_VERSION as KPI8_5_1_, measure2_.MEASURE_DEFINITION_ID as MEASURE9_5_1_, measure2_.MEASURE_FOOTNOTE_ID as MEASURE10_5_1_, kpi3_.KPI_ID as KPI1_2_, kpi3_.KPI_VERSION as KPI2_2_, kpi3_.KPI_DESCRIPTION as KPI3_4_2_, kpi3_.KPI_NAME as KPI4_4_2_, kpi3_.KPI_START_DATE as KPI5_4_2_, kpi3_.KPI_END_DATE as KPI6_4_2_, kpi3_.UNIT_ID as UNIT7_4_2_, unit4_.UNIT_ID as UNIT1_3_, unit4_.UNIT_LAST_UPDATE as UNIT2_15_3_, unit4_.UNIT_NAME as UNIT3_15_3_, unit4_.UNIT_ABBREVIATION as UNIT4_15_3_, measuredef5_.MEASURE_DEFINITION_ID as MEASURE1_4_, measuredef5_.MEASURE_DEFINITION as MEASURE2_6_4_, measurefoo6_.MEASURE_FOOTNOTE_ID as MEASURE1_5_, measurefoo6_.MEASURE_FOOTNOTE as MEASURE2_7_5_, scale7_.SCALE_ID as SCALE1_6_, scale7_.SCALE_LAST_UPDATE as SCALE2_14_6_, scale7_.SCALE_NAME as SCALE3_14_6_, scale7_.SCALE_LONG_NAME as SCALE4_14_6_, scale7_.SCALE_ABBREVIATION as SCALE5_14_6_, scale7_.SCALE_SHORT_NAME as SCALE6_14_6_ from SCORECARD_MEASURE measures0_ inner join ASSOCIATED_MEASURE associated1_ on measures0_.ASSOC_MEASURE_ID=associated1_.ASSOC_MEASURE_ID and measures0_.ASSOC_MEASURE_VERSION=associated1_.ASSOC_MEASURE_VERSION left outer join MEASURE measure2_ on associated1_.MEASURE_ID=measure2_.MEASURE_ID and associated1_.MEASURE_VERSION=measure2_.MEASURE_VERSION left outer join KPI kpi3_ on measure2_.KPI_ID=kpi3_.KPI_ID and measure2_.KPI_VERSION=kpi3_.KPI_VERSION left outer join UNIT unit4_ on kpi3_.UNIT_ID=unit4_.UNIT_ID left outer join MEASURE_DEFINITION measuredef5_ on measure2_.MEASURE_DEFINITION_ID=measuredef5_.MEASURE_DEFINITION_ID left outer join MEASURE_FOOTNOTE measurefoo6_ on measure2_.MEASURE_FOOTNOTE_ID=measurefoo6_.MEASURE_FOOTNOTE_ID left outer join SCALE scale7_ on associated1_.SCALE_ID=scale7_.SCALE_ID where ? BETWEEN associated1_.ASSOCIATED_MEASURE_START_DATE and associated1_.ASSOCIATED_MEASURE_END_DATE and measures0_.SCORECARD_ID=? and measures0_.SCORECARD_VERSION=?
2006-03-01 08:59:46,890 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:378) - preparing statement
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '2005-03-01 08:59:41' to parameter: 1
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' to parameter: 2
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '1' to parameter: 3
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:306) - about to open ResultSet (open ResultSets: 0, globally: 0)
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:718) - result set contains (possibly empty) collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.measures#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:85) - uninitialized collection: initializing
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:405) - processing result set
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:429) - done processing result set (0 rows)
2006-03-01 08:59:46,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:313) - about to close ResultSet (open ResultSets: 1, globally: 1)
2006-03-01 08:59:46,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:298) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-03-01 08:59:46,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:416) - closing statement
2006-03-01 08:59:46,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:528) - total objects hydrated: 0
2006-03-01 08:59:46,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:262) - 1 collections were found in result set for role: net.hcsc.service.perfmngmt.scorecard.Scorecard.measures
2006-03-01 08:59:46,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:206) - collection fully initialized: [net.hcsc.service.perfmngmt.scorecard.Scorecard.measures#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:46,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:272) - 1 collections initialized for role: net.hcsc.service.perfmngmt.scorecard.Scorecard.measures
2006-03-01 08:59:46,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1450) - done loading collection
2006-03-01 08:59:46,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:61) - collection initialized
2006-03-01 08:59:46,953 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:42) - initializing collection [net.hcsc.service.perfmngmt.scorecard.Scorecard.parents#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:47) - checking second-level cache
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:83) - disregarding cached version (if any) of collection due to enabled filters
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:59) - collection not cached
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoader.java:94) - Static select for collection net.hcsc.service.perfmngmt.scorecard.Scorecard.parents: select parents0_.CHILD_SCORECARD_ID as CHILD3_2_, parents0_.CHILD_SCORECARD_VERSION as CHILD4_2_, parents0_.PARENT_SCORECARD_ID as PARENT1_2_, parents0_.PARENT_SCORECARD_VERSION as PARENT2_2_, scorecard1_.SCORECARD_ID as SCORECARD1_0_, scorecard1_.SCORECARD_VERSION as SCORECARD2_0_, scorecard1_.SCORECARD_NAME as SCORECARD3_17_0_, scorecard1_.SCORECARD_DESCRIPTION as SCORECARD4_17_0_, scorecard1_.SCORECARD_ORDER as SCORECARD5_17_0_, scorecard1_.SCORECARD_START_DATE as SCORECARD6_17_0_, scorecard1_.SCORECARD_END_DATE as SCORECARD7_17_0_, scorecard1_.COMMENT_ID as COMMENT8_17_0_, comment2_.COMMENT_ID as COMMENT1_1_, comment2_.COMMENT_TITLE as COMMENT2_10_1_, comment2_.COMMENT_TEXT as COMMENT3_10_1_ from SCORECARD_CHILDREN parents0_ inner join SCORECARD scorecard1_ on parents0_.PARENT_SCORECARD_ID=scorecard1_.SCORECARD_ID and parents0_.PARENT_SCORECARD_VERSION=scorecard1_.SCORECARD_VERSION left outer join COMMENTS comment2_ on scorecard1_.COMMENT_ID=comment2_.COMMENT_ID where :effectiveDate.asOfDate BETWEEN scorecard1_.SCORECARD_START_DATE and scorecard1_.SCORECARD_END_DATE and parents0_.CHILD_SCORECARD_ID=? and parents0_.CHILD_SCORECARD_VERSION=?
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1426) - loading collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.parents#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:290) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-03-01 08:59:47,625 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:324) - select parents0_.CHILD_SCORECARD_ID as CHILD3_2_, parents0_.CHILD_SCORECARD_VERSION as CHILD4_2_, parents0_.PARENT_SCORECARD_ID as PARENT1_2_, parents0_.PARENT_SCORECARD_VERSION as PARENT2_2_, scorecard1_.SCORECARD_ID as SCORECARD1_0_, scorecard1_.SCORECARD_VERSION as SCORECARD2_0_, scorecard1_.SCORECARD_NAME as SCORECARD3_17_0_, scorecard1_.SCORECARD_DESCRIPTION as SCORECARD4_17_0_, scorecard1_.SCORECARD_ORDER as SCORECARD5_17_0_, scorecard1_.SCORECARD_START_DATE as SCORECARD6_17_0_, scorecard1_.SCORECARD_END_DATE as SCORECARD7_17_0_, scorecard1_.COMMENT_ID as COMMENT8_17_0_, comment2_.COMMENT_ID as COMMENT1_1_, comment2_.COMMENT_TITLE as COMMENT2_10_1_, comment2_.COMMENT_TEXT as COMMENT3_10_1_ from SCORECARD_CHILDREN parents0_ inner join SCORECARD scorecard1_ on parents0_.PARENT_SCORECARD_ID=scorecard1_.SCORECARD_ID and parents0_.PARENT_SCORECARD_VERSION=scorecard1_.SCORECARD_VERSION left outer join COMMENTS comment2_ on scorecard1_.COMMENT_ID=comment2_.COMMENT_ID where ? BETWEEN scorecard1_.SCORECARD_START_DATE and scorecard1_.SCORECARD_END_DATE and parents0_.CHILD_SCORECARD_ID=? and parents0_.CHILD_SCORECARD_VERSION=?
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:378) - preparing statement
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '2005-03-01 08:59:41' to parameter: 1
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' to parameter: 2
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '1' to parameter: 3
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:306) - about to open ResultSet (open ResultSets: 0, globally: 0)
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:718) - result set contains (possibly empty) collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.parents#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:85) - uninitialized collection: initializing
2006-03-01 08:59:47,640 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:405) - processing result set
2006-03-01 08:59:47,671 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:429) - done processing result set (0 rows)
2006-03-01 08:59:47,671 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:313) - about to close ResultSet (open ResultSets: 1, globally: 1)
2006-03-01 08:59:47,671 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:298) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:416) - closing statement
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:528) - total objects hydrated: 0
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:262) - 1 collections were found in result set for role: net.hcsc.service.perfmngmt.scorecard.Scorecard.parents
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:206) - collection fully initialized: [net.hcsc.service.perfmngmt.scorecard.Scorecard.parents#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:272) - 1 collections initialized for role: net.hcsc.service.perfmngmt.scorecard.Scorecard.parents
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1450) - done loading collection
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:61) - collection initialized
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:42) - initializing collection [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:47) - checking second-level cache
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:83) - disregarding cached version (if any) of collection due to enabled filters
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultInitializeCollectionEventListener.java:59) - collection not cached
2006-03-01 08:59:47,687 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoader.java:94) - Static select for collection net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards: select childscore0_.PARENT_SCORECARD_ID as PARENT1_2_, childscore0_.PARENT_SCORECARD_VERSION as PARENT2_2_, childscore0_.CHILD_SCORECARD_ID as CHILD3_2_, childscore0_.CHILD_SCORECARD_VERSION as CHILD4_2_, scorecard1_.SCORECARD_ID as SCORECARD1_0_, scorecard1_.SCORECARD_VERSION as SCORECARD2_0_, scorecard1_.SCORECARD_NAME as SCORECARD3_17_0_, scorecard1_.SCORECARD_DESCRIPTION as SCORECARD4_17_0_, scorecard1_.SCORECARD_ORDER as SCORECARD5_17_0_, scorecard1_.SCORECARD_START_DATE as SCORECARD6_17_0_, scorecard1_.SCORECARD_END_DATE as SCORECARD7_17_0_, scorecard1_.COMMENT_ID as COMMENT8_17_0_, comment2_.COMMENT_ID as COMMENT1_1_, comment2_.COMMENT_TITLE as COMMENT2_10_1_, comment2_.COMMENT_TEXT as COMMENT3_10_1_ from SCORECARD_CHILDREN childscore0_ inner join SCORECARD scorecard1_ on childscore0_.CHILD_SCORECARD_ID=scorecard1_.SCORECARD_ID and childscore0_.CHILD_SCORECARD_VERSION=scorecard1_.SCORECARD_VERSION left outer join COMMENTS comment2_ on scorecard1_.COMMENT_ID=comment2_.COMMENT_ID where :effectiveDate.asOfDate BETWEEN scorecard1_.SCORECARD_START_DATE and scorecard1_.SCORECARD_END_DATE and childscore0_.PARENT_SCORECARD_ID=? and childscore0_.PARENT_SCORECARD_VERSION=?
2006-03-01 08:59:48,375 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:1426) - loading collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:48,375 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:290) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-03-01 08:59:48,375 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:324) - select childscore0_.PARENT_SCORECARD_ID as PARENT1_2_, childscore0_.PARENT_SCORECARD_VERSION as PARENT2_2_, childscore0_.CHILD_SCORECARD_ID as CHILD3_2_, childscore0_.CHILD_SCORECARD_VERSION as CHILD4_2_, scorecard1_.SCORECARD_ID as SCORECARD1_0_, scorecard1_.SCORECARD_VERSION as SCORECARD2_0_, scorecard1_.SCORECARD_NAME as SCORECARD3_17_0_, scorecard1_.SCORECARD_DESCRIPTION as SCORECARD4_17_0_, scorecard1_.SCORECARD_ORDER as SCORECARD5_17_0_, scorecard1_.SCORECARD_START_DATE as SCORECARD6_17_0_, scorecard1_.SCORECARD_END_DATE as SCORECARD7_17_0_, scorecard1_.COMMENT_ID as COMMENT8_17_0_, comment2_.COMMENT_ID as COMMENT1_1_, comment2_.COMMENT_TITLE as COMMENT2_10_1_, comment2_.COMMENT_TEXT as COMMENT3_10_1_ from SCORECARD_CHILDREN childscore0_ inner join SCORECARD scorecard1_ on childscore0_.CHILD_SCORECARD_ID=scorecard1_.SCORECARD_ID and childscore0_.CHILD_SCORECARD_VERSION=scorecard1_.SCORECARD_VERSION left outer join COMMENTS comment2_ on scorecard1_.COMMENT_ID=comment2_.COMMENT_ID where ? BETWEEN scorecard1_.SCORECARD_START_DATE and scorecard1_.SCORECARD_END_DATE and childscore0_.PARENT_SCORECARD_ID=? and childscore0_.PARENT_SCORECARD_VERSION=?
2006-03-01 08:59:49,265 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:378) - preparing statement
2006-03-01 08:59:49,265 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '2005-03-01 08:59:41' to parameter: 1
2006-03-01 08:59:49,265 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' to parameter: 2
2006-03-01 08:59:49,265 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:59) - binding '1' to parameter: 3
2006-03-01 08:59:49,265 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:306) - about to open ResultSet (open ResultSets: 0, globally: 0)
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:718) - result set contains (possibly empty) collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:85) - uninitialized collection: initializing
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:405) - processing result set
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:410) - result set row: 0
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4' as column: SCORECARD1_0_
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: SCORECARD2_0_
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT1_1_
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:828) - result row: EntityKey[net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}], null
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:978) - Initializing object from ResultSet: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (BasicEntityPersister.java:1651) - Hydrating entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Gainsharing Program' as column: SCORECARD3_17_0_
2006-03-01 08:59:49,281 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Gainsharing Program' as column: SCORECARD4_17_0_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '4' as column: SCORECARD5_17_0_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-01-01 00:00:00' as column: SCORECARD6_17_0_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-12-31 00:00:00' as column: SCORECARD7_17_0_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT8_17_0_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' as column: PARENT1_2_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: PARENT2_2_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:654) - found row of collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:112) - reading row
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4' as column: CHILD3_2_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: CHILD4_2_
2006-03-01 08:59:49,296 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:143) - loading entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:290) - attempting to resolve: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:299) - resolved object in session cache: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:410) - result set row: 1
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5' as column: SCORECARD1_0_
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: SCORECARD2_0_
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT1_1_
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:828) - result row: EntityKey[net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5, version=1}], null
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:978) - Initializing object from ResultSet: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5, version=1}]
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (BasicEntityPersister.java:1651) - Hydrating entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5, version=1}]
2006-03-01 08:59:49,312 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Divisional Goals' as column: SCORECARD3_17_0_
2006-03-01 08:59:49,328 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Divisional Goals' as column: SCORECARD4_17_0_
2006-03-01 08:59:49,328 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '3' as column: SCORECARD5_17_0_
2006-03-01 08:59:49,328 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-01-01 00:00:00' as column: SCORECARD6_17_0_
2006-03-01 08:59:50,046 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-12-31 00:00:00' as column: SCORECARD7_17_0_
2006-03-01 08:59:50,046 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT8_17_0_
2006-03-01 08:59:50,046 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' as column: PARENT1_2_
2006-03-01 08:59:50,046 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: PARENT2_2_
2006-03-01 08:59:50,046 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:654) - found row of collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:50,046 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:112) - reading row
2006-03-01 08:59:50,062 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5' as column: CHILD3_2_
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: CHILD4_2_
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:143) - loading entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5, version=1}]
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:290) - attempting to resolve: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5, version=1}]
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:299) - resolved object in session cache: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe5, version=1}]
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:410) - result set row: 2
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6' as column: SCORECARD1_0_
2006-03-01 08:59:50,078 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: SCORECARD2_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT1_1_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:828) - result row: EntityKey[net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6, version=1}], null
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:978) - Initializing object from ResultSet: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6, version=1}]
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (BasicEntityPersister.java:1651) - Hydrating entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6, version=1}]
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Shared Divisional Goals' as column: SCORECARD3_17_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Shared Divisional Goals' as column: SCORECARD4_17_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2' as column: SCORECARD5_17_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-01-01 00:00:00' as column: SCORECARD6_17_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-12-31 00:00:00' as column: SCORECARD7_17_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT8_17_0_
2006-03-01 08:59:50,093 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' as column: PARENT1_2_
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: PARENT2_2_
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:654) - found row of collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:112) - reading row
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6' as column: CHILD3_2_
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: CHILD4_2_
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:143) - loading entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6, version=1}]
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:290) - attempting to resolve: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6, version=1}]
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:299) - resolved object in session cache: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe6, version=1}]
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:410) - result set row: 3
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7' as column: SCORECARD1_0_
2006-03-01 08:59:50,109 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: SCORECARD2_0_
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT1_1_
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:828) - result row: EntityKey[net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7, version=1}], null
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:978) - Initializing object from ResultSet: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7, version=1}]
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (BasicEntityPersister.java:1651) - Hydrating entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7, version=1}]
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Corporate Goals' as column: SCORECARD3_17_0_
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'Shared Divisional Goals' as column: SCORECARD4_17_0_
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: SCORECARD5_17_0_
2006-03-01 08:59:50,125 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-01-01 00:00:00' as column: SCORECARD6_17_0_
2006-03-01 08:59:50,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '2005-12-31 00:00:00' as column: SCORECARD7_17_0_
2006-03-01 08:59:50,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:80) - returning null as column: COMMENT8_17_0_
2006-03-01 08:59:50,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8' as column: PARENT1_2_
2006-03-01 08:59:50,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: PARENT2_2_
2006-03-01 08:59:50,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:654) - found row of collection: [net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe8, version=1}]
2006-03-01 08:59:50,906 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:112) - reading row
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning 'bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7' as column: CHILD3_2_
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (NullableType.java:86) - returning '1' as column: CHILD4_2_
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:143) - loading entity: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7, version=1}]
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:290) - attempting to resolve: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7, version=1}]
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (DefaultLoadEventListener.java:299) - resolved object in session cache: [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe7, version=1}]
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:429) - done processing result set (4 rows)
2006-03-01 08:59:50,921 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:313) - about to close ResultSet (open ResultSets: 1, globally: 1)
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:298) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (AbstractBatcher.java:416) - closing statement
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (Loader.java:528) - total objects hydrated: 4
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (TwoPhaseLoad.java:96) - resolving associations for [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.indicators#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.childScorecards#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.parents#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.measures#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (CollectionLoadContext.java:141) - creating collection wrapper:[net.hcsc.service.perfmngmt.scorecard.Scorecard.properties#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1}]
2006-03-01 08:59:50,937 DEBUG [RMI TCP Connection(5)-10.126.154.78] (TwoPhaseLoad.java:167) - done materializing entity [net.hcsc.service.perfmngmt.scorecard.Scorecard#component[id,version]{id=bb6c0bc00eab3481:2b0fb67a:103a33da212:-7fe4, version=1

here is the debug log excerpt:

I found the issue as I was preparing this post. Pilot error. I forgot to add the between keyword. Sorry for the inconvience.