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 |