We are attempting to upgrade from Hibernate 3.0.5 to 3.2 and are seeing a difference in how lazy loading is handled for many-to-one relationships for classes that have been instrumented.

We turned on Hibernate instrumentation in the first place to handle some one-to-one relationships where the foreign key is in the "child" class. This was working fine for us.

The problem now is that the foreign keys for our many-to-one relationships are now being lazy loaded like a properties (i.e. it's not part of the initial select). This is a bad thing from our standpoint because we want to load the foreign keys as part of the initial select and then have Hibernate create proxies for us. The extra selects are not efficient in this case.

I've tried using lazy="proxy" and lazy="no-proxy" and neither seems to make a difference.

Basically, the behavior we are looking for is:

1. For many-to-one relationships, load the foreign key as part of the initial select (it's right there in the table). Create a proxy and only load the additional information from the database when it is referenced.

2. For one-to-one relationships (where the foreign key is not part of the "parent" table), don't do anything during the initial select. Instead, when the child object is referenced, do a second select to see if the child object exists, and if so load it.

This is how 3.0.5 used to work.

From what I can tell, one of the differences between 3.0.5 and 3.2 that might be causing this is the following snippet of code in the property class:




Hibernate version: 3.2 RC 2

Mapping documents:


<hibernate-mapping
>
<class
name="com.netjets.esl.reservation.model.Leg"
table="LEG"
lazy="true"
batch-size="5"
>

(lots of stuff in between)

<many-to-one
name="departureFBO"
class="com.netjets.esl.vendor.model.FBO"
cascade="none"
outer-join="false"
update="true"
insert="true"
>
<column
name="DEPARTUREFBOID"
/>
</many-to-one>

<one-to-one
name="legAssignmentRef"
class="com.netjets.esl.schedule.model.LegAssignmentRef"
cascade="none"
outer-join="false"
constrained="false"
property-ref="leg"
lazy="proxy"
/>

</class>

</hibernate-mapping>


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

Integer legId = (Integer) getRandomPrimaryKey("LEGID", "LEG", Integer.class);
Leg leg = (Leg) session.get(Leg.class, legId);


Full stack trace of any exception that occurs:

Name and version of the database you are using: Oracle 8

The generated SQL (show_sql=true):


Here's an example when the lazy select occrs when the first lazy prop is referenced:


Debug level Hibernate log excerpt:

2006-08-11 10:40:40 INFO [main ] Environment.java:499 - Hibernate 3.2 cr2
2006-08-11 10:40:40 INFO [main ] Environment.java:532 - hibernate.properties not found
2006-08-11 10:40:40 INFO [main ] Environment.java:666 - Bytecode provider name : cglib
2006-08-11 10:40:40 INFO [main ] Environment.java:583 - using JDK 1.4 java.sql.Timestamp handling
2006-08-11 10:40:40 INFO [main ] Configuration.java:1345 - configuring from resource: /hibernate.cfg.xml
2006-08-11 10:40:40 INFO [main ] Configuration.java:1322 - Configuration resource: /hibernate.cfg.xml
2006-08-11 10:40:40 DEBUG [main ] DTDEntityResolver.java:22 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]
2006-08-11 10:40:40 DEBUG [main ] DTDEntityResolver.java:24 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
2006-08-11 10:40:40 DEBUG [main ] DTDEntityResolver.java:34 - located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - show_sql=false
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.query.factory_class=org.hibernate.hql.classic.ClassicQueryTranslatorFactory
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N'
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.dialect=org.hibernate.dialect.Oracle9Dialect
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.connection.datasource=IJET2XADS
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.connection.provider_class=org.hibernate.connection.DatasourceConnectionProvider
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.jndi.class=weblogic.jndi.WLInitialContextFactory
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.transaction.factory_class=org.hibernate.transaction.CMTTransactionFactory
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.transaction.manager_lookup_class=org.hibernate.transaction.WeblogicTransactionManagerLookup
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1306 - hibernate.default_batch_fetch_size=10
2006-08-11 10:40:40 DEBUG [main ] Configuration.java:1505 - hibernate-esl-model<-org.dom4j.tree.DefaultAttribute@1aaf0b3 [Attribute: name resource value "com/netjets/esl/aircraft/model/AircraftTail.hbm.xml"]
2006-08-11 10:40:40 INFO [main ] Configuration.java:502 - Reading mappings from resource: com/netjets/esl/aircraft/model/AircraftTail.hbm.xml
2006-08-11 10:40:40 DEBUG [main ] DTDEntityResolver.java:22 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
2006-08-11 10:40:40 DEBUG [main ] DTDEntityResolver.java:24 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
2006-08-11 10:40:40 DEBUG [main ] DTDEntityResolver.java:34 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath

2006-08-11 10:40:44 DEBUG [main ] Configuration.java:1505 - hibernate-esl-model<-org.dom4j.tree.DefaultAttribute@5ddb6e [Attribute: name resource value "com/netjets/esl/reservation/model/Leg.hbm.xml"]
2006-08-11 10:40:44 INFO [main ] Configuration.java:502 - Reading mappings from resource: com/netjets/esl/reservation/model/Leg.hbm.xml
2006-08-11 10:40:44 DEBUG [main ] DTDEntityResolver.java:22 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
2006-08-11 10:40:44 DEBUG [main ] DTDEntityResolver.java:24 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
2006-08-11 10:40:44 DEBUG [main ] DTDEntityResolver.java:34 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
2006-08-11 10:40:44 INFO [main ] HbmBinder.java:298 - Mapping class: com.netjets.esl.reservation.model.Leg -> LEG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: legID -> LEGID
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: legOrderNumber -> LEG_ORDER_NBR
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: flownStatus -> FLOWN_STATUS
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: reqEstFlightTime -> REQ_EST_FLIGHT_TIME
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: reqEstFuelStops -> REQ_EST_FUEL_STOPS
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: reqEstFlightDistanceKm -> REQ_EST_FLIGHT_DISTANCE_KM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: etdEnteredByUserFlag -> ETD_ENTERED_BY_USER_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: etaEnteredByUserFlag -> ETA_ENTERED_BY_USER_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: depLateEarlyOpsRequiredFlag -> DEP_LATE_EARLY_OPS_RQRD_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: arrLateEarlyOpsRequiredFlag -> ARR_LATE_EARLY_OPS_RQRD_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: ruralAirportTaxFlag -> RURAL_AIRPORT_TAX_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: fetTaxFlag -> FET_TAX_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: fetTaxOverrideFlag -> FET_TAX_OVERRIDE_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: passengerFeeFlag -> PASSENGER_FEE_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: passengerFeeOverrideFlag -> PASSENGER_FEE_OVERRIDE_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: raftRequiredFlag -> RAFT_REQUIRED_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: flightRuleOverrideFlag -> FLIGHT_RULE_OVERRIDE_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: slideOverrideForSlotFlag -> SLIDE_OVERRIDE_FOR_SLOT_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: ferryCateringSetFlag -> FERRY_CATERING_SET_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: airframeHours -> AIRFRAME_HRS_QTY
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: logDeviation -> LOG_DEVIATION_QTY
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: positioningHoursDep -> POSITIONING_HOURS_DEP_NBR
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: actualFlightTimeBaseHours -> FLIGHT_TIME_BASE_HOURS_NBR
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: airframeCycles -> AIRFRAME_CYCLES_QTY
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: ejSchedUpdate -> EJSCHED_LOG_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: tentativeETDCode -> TENTATIVE_ETD_CD
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: tentativeETACode -> TENTATIVE_ETA_CD
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: isInMidas -> IN_MIDAS_FLAG
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: etd -> ETD_TM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: eta -> ETA_TM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: etdSlide -> ETD_SLIDE_TM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: etaSlide -> ETA_SLIDE_TM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: ata -> ATA_TM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: atd -> ATD_TM
2006-08-11 10:40:44 DEBUG [main ] HbmBinder.java:1252 - Mapped property: outTime -> OUTTIME_DAT
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: inTime -> INTIME_DAT
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: inflightUpdatedETA -> INFLIGHT_UPDATED_ETA_DAT
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: flightLogChangedAt -> FLIGHT_LOG_CHANGED_TS
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: flightRuleID -> FLIGHT_RULE_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: fuelStart -> FUEL_START_QTY
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: fuelEnd -> FUEL_END_QTY
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: numberOfLandings -> NUMBER_OF_LANDINGS_QTY
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: estFlightTimeBaseHours -> EST_FLIGHT_TIME_BASE_HOURS_NBR
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: overriddenTripTime -> OVERRIDDEN_TRIP_TIME
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: departureFBOOverrideReason -> DEPT_FBO_OVERRIDE_REASON_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: arrivalFBOOverrideReason -> ARRIVAL_FBO_OVERRIDE_REASON_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: ejSchedLegNumber -> EJSCHED_LEG_NUMBER
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: flightLogChangedBy -> FLIGHT_LOG_CHANGED_BY_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: departureFBOOverrideDesc -> DEPT_FBO_OVERRIDE_DESC
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: arrivalFBOOverrideDesc -> ARRIVAL_FBO_OVERRIDE_DESC
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: overriddenTripTimeReason -> OVERRIDDEN_TRIP_TIME_REASON
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: weatherComments -> WEATHER_COMMENTS
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: logOverrideReason -> LOG_OVERRIDE_REASON_TXT
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: oca -> SYS_VERSION_NBR
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: lastChanged -> SYS_LAST_CHANGED_TS
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: lastChangedBy -> SYS_LAST_CHANGED_BY_USER_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: posRegionWaiverFlag -> POS_REGION_WAIVER_FLAG
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: nonRevenueReasonCd -> NON_REVENUE_REASON_CD
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: positioningHoursArr -> POSITIONING_HOURS_ARR_NBR
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: flightRuleOverrideReason -> FLIGHT_RULE_OVERRIDE_REASON
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: legAssignmentRef
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: departureFBO -> DEPARTUREFBOID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: arrivalFBO -> ARRIVALFBOID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: request -> REQUEST_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedChaufferedAutosForArrival
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedChaufferedAutosForDeparture
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedRentalAutosForArrival
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedRentalAutosForDeparture
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedOtherGroundsForArrival
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedOtherGroundsForDeparture
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedInternationalTripPlans
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: legTasks
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: nextLeg -> NEXTLEGID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: previousLeg
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: requestedSellOff -> REQUESTED_SELL_OFF_ID
2006-08-11 10:40:45 DEBUG [main ] HbmBinder.java:1252 - Mapped property: itineraryLeg



2006-08-11 10:40:46 INFO [main ] Configuration.java:1460 - Configured SessionFactory: hibernate-esl-model
2006-08-11 10:40:46 DEBUG [main ] Configuration.java:1461 - properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider, sun.boot.library.path=C:\bea\jdk142_04\jre\bin, java.vm.version=1.4.2_04-b05, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=US, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\views\ESLModel.R5\ESLModelJAVA, java.runtime.version=1.4.2_04-b05, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\bea\jdk142_04\jre\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, hibernate.default_batch_fetch_size=10, sun.java2d.fontpath=, hibernate.connection.datasource=IJET2XADS, java.library.path=C:\bea\jdk142_04\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\Omaha\scripts;C:\j2sdk1.4.2_04\bin;C:\j2sdk1.4.2_04\jre\bin;C:\powertier709\tool;C:\powertier709\component\server\bin;C:\powertier709\component\client\bin;C:\cygwin\bin;C:\oracle\ora92\bin;C:\Perl\bin\;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\nls;C:\WINDOWS\system32\nls\ENGLISH;C:\Program Files\Microsoft Visual Studio\Common\Tools\WinNT;C:\jintegra\bin;C:\jad;C:\PROGRA~1\ULTRAE~1;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS\system;c:\Program Files\Microsoft Visual Studio\VB98;C:\Program Files\Microsoft Visual Studio\Common\MSDev98\Bin;C:\Program Files\Microsoft Visual Studio\Common\Tools;C:\Program Files\Microsoft Visual Studio\VC98\bin;C:\tibco\rv\BIN;C:\Program Files\Rational\common;C:\Program Files\Rational\ClearCase\bin;c:\Program Files\Microsoft SQL Server\90\Tools\binn\, java.specification.name=Java Platform API Specification, java.class.version=48.0, hibernate.transaction.factory_class=org.hibernate.transaction.CMTTransactionFactory, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, hibernate.query.factory_class=org.hibernate.hql.classic.ClassicQueryTranslatorFactory, os.version=5.1, user.home=C:\Documents and Settings\mspaulding, user.timezone=America/New_York, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, show_sql=false, user.name=mspaulding, java.class.path=C:\views\ESLModel.R5\target\bin\esl-model.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junit-3.8.1.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junit-addons-1.4.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junitee.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junitee-anttask.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\easymock-1.1.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\easymockclassextension-1.1.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\strutstest-2.1.3.jar;C:\bea\weblogic81\server\lib\weblogic.jar;C:\bea\weblogic81\server\lib\webservices.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\antlr-2.7.6.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\asm-attrs.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\asm.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\cglib-2.1.3.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\dom4j-1.6.1.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\ehcache-1.2.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\hibernate3.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\netjets-hibernate3.jar;C:\views\ESLModel.R5\CommonJ2EE\lib\netjets-j2ee.jar;C:\views\ESLModel.R5\CommonJ2EE\lib\quartz.jar;C:\views\ESLModel.R5\CommonJAVA\lib\jaxen-1.1-beta-2.jar;C:\views\ESLModel.R5\CommonJAVA\lib\log4j-1.2.8.jar;C:\views\ESLModel.R5\CommonJAVA\lib\netjets-common.jar;C:\views\ESLModel.R5\CommonJAVA\lib\xbean.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-beanutils-bean-collections.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-beanutils-core.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-beanutils.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-collections-2.1.1.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-dbcp-1.2.1.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-lang.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-logging-1.0.4.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-pool-1.2.jar;C:\views\ESLModel.R5\target\bin\netjets-weblogic.jar;C:\views\ESLModel.R5\WeblogicJ2EE\lib\netjets-weblogic.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit_3.1.1/junitsupport.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.1.0/junitruntime.jar, hibernate.bytecode.use_reflection_optimizer=false, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', hibernate.show_sql=false, java.vm.specification.version=1.0, java.home=C:\bea\jdk142_04\jre, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, java.version=1.4.2_04, java.ext.dirs=C:\bea\jdk142_04\jre\lib\ext, sun.boot.class.path=C:\bea\jdk142_04\jre\lib\rt.jar;C:\bea\jdk142_04\jre\lib\sunrsasign.jar;C:\bea\jdk142_04\jre\lib\jsse.jar;C:\bea\jdk142_04\jre\lib\jce.jar;C:\bea\jdk142_04\jre\lib\charsets.jar;C:\bea\jdk142_04\jre\lib\ext\dnsns.jar;C:\bea\jdk142_04\jre\lib\ext\ldapsec.jar;C:\bea\jdk142_04\jre\lib\ext\localedata.jar;C:\bea\jdk142_04\jre\lib\ext\sunjce_provider.jar;C:\bea\jdk142_04\lib\tools.jar, java.vendor=Sun Microsystems Inc., file.separator=\, hibernate.session_factory_name=hibernate-esl-model, hibernate.connection.provider_class=org.hibernate.connection.DatasourceConnectionProvider, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, hibernate.jndi.class=weblogic.jndi.WLInitialContextFactory, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, hibernate.transaction.manager_lookup_class=org.hibernate.transaction.WeblogicTransactionManagerLookup, sun.cpu.isalist=pentium i486 i386}
2006-08-11 10:40:46 DEBUG [main ] Configuration.java:1204 - Preparing to build session factory with filters : {}

2006-08-11 10:40:47 INFO [main ] ConnectionProviderFactory.java:72 - Initializing connection provider: org.hibernate.connection.DatasourceConnectionProvider
2006-08-11 10:40:47 INFO [main ] NamingHelper.java:26 - JNDI InitialContext properties:{java.naming.provider.url=http://localhost:8080, java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory}
2006-08-11 10:40:48 INFO [main ] DatasourceConnectionProvider.java:61 - Using datasource: IJET2XADS
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:78 - RDBMS: Oracle, version: Oracle9i Enterprise Edition Release 9.2.0.7.0 - Production
With the Partitioning, OLAP and Oracle Data Mining options
JServer Release 9.2.0.7.0 - Production
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:79 - JDBC driver: Oracle JDBC driver, version: 10.1.0.4.0
2006-08-11 10:40:49 INFO [main ] Dialect.java:128 - Using dialect: org.hibernate.dialect.Oracle9Dialect
2006-08-11 10:40:49 INFO [main ] TransactionFactoryFactory.java:34 - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
2006-08-11 10:40:49 INFO [main ] TransactionManagerLookupFactory.java:38 - instantiating TransactionManagerLookup: org.hibernate.transaction.WeblogicTransactionManagerLookup
2006-08-11 10:40:49 INFO [main ] TransactionManagerLookupFactory.java:42 - instantiated TransactionManagerLookup
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:126 - Automatic flush during beforeCompletion(): disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:130 - Automatic session close at end of transaction: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:137 - JDBC batch size: 15
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:140 - JDBC batch updates for versioned data: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:145 - Scrollable result sets: enabled
2006-08-11 10:40:49 DEBUG [main ] SettingsFactory.java:149 - Wrap result sets: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:153 - JDBC3 getGeneratedKeys(): disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:161 - Connection release mode: auto
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:188 - Default batch fetch size: 10
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:192 - Generate SQL with comments: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:196 - Order SQL updates by primary key: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:357 - Query translator: org.hibernate.hql.classic.ClassicQueryTranslatorFactory
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:204 - Query language substitutions: {no='N', true=1, yes='Y', false=0}
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:210 - Second-level cache: enabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:214 - Query cache: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:344 - Cache provider: org.hibernate.cache.EhCacheProvider
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:229 - Optimize cache for minimal puts: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:238 - Structured second-level cache entries: disabled
2006-08-11 10:40:49 DEBUG [main ] SQLExceptionConverterFactory.java:52 - Using dialect defined converter
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:265 - Statistics: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:269 - Deleted entity synthetic identifier rollback: disabled
2006-08-11 10:40:49 INFO [main ] SettingsFactory.java:284 - Default entity-mode: pojo
2006-08-11 10:40:49 INFO [main ] SessionFactoryImpl.java:159 - building session factory
2006-08-11 10:40:49 DEBUG [main ] SessionFactoryImpl.java:170 - Session factory constructed with filter configurations : {}
2006-08-11 10:40:49 DEBUG [main ] SessionFactoryImpl.java:174 - instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., show_sql=false, com.netjets.weblogic.included=true, os.name=Windows XP, sun.boot.class.path=C:\bea\jdk142_04\jre\lib\rt.jar;C:\bea\jdk142_04\jre\lib\sunrsasign.jar;C:\bea\jdk142_04\jre\lib\jsse.jar;C:\bea\jdk142_04\jre\lib\jce.jar;C:\bea\jdk142_04\jre\lib\charsets.jar;C:\bea\jdk142_04\jre\lib\ext\dnsns.jar;C:\bea\jdk142_04\jre\lib\ext\ldapsec.jar;C:\bea\jdk142_04\jre\lib\ext\localedata.jar;C:\bea\jdk142_04\jre\lib\ext\sunjce_provider.jar;C:\bea\jdk142_04\lib\tools.jar, hibernate.jndi.class=weblogic.jndi.WLInitialContextFactory, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_04-b05, hibernate.jndi.url=http://localhost:8080, hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider, user.name=mspaulding, hibernate.session_factory_name=hibernate-esl-model, esl.datasource.name=IJET2XADS, user.language=en, java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory, sun.boot.library.path=C:\bea\jdk142_04\jre\bin, java.version=1.4.2_04, user.timezone=America/New_York, sun.arch.data.model=32, hibernate.session.flushmode=NEVER, java.endorsed.dirs=C:\bea\jdk142_04\jre\lib\endorsed, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, file.separator=\, java.specification.name=Java Platform API Specification, java.class.version=48.0, user.country=US, java.home=C:\bea\jdk142_04\jre, java.vm.info=mixed mode, os.version=5.1, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, hibernate.connection.datasource=IJET2XADS, path.separator=;, java.vm.version=1.4.2_04-b05, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, user.variant=, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, user.home=C:\Documents and Settings\mspaulding, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', java.specification.vendor=Sun Microsystems Inc., java.library.path=C:\bea\jdk142_04\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\Omaha\scripts;C:\j2sdk1.4.2_04\bin;C:\j2sdk1.4.2_04\jre\bin;C:\powertier709\tool;C:\powertier709\component\server\bin;C:\powertier709\component\client\bin;C:\cygwin\bin;C:\oracle\ora92\bin;C:\Perl\bin\;C:\WINDOWS\System32\Wbem;C:\WINDOWS\system32\nls;C:\WINDOWS\system32\nls\ENGLISH;C:\Program Files\Microsoft Visual Studio\Common\Tools\WinNT;C:\jintegra\bin;C:\jad;C:\PROGRA~1\ULTRAE~1;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS\system;c:\Program Files\Microsoft Visual Studio\VB98;C:\Program Files\Microsoft Visual Studio\Common\MSDev98\Bin;C:\Program Files\Microsoft Visual Studio\Common\Tools;C:\Program Files\Microsoft Visual Studio\VC98\bin;C:\tibco\rv\BIN;C:\Program Files\Rational\common;C:\Program Files\Rational\ClearCase\bin;c:\Program Files\Microsoft SQL Server\90\Tools\binn\, java.vendor.url=http://java.sun.com/, com.netjets.j2ee.included=true, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\views\ESLModel.R5\target\bin\esl-model.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junit-3.8.1.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junit-addons-1.4.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junitee.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\junitee-anttask.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\easymock-1.1.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\easymockclassextension-1.1.jar;C:\views\ESLModel.R5\AntBUILD\ant-ext\strutstest-2.1.3.jar;C:\bea\weblogic81\server\lib\weblogic.jar;C:\bea\weblogic81\server\lib\webservices.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\antlr-2.7.6.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\asm-attrs.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\asm.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\cglib-2.1.3.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\dom4j-1.6.1.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\ehcache-1.2.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\hibernate3.jar;C:\views\ESLModel.R5\Hibernate3JAVA\lib\netjets-hibernate3.jar;C:\views\ESLModel.R5\CommonJ2EE\lib\netjets-j2ee.jar;C:\views\ESLModel.R5\CommonJ2EE\lib\quartz.jar;C:\views\ESLModel.R5\CommonJAVA\lib\jaxen-1.1-beta-2.jar;C:\views\ESLModel.R5\CommonJAVA\lib\log4j-1.2.8.jar;C:\views\ESLModel.R5\CommonJAVA\lib\netjets-common.jar;C:\views\ESLModel.R5\CommonJAVA\lib\xbean.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-beanutils-bean-collections.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-beanutils-core.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-beanutils.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-collections-2.1.1.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-dbcp-1.2.1.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-lang.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-logging-1.0.4.jar;C:\views\ESLModel.R5\JakartaCommonsSUPPORT\lib\commons-pool-1.2.jar;C:\views\ESLModel.R5\target\bin\netjets-weblogic.jar;C:\views\ESLModel.R5\WeblogicJ2EE\lib\netjets-weblogic.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit_3.1.1/junitsupport.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.1.0/junitruntime.jar, hibernate.bytecode.use_reflection_optimizer=false, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 2, hibernate.connection.provider_class=org.hibernate.connection.DatasourceConnectionProvider, java.io.tmpdir=C:\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, hibernate.query.factory_class=org.hibernate.hql.classic.ClassicQueryTranslatorFactory, com.netjets.j2ee.management.security.class=com.netjets.j2ee.management.security.WeblogicSecurityHelper, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\bea\jdk142_04\jre\lib\ext, com.netjets.util.log.directory=c:\logs, user.dir=C:\views\ESLModel.R5\ESLModelJAVA, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, hibernate.default_batch_fetch_size=10, com.netjets.common.included=true, java.naming.provider.url=http://localhost:8080, hibernate.jmx.statistics.application.name=esl-model, file.encoding=Cp1252, hibernate.transaction.manager_lookup_class=org.hibernate.transaction.WeblogicTransactionManagerLookup, java.specification.version=1.4, hibernate.show_sql=false}



006-08-11 10:40:53 INFO [main ] EntityMetamodel.java:216 - lazy property fetching available for: com.netjets.esl.reservation.model.Leg
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2523 - Static SQL for entity: com.netjets.esl.reservation.model.Leg
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2525 - Lazy select: select leg_.DEPARTUREFBOID as DEPARTU61_79_, leg_.ARRIVALFBOID as ARRIVAL62_79_, leg_.REQUEST_ID as REQUEST63_79_, leg_.NEXTLEGID as NEXTLEGID79_, leg_.REQUESTED_SELL_OFF_ID as REQUESTED65_79_ from LEG leg_ where leg_.LEGID=?
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2528 - Version select: select LEGID from LEG where LEGID =?
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2531 - Snapshot select: select leg_.LEGID, leg_.LEG_ORDER_NBR as LEG2_79_, leg_.FLOWN_STATUS as FLOWN3_79_, leg_.REQ_EST_FLIGHT_TIME as REQ4_79_, leg_.REQ_EST_FUEL_STOPS as REQ5_79_, leg_.REQ_EST_FLIGHT_DISTANCE_KM as REQ6_79_, leg_.ETD_ENTERED_BY_USER_FLAG as ETD7_79_, leg_.ETA_ENTERED_BY_USER_FLAG as ETA8_79_, leg_.DEP_LATE_EARLY_OPS_RQRD_FLAG as DEP9_79_, leg_.ARR_LATE_EARLY_OPS_RQRD_FLAG as ARR10_79_, leg_.RURAL_AIRPORT_TAX_FLAG as RURAL11_79_, leg_.FET_TAX_FLAG as FET12_79_, leg_.FET_TAX_OVERRIDE_FLAG as FET13_79_, leg_.PASSENGER_FEE_FLAG as PASSENGER14_79_, leg_.PASSENGER_FEE_OVERRIDE_FLAG as PASSENGER15_79_, leg_.RAFT_REQUIRED_FLAG as RAFT16_79_, leg_.FLIGHT_RULE_OVERRIDE_FLAG as FLIGHT17_79_, leg_.SLIDE_OVERRIDE_FOR_SLOT_FLAG as SLIDE18_79_, leg_.FERRY_CATERING_SET_FLAG as FERRY19_79_, leg_.AIRFRAME_HRS_QTY as AIRFRAME20_79_, leg_.LOG_DEVIATION_QTY as LOG21_79_, leg_.POSITIONING_HOURS_DEP_NBR as POSITIO22_79_, leg_.FLIGHT_TIME_BASE_HOURS_NBR as FLIGHT23_79_, leg_.AIRFRAME_CYCLES_QTY as AIRFRAME24_79_, leg_.EJSCHED_LOG_FLAG as EJSCHED25_79_, leg_.TENTATIVE_ETD_CD as TENTATIVE26_79_, leg_.TENTATIVE_ETA_CD as TENTATIVE27_79_, leg_.IN_MIDAS_FLAG as IN28_79_, leg_.ETD_TM as ETD29_79_, leg_.ETA_TM as ETA30_79_, leg_.ETD_SLIDE_TM as ETD31_79_, leg_.ETA_SLIDE_TM as ETA32_79_, leg_.ATA_TM as ATA33_79_, leg_.ATD_TM as ATD34_79_, leg_.OUTTIME_DAT as OUTTIME35_79_, leg_.INTIME_DAT as INTIME36_79_, leg_.INFLIGHT_UPDATED_ETA_DAT as INFLIGHT37_79_, leg_.FLIGHT_LOG_CHANGED_TS as FLIGHT38_79_, leg_.FLIGHT_RULE_ID as FLIGHT39_79_, leg_.FUEL_START_QTY as FUEL40_79_, leg_.FUEL_END_QTY as FUEL41_79_, leg_.NUMBER_OF_LANDINGS_QTY as NUMBER42_79_, leg_.EST_FLIGHT_TIME_BASE_HOURS_NBR as EST43_79_, leg_.OVERRIDDEN_TRIP_TIME as OVERRIDDEN44_79_, leg_.DEPT_FBO_OVERRIDE_REASON_ID as DEPT45_79_, leg_.ARRIVAL_FBO_OVERRIDE_REASON_ID as ARRIVAL46_79_, leg_.EJSCHED_LEG_NUMBER as EJSCHED47_79_, leg_.FLIGHT_LOG_CHANGED_BY_ID as FLIGHT48_79_, leg_.DEPT_FBO_OVERRIDE_DESC as DEPT49_79_, leg_.ARRIVAL_FBO_OVERRIDE_DESC as ARRIVAL50_79_, leg_.OVERRIDDEN_TRIP_TIME_REASON as OVERRIDDEN51_79_, leg_.WEATHER_COMMENTS as WEATHER52_79_, leg_.LOG_OVERRIDE_REASON_TXT as LOG53_79_, leg_.SYS_VERSION_NBR as SYS54_79_, leg_.SYS_LAST_CHANGED_TS as SYS55_79_, leg_.SYS_LAST_CHANGED_BY_USER_ID as SYS56_79_, leg_.POS_REGION_WAIVER_FLAG as POS57_79_, leg_.NON_REVENUE_REASON_CD as NON58_79_, leg_.POSITIONING_HOURS_ARR_NBR as POSITIO59_79_, leg_.FLIGHT_RULE_OVERRIDE_REASON as FLIGHT60_79_, leg_.DEPARTUREFBOID as DEPARTU61_79_, leg_.ARRIVALFBOID as ARRIVAL62_79_, leg_.REQUEST_ID as REQUEST63_79_, leg_.NEXTLEGID as NEXTLEGID79_, leg_.REQUESTED_SELL_OFF_ID as REQUESTED65_79_ from LEG leg_ where leg_.LEGID=?
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2534 - Insert 0: insert into LEG (LEG_ORDER_NBR, FLOWN_STATUS, REQ_EST_FLIGHT_TIME, REQ_EST_FUEL_STOPS, REQ_EST_FLIGHT_DISTANCE_KM, ETD_ENTERED_BY_USER_FLAG, ETA_ENTERED_BY_USER_FLAG, DEP_LATE_EARLY_OPS_RQRD_FLAG, ARR_LATE_EARLY_OPS_RQRD_FLAG, RURAL_AIRPORT_TAX_FLAG, FET_TAX_FLAG, FET_TAX_OVERRIDE_FLAG, PASSENGER_FEE_FLAG, PASSENGER_FEE_OVERRIDE_FLAG, RAFT_REQUIRED_FLAG, FLIGHT_RULE_OVERRIDE_FLAG, SLIDE_OVERRIDE_FOR_SLOT_FLAG, FERRY_CATERING_SET_FLAG, AIRFRAME_HRS_QTY, LOG_DEVIATION_QTY, POSITIONING_HOURS_DEP_NBR, FLIGHT_TIME_BASE_HOURS_NBR, AIRFRAME_CYCLES_QTY, EJSCHED_LOG_FLAG, TENTATIVE_ETD_CD, TENTATIVE_ETA_CD, IN_MIDAS_FLAG, ETD_TM, ETA_TM, ETD_SLIDE_TM, ETA_SLIDE_TM, ATA_TM, ATD_TM, OUTTIME_DAT, INTIME_DAT, INFLIGHT_UPDATED_ETA_DAT, FLIGHT_LOG_CHANGED_TS, FLIGHT_RULE_ID, FUEL_START_QTY, FUEL_END_QTY, NUMBER_OF_LANDINGS_QTY, EST_FLIGHT_TIME_BASE_HOURS_NBR, OVERRIDDEN_TRIP_TIME, DEPT_FBO_OVERRIDE_REASON_ID, ARRIVAL_FBO_OVERRIDE_REASON_ID, EJSCHED_LEG_NUMBER, FLIGHT_LOG_CHANGED_BY_ID, DEPT_FBO_OVERRIDE_DESC, ARRIVAL_FBO_OVERRIDE_DESC, OVERRIDDEN_TRIP_TIME_REASON, WEATHER_COMMENTS, LOG_OVERRIDE_REASON_TXT, SYS_VERSION_NBR, SYS_LAST_CHANGED_TS, SYS_LAST_CHANGED_BY_USER_ID, POS_REGION_WAIVER_FLAG, NON_REVENUE_REASON_CD, POSITIONING_HOURS_ARR_NBR, FLIGHT_RULE_OVERRIDE_REASON, DEPARTUREFBOID, ARRIVALFBOID, REQUEST_ID, NEXTLEGID, REQUESTED_SELL_OFF_ID, LEGID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2535 - Update 0: update LEG set LEG_ORDER_NBR=?, FLOWN_STATUS=?, REQ_EST_FLIGHT_TIME=?, REQ_EST_FUEL_STOPS=?, REQ_EST_FLIGHT_DISTANCE_KM=?, ETD_ENTERED_BY_USER_FLAG=?, ETA_ENTERED_BY_USER_FLAG=?, DEP_LATE_EARLY_OPS_RQRD_FLAG=?, ARR_LATE_EARLY_OPS_RQRD_FLAG=?, RURAL_AIRPORT_TAX_FLAG=?, FET_TAX_FLAG=?, FET_TAX_OVERRIDE_FLAG=?, PASSENGER_FEE_FLAG=?, PASSENGER_FEE_OVERRIDE_FLAG=?, RAFT_REQUIRED_FLAG=?, FLIGHT_RULE_OVERRIDE_FLAG=?, SLIDE_OVERRIDE_FOR_SLOT_FLAG=?, FERRY_CATERING_SET_FLAG=?, AIRFRAME_HRS_QTY=?, LOG_DEVIATION_QTY=?, POSITIONING_HOURS_DEP_NBR=?, FLIGHT_TIME_BASE_HOURS_NBR=?, AIRFRAME_CYCLES_QTY=?, EJSCHED_LOG_FLAG=?, TENTATIVE_ETD_CD=?, TENTATIVE_ETA_CD=?, IN_MIDAS_FLAG=?, ETD_TM=?, ETA_TM=?, ETD_SLIDE_TM=?, ETA_SLIDE_TM=?, ATA_TM=?, ATD_TM=?, OUTTIME_DAT=?, INTIME_DAT=?, INFLIGHT_UPDATED_ETA_DAT=?, FLIGHT_LOG_CHANGED_TS=?, FLIGHT_RULE_ID=?, FUEL_START_QTY=?, FUEL_END_QTY=?, NUMBER_OF_LANDINGS_QTY=?, EST_FLIGHT_TIME_BASE_HOURS_NBR=?, OVERRIDDEN_TRIP_TIME=?, DEPT_FBO_OVERRIDE_REASON_ID=?, ARRIVAL_FBO_OVERRIDE_REASON_ID=?, EJSCHED_LEG_NUMBER=?, FLIGHT_LOG_CHANGED_BY_ID=?, DEPT_FBO_OVERRIDE_DESC=?, ARRIVAL_FBO_OVERRIDE_DESC=?, OVERRIDDEN_TRIP_TIME_REASON=?, WEATHER_COMMENTS=?, LOG_OVERRIDE_REASON_TXT=?, SYS_VERSION_NBR=?, SYS_LAST_CHANGED_TS=?, SYS_LAST_CHANGED_BY_USER_ID=?, POS_REGION_WAIVER_FLAG=?, NON_REVENUE_REASON_CD=?, POSITIONING_HOURS_ARR_NBR=?, FLIGHT_RULE_OVERRIDE_REASON=?, DEPARTUREFBOID=?, ARRIVALFBOID=?, REQUEST_ID=?, NEXTLEGID=?, REQUESTED_SELL_OFF_ID=? where LEGID=?
2006-08-11 10:40:54 DEBUG [main ] AbstractEntityPersister.java:2536 - Delete 0: delete from LEG where LEGID=?


2006-08-11 10:41:01 DEBUG [main ] SessionFactoryImpl.java:305 - instantiated session factory
2006-08-11 10:41:01 DEBUG [main ] SessionFactoryImpl.java:321 - obtaining JTA TransactionManager
2006-08-11 10:41:01 INFO [main ] NamingHelper.java:26 - JNDI InitialContext properties:{java.naming.provider.url=http://localhost:8080, java.naming.factory.initial=weblogic.jndi.WLInitialContextFactory}
2006-08-11 10:41:01 DEBUG [main ] SessionFactoryImpl.java:387 - Checking 0 named HQL queries
2006-08-11 10:41:01 DEBUG [main ] SessionFactoryImpl.java:407 - Checking 0 named SQL queries
2006-08-11 10:41:01 DEBUG [main ] SessionImpl.java:222 - opened session at timestamp: 4732138544218112
2006-08-11 10:41:02 DEBUG [main ] DefaultLoadEventListener.java:154 - loading entity: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:02 DEBUG [main ] DefaultLoadEventListener.java:309 - attempting to resolve: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:02 DEBUG [main ] DefaultLoadEventListener.java:345 - object not resolved in any cache: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:02 DEBUG [main ] AbstractEntityPersister.java:2814 - Fetching entity: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:02 DEBUG [main ] Loader.java:1777 - loading entity: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:02 DEBUG [main ] AbstractBatcher.java:358 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-08-11 10:41:02 DEBUG [main ] ConnectionManager.java:415 - opening JDBC connection
2006-08-11 10:41:02 DEBUG [main ] AbstractBatcher.java:393 - select leg0_.LEGID as LEGID79_0_, leg0_.LEG_ORDER_NBR as LEG2_79_0_, leg0_.FLOWN_STATUS as FLOWN3_79_0_, leg0_.REQ_EST_FLIGHT_TIME as REQ4_79_0_, leg0_.REQ_EST_FUEL_STOPS as REQ5_79_0_, leg0_.REQ_EST_FLIGHT_DISTANCE_KM as REQ6_79_0_, leg0_.ETD_ENTERED_BY_USER_FLAG as ETD7_79_0_, leg0_.ETA_ENTERED_BY_USER_FLAG as ETA8_79_0_, leg0_.DEP_LATE_EARLY_OPS_RQRD_FLAG as DEP9_79_0_, leg0_.ARR_LATE_EARLY_OPS_RQRD_FLAG as ARR10_79_0_, leg0_.RURAL_AIRPORT_TAX_FLAG as RURAL11_79_0_, leg0_.FET_TAX_FLAG as FET12_79_0_, leg0_.FET_TAX_OVERRIDE_FLAG as FET13_79_0_, leg0_.PASSENGER_FEE_FLAG as PASSENGER14_79_0_, leg0_.PASSENGER_FEE_OVERRIDE_FLAG as PASSENGER15_79_0_, leg0_.RAFT_REQUIRED_FLAG as RAFT16_79_0_, leg0_.FLIGHT_RULE_OVERRIDE_FLAG as FLIGHT17_79_0_, leg0_.SLIDE_OVERRIDE_FOR_SLOT_FLAG as SLIDE18_79_0_, leg0_.FERRY_CATERING_SET_FLAG as FERRY19_79_0_, leg0_.AIRFRAME_HRS_QTY as AIRFRAME20_79_0_, leg0_.LOG_DEVIATION_QTY as LOG21_79_0_, leg0_.POSITIONING_HOURS_DEP_NBR as POSITIO22_79_0_, leg0_.FLIGHT_TIME_BASE_HOURS_NBR as FLIGHT23_79_0_, leg0_.AIRFRAME_CYCLES_QTY as AIRFRAME24_79_0_, leg0_.EJSCHED_LOG_FLAG as EJSCHED25_79_0_, leg0_.TENTATIVE_ETD_CD as TENTATIVE26_79_0_, leg0_.TENTATIVE_ETA_CD as TENTATIVE27_79_0_, leg0_.IN_MIDAS_FLAG as IN28_79_0_, leg0_.ETD_TM as ETD29_79_0_, leg0_.ETA_TM as ETA30_79_0_, leg0_.ETD_SLIDE_TM as ETD31_79_0_, leg0_.ETA_SLIDE_TM as ETA32_79_0_, leg0_.ATA_TM as ATA33_79_0_, leg0_.ATD_TM as ATD34_79_0_, leg0_.OUTTIME_DAT as OUTTIME35_79_0_, leg0_.INTIME_DAT as INTIME36_79_0_, leg0_.INFLIGHT_UPDATED_ETA_DAT as INFLIGHT37_79_0_, leg0_.FLIGHT_LOG_CHANGED_TS as FLIGHT38_79_0_, leg0_.FLIGHT_RULE_ID as FLIGHT39_79_0_, leg0_.FUEL_START_QTY as FUEL40_79_0_, leg0_.FUEL_END_QTY as FUEL41_79_0_, leg0_.NUMBER_OF_LANDINGS_QTY as NUMBER42_79_0_, leg0_.EST_FLIGHT_TIME_BASE_HOURS_NBR as EST43_79_0_, leg0_.OVERRIDDEN_TRIP_TIME as OVERRIDDEN44_79_0_, leg0_.DEPT_FBO_OVERRIDE_REASON_ID as DEPT45_79_0_, leg0_.ARRIVAL_FBO_OVERRIDE_REASON_ID as ARRIVAL46_79_0_, leg0_.EJSCHED_LEG_NUMBER as EJSCHED47_79_0_, leg0_.FLIGHT_LOG_CHANGED_BY_ID as FLIGHT48_79_0_, leg0_.DEPT_FBO_OVERRIDE_DESC as DEPT49_79_0_, leg0_.ARRIVAL_FBO_OVERRIDE_DESC as ARRIVAL50_79_0_, leg0_.OVERRIDDEN_TRIP_TIME_REASON as OVERRIDDEN51_79_0_, leg0_.WEATHER_COMMENTS as WEATHER52_79_0_, leg0_.LOG_OVERRIDE_REASON_TXT as LOG53_79_0_, leg0_.SYS_VERSION_NBR as SYS54_79_0_, leg0_.SYS_LAST_CHANGED_TS as SYS55_79_0_, leg0_.SYS_LAST_CHANGED_BY_USER_ID as SYS56_79_0_, leg0_.POS_REGION_WAIVER_FLAG as POS57_79_0_, leg0_.NON_REVENUE_REASON_CD as NON58_79_0_, leg0_.POSITIONING_HOURS_ARR_NBR as POSITIO59_79_0_, leg0_.FLIGHT_RULE_OVERRIDE_REASON as FLIGHT60_79_0_ from LEG leg0_ where leg0_.LEGID=?
2006-08-11 10:41:02 DEBUG [main ] AbstractBatcher.java:476 - preparing statement
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:80 - binding '1148' to parameter: 1
2006-08-11 10:41:03 DEBUG [main ] AbstractBatcher.java:374 - about to open ResultSet (open ResultSets: 0, globally: 0)
2006-08-11 10:41:03 DEBUG [main ] Loader.java:682 - processing result set
2006-08-11 10:41:03 DEBUG [main ] Loader.java:687 - result set row: 0
2006-08-11 10:41:03 DEBUG [main ] Loader.java:1164 - result row: EntityKey[com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:03 DEBUG [main ] Loader.java:1347 - Initializing object from ResultSet: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:03 DEBUG [main ] AbstractEntityPersister.java:1944 - Hydrating entity: [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '1' as column: LEG2_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '1' as column: FLOWN3_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '2.4' as column: REQ4_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '0' as column: REQ5_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '1563.0' as column: REQ6_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'true' as column: ETD7_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: ETA8_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: DEP9_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: ARR10_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: RURAL11_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'true' as column: FET12_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: FET13_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: PASSENGER14_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: PASSENGER15_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: RAFT16_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: FLIGHT17_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: SLIDE18_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: FERRY19_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: AIRFRAME20_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: LOG21_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: POSITIO22_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: FLIGHT23_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: AIRFRAME24_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: EJSCHED25_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: TENTATIVE26_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: TENTATIVE27_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: IN28_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '1' as column: FLIGHT39_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: FUEL40_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: FUEL41_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: NUMBER42_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '2.4' as column: EST43_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: OVERRIDDEN44_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: DEPT45_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: ARRIVAL46_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: EJSCHED47_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'smarder' as column: FLIGHT48_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: DEPT49_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: ARRIVAL50_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: OVERRIDDEN51_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: WEATHER52_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: LOG53_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning '0' as column: SYS54_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'smarder' as column: SYS56_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:122 - returning 'false' as column: POS57_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: NON58_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: POSITIO59_79_0_
2006-08-11 10:41:03 DEBUG [main ] NullableType.java:116 - returning null as column: FLIGHT60_79_0_
2006-08-11 10:41:03 DEBUG [main ] Loader.java:709 - done processing result set (1 rows)
2006-08-11 10:41:03 DEBUG [main ] AbstractBatcher.java:381 - about to close ResultSet (open ResultSets: 1, globally: 1)
2006-08-11 10:41:03 DEBUG [main ] AbstractBatcher.java:366 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-08-11 10:41:03 DEBUG [main ] AbstractBatcher.java:525 - closing statement
2006-08-11 10:41:03 DEBUG [main ] Loader.java:839 - total objects hydrated: 1
2006-08-11 10:41:03 DEBUG [main ] TwoPhaseLoad.java:107 - resolving associations for [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedChaufferedAutosForArrival#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedChaufferedAutosForDeparture#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedRentalAutosForArrival#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedRentalAutosForDeparture#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedOtherGroundsForArrival#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedOtherGroundsForDeparture#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.requestedInternationalTripPlans#1148]
2006-08-11 10:41:03 DEBUG [main ] CollectionLoadContext.java:141 - creating collection wrapper:[com.netjets.esl.reservation.model.Leg.legTasks#1148]
2006-08-11 10:41:03 DEBUG [main ] TwoPhaseLoad.java:206 - done materializing entity [com.netjets.esl.reservation.model.Leg#1148]
2006-08-11 10:41:03 DEBUG [main ] StatefulPersistenceContext.java:748 - initializing non-lazy collections
2006-08-11 10:41:03 DEBUG [main ] Loader.java:1808 - done entity load
2006-08-11 10:41:03 DEBUG [main ] JDBCContext.java:226 - after autocommit
2006-08-11 10:41:03 DEBUG [main ] ConnectionManager.java:398 - aggressively releasing JDBC connection
2006-08-11 10:41:03 DEBUG [main ] ConnectionManager.java:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]

Could you put your snippets in code tags? I don't feel like horizontally scrolling that much today. :)

Hmm. The message doesn't scroll for me. I tried putting the SQL stuff in code tags. Hope that helps.

Rats. Even with my 24" 16x9 monitor, it still has 8 pages of horizontal scroll. Lemme cut and paste into my editor and I'll check it out.

Aha. It's Firefox that's doing it. Looks fine in IE (if I had a nickel for every time I has to say that or vice versa I'd be one wealthy individual).

I think this may have just been addressed.

http://opensource.atlassian.com/projects/hibernate/browse/HHH-1740 and related issue for details.

I'm actually in the process of getting the latest from SVN and testing this fix.

Good, because I could not see how the foreign keys were being proxied and lazy loaded. Let me know if the development snapshot has it fixed already. Only a week or so ago, I started testing RC2 and have not gotten to the instrumentation stuff yet. Good luck!

I checked the link and these defects seem to address the issue. I'm still not clear as to which version has the fix, though. I'll have to dig into it further.

Thanks for the pointer!