|
From: NHibernate J. <mik...@us...> - 2006-11-07 23:13:50
|
Custom load-collection with Stored Procedure
--------------------------------------------
Key: NH-794
URL: http://jira.nhibernate.org/browse/NH-794
Project: NHibernate
Type: Bug
Components: Core
Versions: 1.2.0.Beta1
Reporter: Bill Pierce
Priority: Minor
Attachments: NHibernateSqlLazyLoad.zip
I am using a custom loader to load a collection using a Stored Procedure. The SQL executes just fine, data is returned from the sproc, the objects appear to be hydrated just fine, however the resulting IList is empty when I try to iterate it or get a .Count. No errors are thrown and everything else appears to be working normally. I tried lazy/non lazy, tried using a Set instead of a Bag, but nothing seems to be working.
Attached is a small project + sql files needed to recreate the issue.
[b]Hibernate version:[/b]
1.2.0.2001
[b]Mapping documents:[/b]
<?xml version="1.0" encoding="utf-8" ?>
<hibernate-mapping xmlns="urn:nhibernate-mapping-2.0" assembly="NHibernateSqlLazyLoad" namespace="NHibernateSqlLazyLoad">
<class name="InventoryItem" mutable="false">
<composite-id>
<key-property name="SellerId" access="nosetter.camelcase-underscore" />
<key-property name="ItemId" access="nosetter.camelcase-underscore" />
</composite-id>
<property name="Qualifier" />
<property name="Name" />
<bag name="Alternates" access="nosetter.camelcase-underscore" generic="true">
<key>
<column name="SellerId" />
<column name="ItemId" />
</key>
<one-to-many class="InventoryItem" />
<loader query-ref="GetAlternateItems" />
</bag>
</class>
<sql-query name="GetAlternateItems">
<load-collection alias="alternateItem" role="InventoryItem.Alternates" />
EXEC GetAlternateItems ?, ?
</sql-query>
</hibernate-mapping>
[b]Code between sessionFactory.openSession() and session.close():[/b]
using (ISession session = _sessionFactory.OpenSession())
{
InventoryItem lookup = new InventoryItem(1031086, 1);
InventoryItem item = session.Get<InventoryItem>(lookup);
lookup = null;
Assert.AreEqual(2, item.Alternates.Count);
}
[b]Full stack trace of any exception that occurs:[/b]
[b]Name and version of the database you are using:[/b]
Microsoft SQL Server 2000 SP4
[b]The generated SQL (show_sql=true):[/b]
EXEC GetAlternateItems @p0, @p1; @p0 = '1031086', @p1 = '1'
[b]Debug level Hibernate log excerpt:[/b]
2006-11-07 16:10:02,023 [5400] INFO NHibernate.Cfg.Environment - NHibernate 1.2.0.2001 (1.2.0.Beta1)
2006-11-07 16:10:02,132 [5400] INFO NHibernate.Cfg.Environment - Bytecode provider name : lcg
2006-11-07 16:10:02,132 [5400] INFO NHibernate.Cfg.Environment - Using reflection optimizer
2006-11-07 16:10:02,147 [5400] INFO NHibernate.Cfg.Configuration - Mapping file: InventoryItem.hbm.xml
2006-11-07 16:10:02,878 [5400] INFO NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2000Dialect
2006-11-07 16:10:03,018 [5400] INFO NHibernate.Cfg.HbmBinder - Mapping class: NHibernateSqlLazyLoad.InventoryItem -> InventoryItem
2006-11-07 16:10:03,049 [5400] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: SellerId -> SellerId, type: Int32
2006-11-07 16:10:03,049 [5400] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: ItemId -> ItemId, type: Int32
2006-11-07 16:10:03,096 [5400] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: Qualifier -> Qualifier, type: String
2006-11-07 16:10:03,096 [5400] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: Name -> Name, type: String
2006-11-07 16:10:03,111 [5400] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: Alternates, type: IList`1
2006-11-07 16:10:03,111 [5400] INFO NHibernate.Cfg.Configuration - processing one-to-many association mappings
2006-11-07 16:10:03,127 [5400] DEBUG NHibernate.Cfg.CollectionSecondPass - Second pass for collection: NHibernateSqlLazyLoad.InventoryItem.Alternates
2006-11-07 16:10:03,127 [5400] INFO NHibernate.Cfg.HbmBinder - mapping collection: NHibernateSqlLazyLoad.InventoryItem.Alternates -> InventoryItem
2006-11-07 16:10:03,127 [5400] DEBUG NHibernate.Cfg.CollectionSecondPass - Mapped collection key: SellerId, ItemId, one-to-many: InventoryItem
2006-11-07 16:10:03,158 [5400] DEBUG NHibernate.Cfg.NamedSQLQuerySecondPass - Named SQL query: GetAlternateItems -> EXEC GetAlternateItems ?, ?
2006-11-07 16:10:03,158 [5400] INFO NHibernate.Cfg.Configuration - processing one-to-one association property references
2006-11-07 16:10:03,158 [5400] INFO NHibernate.Cfg.Configuration - processing foreign key constraints
2006-11-07 16:10:03,158 [5400] DEBUG NHibernate.Cfg.Configuration - resolving reference to class: InventoryItem
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2000Dialect
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Connection.ConnectionProviderFactory - Intitializing connection provider: NHibernate.Connection.DriverConnectionProvider
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Connection.ConnectionProvider - Configuring ConnectionProvider
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Cfg.SettingsFactory - Optimize cache for minimal puts: False
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Cfg.SettingsFactory - echoing all SQL to stdout
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Cfg.SettingsFactory - Query language substitutions: {}
2006-11-07 16:10:03,173 [5400] INFO NHibernate.Cfg.SettingsFactory - cache provider: NHibernate.Cache.HashtableCacheProvider
2006-11-07 16:10:03,189 [5400] INFO NHibernate.Impl.SessionFactoryImpl - building session factory
2006-11-07 16:10:03,189 [5400] DEBUG NHibernate.Impl.SessionFactoryImpl - instantiating session factory with properties: {hibernate.connection.driver_class=NHibernate.Driver.SqlClientDriver, hibernate.dialect=NHibernate.Dialect.MsSql2000Dialect, hibernate.connection.provider=NHibernate.Connection.DriverConnectionProvider, hibernate.use_reflection_optimizer=true, hibernate.show_sql=true, hibernate.connection.connection_string=Server=(local);Database=WCPierce;Trusted_Connection=True;}
2006-11-07 16:10:03,298 [5400] DEBUG NHibernate.Impl.SessionFactoryObjectFactory - initializing class SessionFactoryObjectFactory
2006-11-07 16:10:03,313 [5400] DEBUG NHibernate.Impl.SessionFactoryObjectFactory - registered: 8089699df1764b6fb8e1d2890a511db2(unnamed)
2006-11-07 16:10:03,313 [5400] INFO NHibernate.Impl.SessionFactoryObjectFactory - no name configured
2006-11-07 16:10:03,329 [5400] DEBUG NHibernate.SqlCommand.SqlSelectBuilder - The initial capacity was set too low at: 8 for the SelectSqlBuilder that needed a capacity of: 12 for the table InventoryItem inventoryi_
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.SqlCommand.SqlSelectBuilder - The initial capacity was set too low at: 8 for the SelectSqlBuilder that needed a capacity of: 12 for the table InventoryItem inventoryi0_
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity NHibernateSqlLazyLoad.InventoryItem: SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=? and inventoryi0_.ItemId=?
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.SqlCommand.SqlSelectBuilder - The initial capacity was set too low at: 8 for the SelectSqlBuilder that needed a capacity of: 12 for the table InventoryItem inventoryi0_
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity NHibernateSqlLazyLoad.InventoryItem: SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=? and inventoryi0_.ItemId=?
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.SqlCommand.SqlSelectBuilder - The initial capacity was set too low at: 8 for the SelectSqlBuilder that needed a capacity of: 12 for the table InventoryItem inventoryi0_
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity NHibernateSqlLazyLoad.InventoryItem: SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=? and inventoryi0_.ItemId=?
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.SqlCommand.SqlSelectBuilder - The initial capacity was set too low at: 8 for the SelectSqlBuilder that needed a capacity of: 12 for the table InventoryItem inventoryi0_
2006-11-07 16:10:03,376 [5400] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity NHibernateSqlLazyLoad.InventoryItem: SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=? and inventoryi0_.ItemId=?
2006-11-07 16:10:03,391 [5400] DEBUG NHibernate.Impl.SessionFactoryImpl - Instantiated session factory
2006-11-07 16:10:03,407 [5400] DEBUG NHibernate.Impl.SessionImpl - opened session
2006-11-07 16:10:03,422 [5400] DEBUG NHibernate.Impl.SessionImpl - loading [InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:03,422 [5400] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:03,422 [5400] DEBUG NHibernate.Impl.SessionImpl - object not resolved in any cache [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:03,422 [5400] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Fetching entity: [NHibernateSqlLazyLoad.InventoryItem#InventoryItem{ItemId=1, SellerId=1031086}]
2006-11-07 16:10:03,422 [5400] DEBUG NHibernate.Loader.Loader - loading entity: [NHibernateSqlLazyLoad.InventoryItem#InventoryItem{ItemId=1, SellerId=1031086}]
2006-11-07 16:10:03,516 [5400] DEBUG NHibernate.Impl.BatcherImpl - Opened new IDbCommand, open IDbCommands :1
2006-11-07 16:10:03,516 [5400] DEBUG NHibernate.Impl.BatcherImpl - Building an IDbCommand object for the SqlString: SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=? and inventoryi0_.ItemId=?
2006-11-07 16:10:03,516 [5400] DEBUG NHibernate.Type.Int32Type - binding '1031086' to parameter: 0
2006-11-07 16:10:03,516 [5400] DEBUG NHibernate.Type.Int32Type - binding '1' to parameter: 1
2006-11-07 16:10:03,516 [5400] INFO NHibernate.Loader.Loader - SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=@p0 and inventoryi0_.ItemId=@p1
2006-11-07 16:10:03,516 [5400] DEBUG NHibernate.SQL - SELECT inventoryi0_.SellerId as SellerId0_0_, inventoryi0_.ItemId as ItemId0_0_, inventoryi0_.Qualifier as Qualifier0_0_, inventoryi0_.Name as Name0_0_ FROM InventoryItem inventoryi0_ WHERE inventoryi0_.SellerId=@p0 and inventoryi0_.ItemId=@p1; @p0 = '1031086', @p1 = '1'
2006-11-07 16:10:03,516 [5400] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2006-11-07 16:10:04,619 [5400] DEBUG NHibernate.Impl.BatcherImpl - Opened Reader, open Readers :1
2006-11-07 16:10:04,635 [5400] DEBUG NHibernate.Loader.Loader - processing result set
2006-11-07 16:10:04,635 [5400] DEBUG NHibernate.Loader.Loader - result set row: 0
2006-11-07 16:10:04,650 [5400] DEBUG NHibernate.Loader.Loader - result row: NHibernateSqlLazyLoad.InventoryItem/1031086/1
2006-11-07 16:10:04,650 [5400] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,650 [5400] DEBUG NHibernate.Loader.Loader - Hydrating entity: NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1
2006-11-07 16:10:04,697 [5400] DEBUG NHibernate.Type.StringType - returning 'BRG' as column: Qualifier0_0_
2006-11-07 16:10:04,697 [5400] DEBUG NHibernate.Type.StringType - returning '260097' as column: Name0_0_
2006-11-07 16:10:04,713 [5400] DEBUG NHibernate.Loader.Loader - done processing result set (1 rows)
2006-11-07 16:10:04,728 [5400] DEBUG NHibernate.Driver.NHybridDataReader - running NHybridDataReader.Dispose()
2006-11-07 16:10:04,728 [5400] DEBUG NHibernate.Impl.BatcherImpl - Closed Reader, open Readers :0
2006-11-07 16:10:04,728 [5400] DEBUG NHibernate.Impl.BatcherImpl - Closed IDbCommand, open IDbCommands :0
2006-11-07 16:10:04,728 [5400] DEBUG NHibernate.Loader.Loader - total objects hydrated: 1
2006-11-07 16:10:04,744 [5400] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,744 [5400] DEBUG NHibernate.Impl.SessionImpl - creating collection wrapper:[NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,759 [5400] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,759 [5400] DEBUG NHibernate.Impl.SessionImpl - initializing non-lazy collections
2006-11-07 16:10:04,759 [5400] DEBUG NHibernate.Loader.Loader - done entity load
2006-11-07 16:10:04,775 [5400] DEBUG NHibernate.Impl.SessionImpl - initializing collection [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,775 [5400] DEBUG NHibernate.Impl.SessionImpl - checking second-level cache
2006-11-07 16:10:04,775 [5400] DEBUG NHibernate.Impl.SessionImpl - collection not cached
2006-11-07 16:10:04,775 [5400] DEBUG NHibernate.Persister.Collection.NamedQueryCollectionInitializer - initializing collection: NHibernateSqlLazyLoad.InventoryItem.Alternates using named query: GetAlternateItems
2006-11-07 16:10:04,821 [5400] DEBUG NHibernate.Loader.Custom.SQLCustomQuery - starting processing of sql query [EXEC GetAlternateItems ?, ?]
2006-11-07 16:10:04,821 [5400] DEBUG NHibernate.Loader.Custom.SQLQueryReturnProcessor - mapping alias [alternateItem] to collection-suffix [0__]
2006-11-07 16:10:04,821 [5400] DEBUG NHibernate.Loader.Custom.SQLQueryReturnProcessor - mapping alias [alternateItem] to entity-suffix [0_]
2006-11-07 16:10:04,868 [5400] DEBUG NHibernate.Impl.BatcherImpl - Opened new IDbCommand, open IDbCommands :1
2006-11-07 16:10:04,884 [5400] DEBUG NHibernate.Impl.BatcherImpl - Building an IDbCommand object for the SqlString: EXEC GetAlternateItems ?, ?
2006-11-07 16:10:04,884 [5400] DEBUG NHibernate.Type.Int32Type - binding '1031086' to parameter: 0
2006-11-07 16:10:04,884 [5400] DEBUG NHibernate.Type.Int32Type - binding '1' to parameter: 1
2006-11-07 16:10:04,884 [5400] INFO NHibernate.Loader.Loader - EXEC GetAlternateItems @p0, @p1
2006-11-07 16:10:04,884 [5400] DEBUG NHibernate.SQL - EXEC GetAlternateItems @p0, @p1; @p0 = '1031086', @p1 = '1'
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Impl.BatcherImpl - Opened Reader, open Readers :1
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Loader.Loader - result set contains (possibly empty) collection: [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Impl.SessionImpl - uninitialized collection: initializing
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Loader.Loader - processing result set
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Loader.Loader - result set row: 0
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Type.Int32Type - returning '1031086' as column: SellerId
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Type.Int32Type - returning '2' as column: ItemId
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Loader.Loader - result row: NHibernateSqlLazyLoad.InventoryItem/1031086/2
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Loader.Loader - Hydrating entity: NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Type.StringType - returning 'BRG' as column: Qualifier
2006-11-07 16:10:04,915 [5400] DEBUG NHibernate.Type.StringType - returning '397222' as column: Name
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '1031086' as column: SellerId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '2' as column: ItemId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - found row of collection: [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - new collection: instantiating
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '1031086' as column: SellerId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '2' as column: ItemId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - loading [InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - result set row: 1
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '1031086' as column: SellerId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '3' as column: ItemId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - result row: NHibernateSqlLazyLoad.InventoryItem/1031086/3
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - Hydrating entity: NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.StringType - returning 'BRG' as column: Qualifier
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.StringType - returning '695745' as column: Name
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '1031086' as column: SellerId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '3' as column: ItemId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - found row of collection: [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - new collection: instantiating
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '1031086' as column: SellerId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Type.Int32Type - returning '3' as column: ItemId
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - loading [InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Loader.Loader - done processing result set (2 rows)
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Driver.NHybridDataReader - running NHybridDataReader.Dispose()
2006-11-07 16:10:04,930 [5400] DEBUG NHibernate.Impl.BatcherImpl - Closed Reader, open Readers :0
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.BatcherImpl - Closed IDbCommand, open IDbCommands :0
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Loader.Loader - total objects hydrated: 2
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - returning loading collection:[NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - returning loading collection:[NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [NHibernateSqlLazyLoad.InventoryItem#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - 3 collections were found in result set
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - collection fully initialized: [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/3]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - collection fully initialized: [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/2]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - collection fully initialized: [NHibernateSqlLazyLoad.InventoryItem.Alternates#NHibernateSqlLazyLoad.InventoryItem/1031086/1]
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - 3 collections initialized
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - initializing non-lazy collections
2006-11-07 16:10:04,946 [5400] DEBUG NHibernate.Impl.SessionImpl - collection initialized
2006-11-07 16:10:04,977 [5400] DEBUG NHibernate.Impl.SessionImpl - running ISession.Dispose()
2006-11-07 16:10:04,977 [5400] DEBUG NHibernate.Transaction.AdoTransaction - running AdoTransaction.Dispose()
2006-11-07 16:10:04,977 [5400] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2006-11-07 16:10:04,977 [5400] DEBUG NHibernate.Impl.BatcherImpl - running BatcherImpl.Dispose(true)
2006-11-07 16:10:04,977 [5400] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
http://jira.nhibernate.org/secure/Administrators.jspa
-
For more information on JIRA, see:
http://www.atlassian.com/software/jira
|