-->
These old forums are deprecated now and set to read-only. We are waiting for you on our new forums!
More modern, Discourse-based and with GitHub/Google/Twitter authentication built-in.

All times are UTC - 5 hours [ DST ]



Forum locked This topic is locked, you cannot edit posts or make further replies.  [ 20 posts ]  Go to page 1, 2  Next
Author Message
 Post subject: StaleObjectStateException after upgrading oracle jdbc driver
PostPosted: Thu Feb 05, 2004 2:01 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
Hi guys. I'm still using hibernate-2.0.3 and I'm getting a StaleObjectStateException on transaction.commit() after upgrading my oracle jdbc driver to the latest one. Stacktrace reads:

Code:
net.sf.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect) for com.rhoworld.edc.model.Observation instance with identifier: 1683
        at net.sf.hibernate.persister.AbstractEntityPersister.check(AbstractEntityPersister.java:414)
        at net.sf.hibernate.persister.EntityPersister.update(EntityPersister.java:639)
        at net.sf.hibernate.persister.EntityPersister.update(EntityPersister.java:611)
        at net.sf.hibernate.impl.ScheduledUpdate.execute(ScheduledUpdate.java:31)
        at net.sf.hibernate.impl.SessionImpl.executeAll(SessionImpl.java:2100)
        at net.sf.hibernate.impl.SessionImpl.execute(SessionImpl.java:2062)
        at net.sf.hibernate.impl.SessionImpl.flush(SessionImpl.java:2005)
        at net.sf.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:57)


Oddly enough, the Observation table is empty. I'm adding a new Observation container filled with a bunch of Observations. Mapping file reads:

Code:
<hibernate-mapping>
  <class name="com.rhoworld.edc.model.Observation" table="OBSERVATION_TABLE">
    <id name="id" type="long" unsaved-value="null">
      <generator class="native"/>
    </id>
    <version name="version" type="timestamp"/>
    <many-to-one name="sectionSubmission" column="section_submission_id" not-null="true"/>
    <many-to-one name="field" column="field_id" not-null="true"/>
    <property name="value" type="string"/>
    <property name="status" type="com.rhoworld.edc.model.ObservationStatus"/>
    <property name="missingValue" type="com.rhoworld.edc.model.MissingValue"/>
  </class>
</hibernate-mapping>


and my hibernate.properties:

Code:
hibernate.connection.provider_class=net.sf.hibernate.connection.C3P0ConnectionProvider
hibernate.show_sql=false
hibernate.c3p0.min_size=2
hibernate.c3p0.max_size=5
hibernate.c3p0.timeout=5000
hibernate.c3p0.max_statements=100
hibernate.c3p0.validate=true
hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver
hibernate.connection.url=jdbc:oracle:thin@...
hibernate.connection.username=...
hibernate.connection.password=...
hibernate.dialect=net.sf.hibernate.dialect.OracleDialect


I'm using optimistic locking with manual version checking, though that's not really relevant here since we're talking about entirely new Objects.

Can anyone suggest any avenues of research? fwiw, I upgraded from the old classes12.jar (so helpfully named) to ojdbc14.jar.


Top
 Profile  
 
 Post subject:
PostPosted: Thu Feb 05, 2004 2:07 pm 
Hibernate Team
Hibernate Team

Joined: Sun Sep 14, 2003 3:54 am
Posts: 7256
Location: Paris, France
try batch_size=0
Any relation with a timestamp management of Oralce JDBC driver ?

_________________
Emmanuel


Top
 Profile  
 
 Post subject:
PostPosted: Thu Feb 05, 2004 3:59 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
emmanuel wrote:
try batch_size=0
Any relation with a timestamp management of Oralce JDBC driver ?


tried adding

Code:
hibernate.jdbc.batch_size=0


to my hibernate.properties file. no change.

Dunno what you mean about timestamp management of Oracle JDBC driver...?


Top
 Profile  
 
 Post subject:
PostPosted: Thu Feb 05, 2004 4:49 pm 
Hibernate Team
Hibernate Team

Joined: Sun Sep 14, 2003 3:54 am
Posts: 7256
Location: Paris, France
I read you're using timestamp to manage version.

BTW use <timestamp/> instead of <version type="timestamp"/>

_________________
Emmanuel


Top
 Profile  
 
 Post subject:
PostPosted: Thu Feb 05, 2004 4:59 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
emmanuel wrote:
I read you're using timestamp to manage version.

BTW use <timestamp/> instead of <version type="timestamp"/>

Are you suggesting the new Oracle driver doesn't handle timestamps correctly? That seems odd to me. I figured the most likely explanation is that the transaction management code in the Oracle driver changed, and is either now not working correctly or I'd been relying on misbehavior before.

Re: the timestamp element - the hibernate docs clearly state:
Quote:
Note that <timestamp> is equivalent to <version type="timestamp">

Is there in fact a substantive difference, or is it just a style preference? If the former, the docs are misleading and should be patched.


Top
 Profile  
 
 Post subject:
PostPosted: Thu Feb 05, 2004 6:47 pm 
Hibernate Team
Hibernate Team

Joined: Sun Sep 14, 2003 3:54 am
Posts: 7256
Location: Paris, France
rhobot wrote:
Are you suggesting the new Oracle driver doesn't handle timestamps correctly? That seems odd to me. I figured the most likely explanation is that the transaction management code in the Oracle driver changed, and is either now not working correctly or I'd been relying on misbehavior before.

No I don't think the Tx is involved. The Hibernate.commit() raise session.flush() and it fails: it happens before any commit(). Show the minimal code and full debug log.

rhobot wrote:
If the former, the docs are misleading and should be patched.

My fault, I think I'll have to read the reference guide again.

_________________
Emmanuel


Top
 Profile  
 
 Post subject:
PostPosted: Fri Feb 06, 2004 12:52 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
Minimal code - boy, that's hard. I'll try to stitch something together...

Code:
SectionSubmission sectionSubmission = ... // from elsewhere - new or old
session.save(sectionSubmission);
for (Iterator iterator = sectionSubmission.getSection().getFields().values().iterator(); iterator.hasNext();) {
    Field field = (Field)iterator.next();
    Observation obs = sectionSubmission.getObservation(field);
     if (obs == null) {
         obs = new Observation();
         obs.setField(field);
         sectionSubmission.addObservation(obs);
     } else {
         String version = request.getParameter(field.getName() + "-version");
         if (version == null) {
            log.warn("Null version for: " + field.getName());
        } else {
            if (!version.equals(obs.getVersion().toString())) {
                error(request, response, HttpServletResponse.SC_FORBIDDEN,
                        "Cannot update submission, version collision");
                return false;
            }
        }
    }
    String value = request.getParameter(field.getName());
    if (value == null) {
        //log.warn("Null request parameter value for: " + field.getName());
        value = "";
    }
    obs.setValue(value);
    session.save(obs);
}
...
tx.commit();


The StateObject exception is being tossed at transaction.commit(), the original stack trace shows that.

I'm going to try to only show the relevant portion of the log file; let me know if I should include more or put it up in webspace somewhere:

[code]
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select hibernate_sequence.nextval from dual
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.id.SequenceGenerator: Sequence identifier generated: 1764
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.impl.SessionImpl: saving [com.rhoworld.edc.model.Observation#1764]
DEBUG net.sf.hibernate.engine.Versioning: Seeding: 2004-02-06 11:42:40.939
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select hibernate_sequence.nextval from dual
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.id.SequenceGenerator: Sequence identifier generated: 1765
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.impl.SessionImpl: saving [com.rhoworld.edc.model.Observation#1765]
DEBUG net.sf.hibernate.engine.Versioning: Seeding: 2004-02-06 11:42:40.949
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select hibernate_sequence.nextval from dual
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.id.SequenceGenerator: Sequence identifier generated: 1766
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.impl.SessionImpl: saving [com.rhoworld.edc.model.Observation#1766]
DEBUG net.sf.hibernate.engine.Versioning: Seeding: 2004-02-06 11:42:40.949
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select hibernate_sequence.nextval from dual
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.id.SequenceGenerator: Sequence identifier generated: 1767
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.impl.SessionImpl: saving [com.rhoworld.edc.model.Observation#1767]
DEBUG net.sf.hibernate.engine.Versioning: Seeding: 2004-02-06 11:42:40.949
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select hibernate_sequence.nextval from dual
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.id.SequenceGenerator: Sequence identifier generated: 1768
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.impl.SessionImpl: saving [com.rhoworld.edc.model.Observation#1768]
DEBUG net.sf.hibernate.engine.Versioning: Seeding: 2004-02-06 11:42:40.959
DEBUG net.sf.hibernate.impl.SessionImpl: initializing collection [com.rhoworld.edc.model.Section.sections#290]
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select section_0_.id as id__, form1_.id as id0_, form1_.name as name0_, form1_.label as label0_, form1_.labelPattern as labelPat4_0_, form1_.minSubmissions as minSubmi5_0_, form1_.maxSubmissions as maxSubmi6_0_, form1_.study_id as study_id0_, study2_.id as id1_, study2_.name as name1_, study2_.label as label1_, study2_.subjectNamePattern as subjectN4_1_, study2_.subjectLabel as subjectL5_1_, study2_.dateFormat as dateFormat1_, study2_.path as path1_, section_0_.id as id2_, section_0_.subclass as subclass2_, section_0_.name as name2_, section_0_.labelPattern as labelPat4_2_, section_0_.minSubmissions as minSubmi5_2_, section_0_.maxSubmissions as maxSubmi6_2_, section_0_.form_id as form_id2_, section_0_.pageNumber as pageNumber2_, section_0_.section_id as section_id2_ from SECTION_TABLE section_0_, FORM_TABLE form1_, STUDY_TABLE study2_ where section_0_.section_id=? and section_0_.form_id=form1_.id(+) and form1_.study_id=study2_.id(+)
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.type.LongType: binding '290' to parameter: 1
DEBUG net.sf.hibernate.loader.Loader: processing result set
DEBUG net.sf.hibernate.loader.Loader: done processing result set (0 rows)
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.loader.Loader: total objects hydrated: 0
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select hibernate_sequence.nextval from dual
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.id.SequenceGenerator: Sequence identifier generated: 1769
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.impl.SessionImpl: saving [com.rhoworld.edc.model.Observation#1769]
DEBUG net.sf.hibernate.engine.Versioning: Seeding: 2004-02-06 11:42:40.969
DEBUG com.rhoworld.edc.model.SectionSubmission: Cannot parse date field TODAYDT
java.lang.Exception: does not match the pattern: \s*(-?\d{1,4})\s*
at com.rhoworld.edc.model.NumberField.getComparableValue(NumberField.java:21)
at com.rhoworld.edc.model.SectionSubmission.validate(SectionSubmission.java:109)
at com.rhoworld.edc.servlet.Retriever.doAddSectionSubmission(Retriever.java:184)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at com.rhoworld.roe.servlet.Finder.dispatch(Finder.java:283)
at com.rhoworld.roe.servlet.Finder.service(Finder.java:229)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:247)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:193)
at com.rhoworld.edc.servlet.CommonFilter.doFilter(CommonFilter.java:88)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:213)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:193)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:260)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:643)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:480)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:995)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:643)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:480)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:995)
at org.apache.catalina.core.StandardContext.invoke(StandardContext.java:2415)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:180)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:643)
at org.apache.catalina.valves.ErrorDispatcherValve.invoke(ErrorDispatcherValve.java:170)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:641)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:172)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:641)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:509)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:641)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:480)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:995)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:174)
at org.apache.catalina.core.StandardPipeline$StandardPipelineValveContext.invokeNext(StandardPipeline.java:643)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:480)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:995)
at org.apache.coyote.tomcat4.CoyoteAdapter.service(CoyoteAdapter.java:223)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:432)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:386)
at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:534)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:530)
at java.lang.Thread.run(Thread.java:534)
DEBUG com.rhoworld.edc.model.Observation: validating observation: for field: KARNSKY
DEBUG com.rhoworld.edc.model.Observation: validating observation: for field: LANSKY
DEBUG com.rhoworld.edc.model.Observation: validating observation: for field: TODAYDA
DEBUG com.rhoworld.edc.model.Observation: validating observation: for field: TODAYDT
DEBUG com.rhoworld.edc.model.Observation: missing value is not allowed: TODAYDT
DEBUG com.rhoworld.edc.model.Observation: validating observation: for field: TODAYMO
DEBUG com.rhoworld.edc.model.Observation: validating observation: for field: TODAYYR
DEBUG net.sf.hibernate.impl.SessionImpl: initializing collection [com.rhoworld.edc.model.Study.subjects#1]
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select subject_0_.id as id__, subject_0_.id as id, subject_0_.name as name, subject_0_.label as label, subject_0_.study_id as study_id from SUBJECT_TABLE subject_0_ where subject_0_.study_id=?
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.type.LongType: binding '1' to parameter: 1
DEBUG net.sf.hibernate.loader.Loader: processing result set
DEBUG net.sf.hibernate.type.LongType: returning '1680' as column: id
DEBUG net.sf.hibernate.loader.Loader: result row: 1680
DEBUG net.sf.hibernate.type.LongType: returning '1680' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Subject#1680]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Subject#1680]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Subject#1680]
DEBUG net.sf.hibernate.loader.Loader: done processing result set (1 rows)
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.loader.Loader: total objects hydrated: 0
DEBUG net.sf.hibernate.impl.SessionImpl: initializing collection [com.rhoworld.edc.model.Study.forms#1]
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select form_tab0_.id as id__, form_tab0_.id as id, form_tab0_.name as name, form_tab0_.label as label, form_tab0_.labelPattern as labelPat4_, form_tab0_.minSubmissions as minSubmi5_, form_tab0_.maxSubmissions as maxSubmi6_, form_tab0_.study_id as study_id from FORM_TABLE form_tab0_ where form_tab0_.study_id=?
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.type.LongType: binding '1' to parameter: 1
DEBUG net.sf.hibernate.loader.Loader: processing result set
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: id
DEBUG net.sf.hibernate.loader.Loader: result row: 2
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.type.LongType: returning '1141' as column: id
DEBUG net.sf.hibernate.loader.Loader: result row: 1141
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 1141
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.Form#1141
DEBUG net.sf.hibernate.type.StringType: returning 'GVHD' as column: name
DEBUG net.sf.hibernate.type.StringType: returning 'GVHD' as column: label
DEBUG net.sf.hibernate.type.StringType: returning 'Form dated {16.TODAYDT}' as column: labelPat4_
DEBUG net.sf.hibernate.type.IntegerType: returning '0' as column: minSubmi5_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_
DEBUG net.sf.hibernate.type.LongType: returning '1' as column: study_id
DEBUG net.sf.hibernate.type.LongType: returning '1141' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#1141]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#1141]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#1141]
DEBUG net.sf.hibernate.type.LongType: returning '1518' as column: id
DEBUG net.sf.hibernate.loader.Loader: result row: 1518
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 1518
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.Form#1518
DEBUG net.sf.hibernate.type.StringType: returning 'CNS Event' as column: name
DEBUG net.sf.hibernate.type.StringType: returning 'CNS Event' as column: label
DEBUG net.sf.hibernate.type.StringType: returning 'Event dated {24.REPRTDT}' as column: labelPat4_
DEBUG net.sf.hibernate.type.IntegerType: returning '0' as column: minSubmi5_
DEBUG net.sf.hibernate.type.IntegerType: returning '0' as column: maxSubmi6_
DEBUG net.sf.hibernate.type.LongType: returning '1' as column: study_id
DEBUG net.sf.hibernate.type.LongType: returning '1518' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#1518]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#1518]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#1518]
DEBUG net.sf.hibernate.loader.Loader: done processing result set (3 rows)
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.loader.Loader: total objects hydrated: 2
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.Form#1141]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Study#1]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Study#1]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Study#1]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.Form#1141]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.Form#1518]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Study#1]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Study#1]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Study#1]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.Form#1518]
DEBUG net.sf.hibernate.impl.SessionImpl: initializing collection [com.rhoworld.edc.model.Form.sections#2]
DEBUG net.sf.hibernate.impl.BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: prepared statement get: select section_0_.id as id__, section_0_.pageNumber as pageNumber__, section1_.id as id0_, section1_.subclass as subclass0_, section1_.name as name0_, section1_.labelPattern as labelPat4_0_, section1_.minSubmissions as minSubmi5_0_, section1_.maxSubmissions as maxSubmi6_0_, section1_.form_id as form_id0_, section1_.pageNumber as pageNumber0_, section1_.section_id as section_id0_, section_0_.id as id1_, section_0_.subclass as subclass1_, section_0_.name as name1_, section_0_.labelPattern as labelPat4_1_, section_0_.minSubmissions as minSubmi5_1_, section_0_.maxSubmissions as maxSubmi6_1_, section_0_.form_id as form_id1_, section_0_.pageNumber as pageNumber1_, section_0_.section_id as section_id1_ from SECTION_TABLE section_0_, SECTION_TABLE section1_ where section_0_.form_id=? and section_0_.section_id=section1_.id(+)
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: preparing statement
DEBUG net.sf.hibernate.type.LongType: binding '2' to parameter: 1
DEBUG net.sf.hibernate.loader.Loader: processing result set
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '3' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 3
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 3
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#3
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Physical Exam' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '3' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#3]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#3]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#3]
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '46' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 46
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 46
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#46
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '2' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Hematological Evaluation' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '46' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#46]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#46]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#46]
DEBUG net.sf.hibernate.type.IntegerType: returning '2' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '143' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 143
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 143
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#143
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '3' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Studies of Engraftment' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '143' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#143]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#143]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#143]
DEBUG net.sf.hibernate.type.IntegerType: returning '3' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '223' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 223
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 223
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#223
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '4' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Renal and Hepatic Studies' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '223' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#223]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#223]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#223]
DEBUG net.sf.hibernate.type.IntegerType: returning '4' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '271' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 271
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 271
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#271
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '5' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Urinalysis' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '271' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#271]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#271]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#271]
DEBUG net.sf.hibernate.type.IntegerType: returning '5' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '290' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 290
DEBUG net.sf.hibernate.type.LongType: returning '290' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#290]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#290]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#290]
DEBUG net.sf.hibernate.type.IntegerType: returning '6' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '301' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 301
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 301
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#301
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '7' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Pulmonary Studies' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '301' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#301]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#301]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#301]
DEBUG net.sf.hibernate.type.IntegerType: returning '7' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '355' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 355
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 355
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#355
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '8' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Intelligence Testing' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '355' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#355]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#355]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#355]
DEBUG net.sf.hibernate.type.IntegerType: returning '8' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '421' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 421
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 421
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#421
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '9' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Child Behavior Checklist' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '421' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#421]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#421]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#421]
DEBUG net.sf.hibernate.type.IntegerType: returning '9' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '448' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 448
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 448
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#448
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '10' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Child Behavior Checklist: Validity Assessment' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '448' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#448]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#448]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#448]
DEBUG net.sf.hibernate.type.IntegerType: returning '10' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '527' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 527
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 527
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#527
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '11' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Neurologic Studies' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '527' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#527]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#527]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#527]
DEBUG net.sf.hibernate.type.IntegerType: returning '11' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '605' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 605
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 605
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#605
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '12' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Neurologic Studies' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '605' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#605]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#605]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#605]
DEBUG net.sf.hibernate.type.IntegerType: returning '12' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '718' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 718
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 718
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#718
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '13' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Growth and Development' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '718' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#718]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#718]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#718]
DEBUG net.sf.hibernate.type.IntegerType: returning '13' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '746' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 746
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 746
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#746
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '14' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Growth and Development' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '746' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#746]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#746]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#746]
DEBUG net.sf.hibernate.type.IntegerType: returning '14' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '828' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 828
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 828
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#828
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '15' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Interim Events' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '828' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#828]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#828]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#828]
DEBUG net.sf.hibernate.type.IntegerType: returning '15' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '924' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 924
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 924
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#924
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '20' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Visual Acuity' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '924' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#924]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#924]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#924]
DEBUG net.sf.hibernate.type.IntegerType: returning '20' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '963' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 963
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 963
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#963
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '21' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Immune Reconstitution Studies' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '963' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#963]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#963]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#963]
DEBUG net.sf.hibernate.type.IntegerType: returning '21' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '1067' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 1067
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 1067
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#1067
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '22' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Medications' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '1067' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#1067]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#1067]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#1067]
DEBUG net.sf.hibernate.type.IntegerType: returning '22' as column: pageNumber__
DEBUG net.sf.hibernate.type.LongType: returning null as column: id0_
DEBUG net.sf.hibernate.type.LongType: returning '1080' as column: id1_
DEBUG net.sf.hibernate.loader.Loader: result row: null, 1080
DEBUG net.sf.hibernate.type.StringType: returning 'P' as column: subclass1_
DEBUG net.sf.hibernate.loader.Loader: Initializing object from ResultSet: 1080
DEBUG net.sf.hibernate.loader.Loader: Hydrating entity: com.rhoworld.edc.model.PageSection#1080
DEBUG net.sf.hibernate.type.LongType: returning '2' as column: form_id1_
DEBUG net.sf.hibernate.type.IntegerType: returning '23' as column: pageNumber1_
DEBUG net.sf.hibernate.type.StringType: returning 'Study Samples Shipping' as column: name1_
DEBUG net.sf.hibernate.type.StringType: returning '{TODAYDT}' as column: labelPat4_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: minSubmi5_1_
DEBUG net.sf.hibernate.type.IntegerType: returning '1' as column: maxSubmi6_1_
DEBUG net.sf.hibernate.type.LongType: returning '1080' as column: id__
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Section#1080]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Section#1080]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Section#1080]
DEBUG net.sf.hibernate.type.IntegerType: returning '23' as column: pageNumber__
DEBUG net.sf.hibernate.loader.Loader: done processing result set (19 rows)
DEBUG net.sf.hibernate.impl.BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
DEBUG net.sf.hibernate.impl.SessionFactoryImpl: closing statement
DEBUG net.sf.hibernate.loader.Loader: total objects hydrated: 18
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#3]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#3]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#46]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#46]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#143]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#143]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#223]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#223]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#271]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#271]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#301]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#301]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#355]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#355]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#421]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#421]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#448]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#448]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#527]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#527]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#605]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#605]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#718]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#718]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#746]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#746]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#828]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#828]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#924]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#924]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#963]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#963]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#1067]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#1067]
DEBUG net.sf.hibernate.impl.SessionImpl: resolving associations for [com.rhoworld.edc.model.PageSection#1080]
DEBUG net.sf.hibernate.impl.SessionImpl: loading [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: attempting to resolve [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: resolved object in session cache [com.rhoworld.edc.model.Form#2]
DEBUG net.sf.hibernate.impl.SessionImpl: done materializing entity [com.rhoworld.edc.model.PageSection#1080]
DEBUG net.sf.hibernate.transaction.JDBCTransaction: commit
DEBUG net.sf.hibernate.impl.SessionImpl: flushing session
DEBUG net.sf.hibernate.engine.Cascades: processing cascades for: com.rhoworld.edc.model.Form
DEBUG net.sf.hibernate.engine.Cascades: cascading to collection: com.rhoworld.edc.model.Form.sections
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: done processing cascades for: com.rhoworld.edc.model.Form
DEBUG net.sf.hibernate.engine.Cascades: processing cascades for: com.rhoworld.edc.model.Study
DEBUG net.sf.hibernate.engine.Cascades: cascading to collection: com.rhoworld.edc.model.Study.subjects
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to collection: com.rhoworld.edc.model.Study.forms
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUpdate()
DEBUG net.sf.hibernate.impl.SessionImpl: saveOrUpdate() persistent instance
DEBUG net.sf.hibernate.engine.Cascades: cascading to saveOrUp


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 12:06 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
Don't suppose anyone has any suggestions as to why upgrading the Oracle drivers might be causing this, or avenues of research I could undertake?


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 12:51 pm 
Hibernate Team
Hibernate Team

Joined: Sun Sep 14, 2003 3:54 am
Posts: 7256
Location: Paris, France
Did you dig
Code:
DEBUG com.rhoworld.edc.model.SectionSubmission: Cannot parse date field TODAYDT
java.lang.Exception: does not match the pattern: \s*(-?\d{1,4})\s*
at com.rhoworld.edc.model.NumberField.getComparableValue(NumberField.java:21)
at com.rhoworld.edc.model.SectionSubmission.validate(SectionSubmission.java:109)
at com.rhoworld.edc.servlet.Retriever.doAddSectionSubmission(Retriever.java:184)
?

_________________
Emmanuel


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 12:55 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
Oh, yeah, that's just little informative messages my app debugs out when it encounters Observations which don't contain valid values. It's an expected behavior and has nothing to do with the problem I'm encountering.

I ought to have scrubbed them from the log before posting; would doing so make it easier for you to parse the output?


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 1:10 pm 
Hibernate Team
Hibernate Team

Joined: Sun Sep 14, 2003 3:54 am
Posts: 7256
Location: Paris, France
In your POJO, you use Timestamp and not Date ?

_________________
Emmanuel


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 1:18 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
emmanuel wrote:
In your POJO, you use Timestamp and not Date ?


I use java.util.Date, figuring that I didn't want any dependencies on java.sql.* in my model objects. It's worked as expected so far - getVersion() on the Observation objects returns the same value as the version column in the database table.


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 1:19 pm 
Hibernate Team
Hibernate Team

Joined: Sun Sep 14, 2003 3:54 am
Posts: 7256
Location: Paris, France
Have to try :) I'm a bit out of ideas

_________________
Emmanuel


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 1:25 pm 
Regular
Regular

Joined: Fri Aug 29, 2003 12:48 pm
Posts: 63
Understand, thanks for your suggestions so far. Perhaps you could answer these at least:

What underlying circumstance tells hibernate to throw a StateObjectStateException?

Would switching to a more aggressive flush mode help isolate which database operation is causing the error?

How about, um, transaction isolation strategy? I'm not very well-versed in such matters, but maybe the default transaction isolation level is not appropriate in my context.


Top
 Profile  
 
 Post subject:
PostPosted: Mon Feb 09, 2004 3:45 pm 
Hibernate Team
Hibernate Team

Joined: Tue Aug 26, 2003 12:50 pm
Posts: 5130
Location: Melbourne, Australia
Note that some versions of Oracle 9 driver have a bug in timestamp handling. This is documented on the wiki.


Top
 Profile  
 
Display posts from previous:  Sort by  
Forum locked This topic is locked, you cannot edit posts or make further replies.  [ 20 posts ]  Go to page 1, 2  Next

All times are UTC - 5 hours [ DST ]


You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum

Search for:
© Copyright 2014, Red Hat Inc. All rights reserved. JBoss and Hibernate are registered trademarks and servicemarks of Red Hat, Inc.