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

View unanswered posts | View active topics


Board index » Hibernate & Java Persistence » NHibernate Users

All times are UTC - 5 hours [ DST ]


Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 7 posts ] 
  Previous topic | Next topic 
Author Message
exp2000
 Post subject: Lazy load question
PostPosted: Thu May 15, 2008 1:27 pm 
Regular
Regular

Joined: Tue Oct 16, 2007 9:45 am
Posts: 93
I have an invoice with 5 line items. I've created custom collection that just inherits from PersistentGenericBag, and I have implemented IUserCollection type. Now I can load line items fine using the mapping below, but I do not see it lazy loaded. I expected to see 5 selects to get the line items as I iterate through the collection. I am seeing only one that loads the whole collection. Am I doing something wrong in the mapping?


Hibernate version: 1.2.1

Mapping documents:
Code:
     <!-- m 4 -->
     <bag name="lineItems" inverse="true" table="LINE_ITEM" cascade="save-update" lazy="true" batch-size="1"
         collection-type="NHibernateExtensions.Collections.PersistentBagType`1[[Core.billing.LineItem, Core]],
                    NHibernateExtensions">
       <key column="INVOICE_VEOID"/>
       <one-to-many class="Core.billing.LineItem"/>
     </bag>


Last edited by exp2000 on Fri May 16, 2008 9:58 am, edited 1 time in total.

Top
 Profile  
 
exp2000
 Post subject:
PostPosted: Thu May 15, 2008 5:06 pm 
Regular
Regular

Joined: Tue Oct 16, 2007 9:45 am
Posts: 93
So to take my collection out of the picture I declared line items as IList<LineItem> type and used this mapping, and it still does not do lazy load. Only two queries instaed of 6 expected (1 for parent and 5 for each of the childen).

Code:
     <bag name="lineItems" inverse="true" table="LINE_ITEM" cascade="save-update" lazy="true" batch-size="1" generic="true">

       <key column="INVOICE_VEOID"/>
       <one-to-many class="Core.billing.LineItem"/>
     </bag>
Top
 Profile  
 
typhos
 Post subject:
PostPosted: Fri May 16, 2008 2:14 am 
Regular
Regular

Joined: Thu Mar 06, 2008 5:06 am
Posts: 68
Lazy load works in the way that NHibernate loads the collection only when it is needed.
NHibernate is not able to load every item in the collection with an extra select...
Top
 Profile  
 
exp2000
 Post subject:
PostPosted: Fri May 16, 2008 9:54 am 
Regular
Regular

Joined: Tue Oct 16, 2007 9:45 am
Posts: 93
That is not correct:

Page 50 of the doc:
batch-size (optional, defaults to 1) specify a "batch size" for lazily fetching instances of this collection.

Page 119 of the doc:
Quote:
You may also enable batch fetching of collections. For example, if each Person has a lazy collection of Cats,
and 10 persons are currently loaded in the ISesssion, iterating through all persons will generate 10 SELECTs,
one for every call to person.Cats. If you enable batch fetching for the Cats collection in the mapping of Person


But even if if was, if I do this, it says that collection is initialized before iterating through it.

Code:
   Console.WriteLine("Begining transaction");

            BillingRepository br = new BillingRepository();
            ITransaction tx = _session.BeginTransaction();

            try
            {

                IBag<Invoice> allInvoices = br.AllInvoices();

                Console.WriteLine("Invoices count:" + allInvoices.Count());

foreach (Invoice i in allInvoices)
                {
                    Console.WriteLine("Invoice: " + i.ObjectId);
                    //Console.WriteLine(i.lineItems.Count());
                    Console.WriteLine("Bag initialized? " + NHibernate.NHibernateUtil.IsInitialized(i.lineItems));


                    foreach (LineItem li in i.lineItems)
                    {
                        Console.WriteLine("   LineItem:" + li.ObjectId.ToString());
                      //  Console.WriteLine("   line items count: " + i.lineItems.Count);
                    }
                }



where call to br.AllInvoices invokes this method in generic repository
Code:
      protected IBag<T> FindAll<T>() where T : EntityBase
        {
            try
            {
               // ICriteria c = NHSession.CreateCriteria(typeof(T));
               // IList<T> l = c.List<T>();

                return new Bag<T>(NHSession.CreateCriteria(typeof(T)).List<T>());
            }
            catch (HibernateException ex)
            {
                throw new InfrastrucureException(ex);
            }
            catch (Exception ex)
            {
                throw new InfrastrucureException(ex);
            }
        }

[/quote]


So can anybody elighten me where I am going wrong with this.
Top
 Profile  
 
typhos
 Post subject:
PostPosted: Fri May 16, 2008 9:59 am 
Regular
Regular

Joined: Thu Mar 06, 2008 5:06 am
Posts: 68
Ah, ok... sorry.

Try to set the FetchMode for your Criteria explicitly:

Code:
NHSession.CreateCriteria(typeof(T)).SetFetchMode("lineItems", FetchMode.Lazy)


Does this help?
Top
 Profile  
 
exp2000
 Post subject:
PostPosted: Fri May 16, 2008 10:06 am 
Regular
Regular

Joined: Tue Oct 16, 2007 9:45 am
Posts: 93
I'll try that just to see if it works, but I think one should not have to do that, otherwise I cannot have a generic repository, or better yet, I should not be concered with it in a code, but in the mapping.

Thanks for the tip.
Top
 Profile  
 
exp2000
 Post subject:
PostPosted: Fri May 16, 2008 10:17 am 
Regular
Regular

Joined: Tue Oct 16, 2007 9:45 am
Posts: 93
No difference whatsoever. Here is the full output in log file

Code:
2008-05-16 09:12:37,070 [TestRunnerThread] INFO  NHibernate.Cfg.Environment - NHibernate 1.2.0.4000 (1.2.0.4000)
2008-05-16 09:12:37,085 [TestRunnerThread] INFO  NHibernate.Cfg.Environment - nhibernate section not found in application configuration file
2008-05-16 09:12:37,086 [TestRunnerThread] INFO  NHibernate.Cfg.Environment - Bytecode provider name : lcg
2008-05-16 09:12:37,088 [TestRunnerThread] INFO  NHibernate.Cfg.Environment - Using reflection optimizer
2008-05-16 09:12:37,606 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - connection.provider=
        NHibernate.Connection.DriverConnectionProvider
     
2008-05-16 09:12:37,607 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - dialect=
        NHibernate.Dialect.MsSql2005Dialect
       
2008-05-16 09:12:37,607 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - connection.driver_class=
        NHibernate.Driver.SqlClientDriver
       
2008-05-16 09:12:37,607 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - connection.connection_string=
        Server=INTELLIUNWD-PC\SQLEXPRESS;User Id=sa;Password=password;Database=AR200;
       
2008-05-16 09:12:37,607 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - show_sql=
        true
     
2008-05-16 09:12:37,607 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - <-Data
2008-05-16 09:12:37,608 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Searching for mapped documents in assembly: Data
2008-05-16 09:12:37,730 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Adding embedded mapping document: Data.Mappings.billing.Invoice.hbm.xml
2008-05-16 09:12:37,730 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Mapping resource: Data.Mappings.billing.Invoice.hbm.xml
2008-05-16 09:12:37,829 [TestRunnerThread] INFO  NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2005Dialect
2008-05-16 09:12:38,109 [TestRunnerThread] INFO  NHibernate.Cfg.HbmBinder - Mapping class: Core.billing.Invoice -> INVOICE
2008-05-16 09:12:38,141 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: RecordId -> VEOID, type: Int32
2008-05-16 09:12:38,162 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: number -> NUMBER, type: String
2008-05-16 09:12:38,163 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: date -> INV_DATE, type: DateTime
2008-05-16 09:12:38,163 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: unpaidBalance -> UNPAID_BALANCE, type: Double
2008-05-16 09:12:38,183 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: lineItems, type: IList`1
2008-05-16 09:12:38,184 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Adding embedded mapping document: Data.Mappings.billing.LineItem.hbm.xml
2008-05-16 09:12:38,184 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Mapping resource: Data.Mappings.billing.LineItem.hbm.xml
2008-05-16 09:12:38,185 [TestRunnerThread] INFO  NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2005Dialect
2008-05-16 09:12:38,185 [TestRunnerThread] INFO  NHibernate.Cfg.HbmBinder - Mapping class: Core.billing.LineItem -> LINE_ITEM
2008-05-16 09:12:38,185 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: RecordId -> VEOID, type: Int32
2008-05-16 09:12:38,185 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: description -> DESCRIPTION, type: String
2008-05-16 09:12:38,185 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: unitPrice -> UNIT_PRICE, type: Single
2008-05-16 09:12:38,185 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: quantity -> QUANTITY, type: Int32
2008-05-16 09:12:38,193 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: invoice -> INVOICE_VEOID, type: Invoice
2008-05-16 09:12:38,193 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - properties: System.Collections.Hashtable
2008-05-16 09:12:38,196 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - processing one-to-many association mappings
2008-05-16 09:12:38,197 [TestRunnerThread] DEBUG NHibernate.Cfg.CollectionSecondPass - Second pass for collection: Core.billing.Invoice.lineItems
2008-05-16 09:12:38,201 [TestRunnerThread] INFO  NHibernate.Cfg.HbmBinder - mapping collection: Core.billing.Invoice.lineItems -> LINE_ITEM
2008-05-16 09:12:38,202 [TestRunnerThread] DEBUG NHibernate.Cfg.CollectionSecondPass - Mapped collection key: INVOICE_VEOID, one-to-many: LineItem
2008-05-16 09:12:38,202 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - processing one-to-one association property references
2008-05-16 09:12:38,202 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - processing foreign key constraints
2008-05-16 09:12:38,204 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - resolving reference to class: Invoice
2008-05-16 09:12:38,221 [TestRunnerThread] INFO  NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2005Dialect
2008-05-16 09:12:38,223 [TestRunnerThread] INFO  NHibernate.Connection.ConnectionProviderFactory - Initializing connection provider:
        NHibernate.Connection.DriverConnectionProvider
     
2008-05-16 09:12:38,224 [TestRunnerThread] INFO  NHibernate.Connection.ConnectionProvider - Configuring ConnectionProvider
2008-05-16 09:12:38,227 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Optimize cache for minimal puts: False
2008-05-16 09:12:38,227 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Connection release mode: auto
2008-05-16 09:12:38,227 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - echoing all SQL to stdout
2008-05-16 09:12:38,227 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Query translator: NHibernate.Hql.Classic.ClassicQueryTranslatorFactory
2008-05-16 09:12:38,229 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Query language substitutions: {}
2008-05-16 09:12:38,230 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - cache provider: NHibernate.Cache.NoCacheProvider, NHibernate, Version=1.2.0.4000, Culture=neutral, PublicKeyToken=aa95f207798dfdb4
2008-05-16 09:12:38,239 [TestRunnerThread] INFO  NHibernate.Impl.SessionFactoryImpl - building session factory
2008-05-16 09:12:38,240 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryImpl - instantiating session factory with properties: {hibernate.dialect=
        NHibernate.Dialect.MsSql2005Dialect
        , hibernate.connection.connection_string=
        Server=INTELLIUNWD-PC\SQLEXPRESS;User Id=sa;Password=password;Database=AR200;
        , dialect=
        NHibernate.Dialect.MsSql2005Dialect
        , connection.driver_class=
        NHibernate.Driver.SqlClientDriver
        , hibernate.connection.driver_class=
        NHibernate.Driver.SqlClientDriver
        , hibernate.show_sql=
        true
      , hibernate.use_reflection_optimizer=true, connection.connection_string=
        Server=INTELLIUNWD-PC\SQLEXPRESS;User Id=sa;Password=password;Database=AR200;
        , connection.provider=
        NHibernate.Connection.DriverConnectionProvider
      , hibernate.connection.provider=
        NHibernate.Connection.DriverConnectionProvider
      , show_sql=
        true
      }
2008-05-16 09:12:38,451 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryObjectFactory - initializing class SessionFactoryObjectFactory
2008-05-16 09:12:38,452 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryObjectFactory - registered: 046edaf13c5a4da3b9d61bfcaa21a00f(unnamed)
2008-05-16 09:12:38,452 [TestRunnerThread] INFO  NHibernate.Impl.SessionFactoryObjectFactory - no name configured
2008-05-16 09:12:38,530 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=?
2008-05-16 09:12:38,530 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=?
2008-05-16 09:12:38,530 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ with (updlock, rowlock) WHERE lineitem0_.VEOID=?
2008-05-16 09:12:38,531 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ with (updlock, rowlock) WHERE lineitem0_.VEOID=?
2008-05-16 09:12:38,533 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID0_0_, invoice0_.NUMBER as NUMBER0_0_, invoice0_.INV_DATE as INV3_0_0_, invoice0_.UNPAID_BALANCE as UNPAID4_0_0_ FROM INVOICE invoice0_ WHERE invoice0_.VEOID=?
2008-05-16 09:12:38,533 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID0_0_, invoice0_.NUMBER as NUMBER0_0_, invoice0_.INV_DATE as INV3_0_0_, invoice0_.UNPAID_BALANCE as UNPAID4_0_0_ FROM INVOICE invoice0_ WHERE invoice0_.VEOID=?
2008-05-16 09:12:38,533 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID0_0_, invoice0_.NUMBER as NUMBER0_0_, invoice0_.INV_DATE as INV3_0_0_, invoice0_.UNPAID_BALANCE as UNPAID4_0_0_ FROM INVOICE invoice0_ with (updlock, rowlock) WHERE invoice0_.VEOID=?
2008-05-16 09:12:38,533 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID0_0_, invoice0_.NUMBER as NUMBER0_0_, invoice0_.INV_DATE as INV3_0_0_, invoice0_.UNPAID_BALANCE as UNPAID4_0_0_ FROM INVOICE invoice0_ with (updlock, rowlock) WHERE invoice0_.VEOID=?
2008-05-16 09:12:38,548 [TestRunnerThread] DEBUG NHibernate.Loader.Collection.OneToManyLoader - Static select for one-to-many Core.billing.Invoice.lineItems: SELECT lineitems0_.INVOICE_VEOID as INVOICE5___1_, lineitems0_.VEOID as VEOID1_, lineitems0_.VEOID as VEOID1_0_, lineitems0_.DESCRIPTION as DESCRIPT2_1_0_, lineitems0_.UNIT_PRICE as UNIT3_1_0_, lineitems0_.QUANTITY as QUANTITY1_0_, lineitems0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitems0_ WHERE lineitems0_.INVOICE_VEOID=?
2008-05-16 09:12:38,548 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryImpl - Instantiated session factory
2008-05-16 09:12:38,559 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - opened session
2008-05-16 09:12:38,575 [TestRunnerThread] DEBUG NHibernate.Transaction.AdoTransaction - begin
2008-05-16 09:12:38,576 [TestRunnerThread] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2008-05-16 09:12:39,104 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - connection.provider=
        NHibernate.Connection.DriverConnectionProvider
     
2008-05-16 09:12:39,104 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - dialect=
        NHibernate.Dialect.MsSql2005Dialect
       
2008-05-16 09:12:39,104 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - connection.driver_class=
        NHibernate.Driver.SqlClientDriver
       
2008-05-16 09:12:39,104 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - connection.connection_string=
        Server=INTELLIUNWD-PC\SQLEXPRESS;User Id=sa;Password=password;Database=AR200;
       
2008-05-16 09:12:39,104 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - show_sql=
        true
     
2008-05-16 09:12:39,104 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - <-Data
2008-05-16 09:12:39,104 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Searching for mapped documents in assembly: Data
2008-05-16 09:12:39,104 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Adding embedded mapping document: Data.Mappings.billing.Invoice.hbm.xml
2008-05-16 09:12:39,105 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Mapping resource: Data.Mappings.billing.Invoice.hbm.xml
2008-05-16 09:12:39,121 [TestRunnerThread] INFO  NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2005Dialect
2008-05-16 09:12:39,121 [TestRunnerThread] INFO  NHibernate.Cfg.HbmBinder - Mapping class: Core.billing.Invoice -> INVOICE
2008-05-16 09:12:39,121 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: RecordId -> VEOID, type: Int32
2008-05-16 09:12:39,122 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: number -> NUMBER, type: String
2008-05-16 09:12:39,122 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: date -> INV_DATE, type: DateTime
2008-05-16 09:12:39,122 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: unpaidBalance -> UNPAID_BALANCE, type: Double
2008-05-16 09:12:39,122 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: lineItems, type: IList`1
2008-05-16 09:12:39,122 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Adding embedded mapping document: Data.Mappings.billing.LineItem.hbm.xml
2008-05-16 09:12:39,122 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - Mapping resource: Data.Mappings.billing.LineItem.hbm.xml
2008-05-16 09:12:39,123 [TestRunnerThread] INFO  NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2005Dialect
2008-05-16 09:12:39,123 [TestRunnerThread] INFO  NHibernate.Cfg.HbmBinder - Mapping class: Core.billing.LineItem -> LINE_ITEM
2008-05-16 09:12:39,123 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: RecordId -> VEOID, type: Int32
2008-05-16 09:12:39,123 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: description -> DESCRIPTION, type: String
2008-05-16 09:12:39,123 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: unitPrice -> UNIT_PRICE, type: Single
2008-05-16 09:12:39,123 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: quantity -> QUANTITY, type: Int32
2008-05-16 09:12:39,124 [TestRunnerThread] DEBUG NHibernate.Cfg.HbmBinder - Mapped property: invoice -> INVOICE_VEOID, type: Invoice
2008-05-16 09:12:39,124 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - properties: System.Collections.Hashtable
2008-05-16 09:12:39,124 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - processing one-to-many association mappings
2008-05-16 09:12:39,124 [TestRunnerThread] DEBUG NHibernate.Cfg.CollectionSecondPass - Second pass for collection: Core.billing.Invoice.lineItems
2008-05-16 09:12:39,124 [TestRunnerThread] INFO  NHibernate.Cfg.HbmBinder - mapping collection: Core.billing.Invoice.lineItems -> LINE_ITEM
2008-05-16 09:12:39,124 [TestRunnerThread] DEBUG NHibernate.Cfg.CollectionSecondPass - Mapped collection key: INVOICE_VEOID, one-to-many: LineItem
2008-05-16 09:12:39,124 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - processing one-to-one association property references
2008-05-16 09:12:39,124 [TestRunnerThread] INFO  NHibernate.Cfg.Configuration - processing foreign key constraints
2008-05-16 09:12:39,124 [TestRunnerThread] DEBUG NHibernate.Cfg.Configuration - resolving reference to class: Invoice
2008-05-16 09:12:39,124 [TestRunnerThread] INFO  NHibernate.Dialect.Dialect - Using dialect: NHibernate.Dialect.MsSql2005Dialect
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Connection.ConnectionProviderFactory - Initializing connection provider:
        NHibernate.Connection.DriverConnectionProvider
     
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Connection.ConnectionProvider - Configuring ConnectionProvider
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Optimize cache for minimal puts: False
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Connection release mode: auto
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - echoing all SQL to stdout
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Query translator: NHibernate.Hql.Classic.ClassicQueryTranslatorFactory
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - Query language substitutions: {}
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Cfg.SettingsFactory - cache provider: NHibernate.Cache.NoCacheProvider, NHibernate, Version=1.2.0.4000, Culture=neutral, PublicKeyToken=aa95f207798dfdb4
2008-05-16 09:12:39,125 [TestRunnerThread] INFO  NHibernate.Impl.SessionFactoryImpl - building session factory
2008-05-16 09:12:39,125 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryImpl - instantiating session factory with properties: {hibernate.dialect=
        NHibernate.Dialect.MsSql2005Dialect
        , hibernate.connection.connection_string=
        Server=INTELLIUNWD-PC\SQLEXPRESS;User Id=sa;Password=password;Database=AR200;
        , dialect=
        NHibernate.Dialect.MsSql2005Dialect
        , connection.driver_class=
        NHibernate.Driver.SqlClientDriver
        , hibernate.connection.driver_class=
        NHibernate.Driver.SqlClientDriver
        , hibernate.show_sql=
        true
      , hibernate.use_reflection_optimizer=true, connection.connection_string=
        Server=INTELLIUNWD-PC\SQLEXPRESS;User Id=sa;Password=password;Database=AR200;
        , connection.provider=
        NHibernate.Connection.DriverConnectionProvider
      , hibernate.connection.provider=
        NHibernate.Connection.DriverConnectionProvider
      , show_sql=
        true
      }
2008-05-16 09:12:39,128 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryObjectFactory - registered: 3e3fd88d36b34d97bfef35f746113414(unnamed)
2008-05-16 09:12:39,128 [TestRunnerThread] INFO  NHibernate.Impl.SessionFactoryObjectFactory - no name configured
2008-05-16 09:12:39,128 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID3_0_, lineitem0_.DESCRIPTION as DESCRIPT2_3_0_, lineitem0_.UNIT_PRICE as UNIT3_3_0_, lineitem0_.QUANTITY as QUANTITY3_0_, lineitem0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=?
2008-05-16 09:12:39,128 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID3_0_, lineitem0_.DESCRIPTION as DESCRIPT2_3_0_, lineitem0_.UNIT_PRICE as UNIT3_3_0_, lineitem0_.QUANTITY as QUANTITY3_0_, lineitem0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID3_0_, lineitem0_.DESCRIPTION as DESCRIPT2_3_0_, lineitem0_.UNIT_PRICE as UNIT3_3_0_, lineitem0_.QUANTITY as QUANTITY3_0_, lineitem0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitem0_ with (updlock, rowlock) WHERE lineitem0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.LineItem: SELECT lineitem0_.VEOID as VEOID3_0_, lineitem0_.DESCRIPTION as DESCRIPT2_3_0_, lineitem0_.UNIT_PRICE as UNIT3_3_0_, lineitem0_.QUANTITY as QUANTITY3_0_, lineitem0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitem0_ with (updlock, rowlock) WHERE lineitem0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID2_0_, invoice0_.NUMBER as NUMBER2_0_, invoice0_.INV_DATE as INV3_2_0_, invoice0_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE invoice0_ WHERE invoice0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID2_0_, invoice0_.NUMBER as NUMBER2_0_, invoice0_.INV_DATE as INV3_2_0_, invoice0_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE invoice0_ WHERE invoice0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID2_0_, invoice0_.NUMBER as NUMBER2_0_, invoice0_.INV_DATE as INV3_2_0_, invoice0_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE invoice0_ with (updlock, rowlock) WHERE invoice0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Entity.AbstractEntityLoader - Static select for entity Core.billing.Invoice: SELECT invoice0_.VEOID as VEOID2_0_, invoice0_.NUMBER as NUMBER2_0_, invoice0_.INV_DATE as INV3_2_0_, invoice0_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE invoice0_ with (updlock, rowlock) WHERE invoice0_.VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Loader.Collection.OneToManyLoader - Static select for one-to-many Core.billing.Invoice.lineItems: SELECT lineitems0_.INVOICE_VEOID as INVOICE5___1_, lineitems0_.VEOID as VEOID1_, lineitems0_.VEOID as VEOID3_0_, lineitems0_.DESCRIPTION as DESCRIPT2_3_0_, lineitems0_.UNIT_PRICE as UNIT3_3_0_, lineitems0_.QUANTITY as QUANTITY3_0_, lineitems0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitems0_ WHERE lineitems0_.INVOICE_VEOID=?
2008-05-16 09:12:39,129 [TestRunnerThread] DEBUG NHibernate.Impl.SessionFactoryImpl - Instantiated session factory
2008-05-16 09:12:39,144 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - opened session
2008-05-16 09:12:39,170 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - flushing session
2008-05-16 09:12:39,176 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushing entities and processing referenced collections
2008-05-16 09:12:39,178 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Processing unreferenced collections
2008-05-16 09:12:39,178 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - scheduling collection removes/(re)creates/updates
2008-05-16 09:12:39,179 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
2008-05-16 09:12:39,179 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2008-05-16 09:12:39,181 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - dont need to execute flush
2008-05-16 09:12:39,206 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Opened new IDbCommand, open IDbCommands: 1
2008-05-16 09:12:39,206 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Building an IDbCommand object for the SqlString: SELECT this_.VEOID as VEOID2_0_, this_.NUMBER as NUMBER2_0_, this_.INV_DATE as INV3_2_0_, this_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE this_
2008-05-16 09:12:39,208 [TestRunnerThread] INFO  NHibernate.Loader.Loader - SELECT this_.VEOID as VEOID2_0_, this_.NUMBER as NUMBER2_0_, this_.INV_DATE as INV3_2_0_, this_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE this_
2008-05-16 09:12:39,214 [TestRunnerThread] DEBUG NHibernate.SQL - SELECT this_.VEOID as VEOID2_0_, this_.NUMBER as NUMBER2_0_, this_.INV_DATE as INV3_2_0_, this_.UNPAID_BALANCE as UNPAID4_2_0_ FROM INVOICE this_
2008-05-16 09:12:39,214 [TestRunnerThread] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2008-05-16 09:12:39,287 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Opened IDataReader, open IDataReaders: 1
2008-05-16 09:12:39,290 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - processing result set
2008-05-16 09:12:39,293 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result set row: 0
2008-05-16 09:12:39,319 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: VEOID2_0_
2008-05-16 09:12:39,322 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result row: 11010
2008-05-16 09:12:39,328 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [Core.billing.Invoice#11010]
2008-05-16 09:12:39,331 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Hydrating entity: Core.billing.Invoice#11010
2008-05-16 09:12:39,333 [TestRunnerThread] DEBUG NHibernate.Type.StringType - returning '1' as column: NUMBER2_0_
2008-05-16 09:12:39,335 [TestRunnerThread] DEBUG NHibernate.Type.DateTimeType - returning '5/13/2008' as column: INV3_2_0_
2008-05-16 09:12:39,337 [TestRunnerThread] DEBUG NHibernate.Type.DoubleType - returning null as column: UNPAID4_2_0_
2008-05-16 09:12:39,344 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - done processing result set (1 rows)
2008-05-16 09:12:39,345 [TestRunnerThread] DEBUG NHibernate.Driver.NHybridDataReader - running NHybridDataReader.Dispose()
2008-05-16 09:12:39,348 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Closed IDataReader, open IDataReaders :0
2008-05-16 09:12:39,350 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Closed IDbCommand, open IDbCommands: 0
2008-05-16 09:12:39,351 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,352 [TestRunnerThread] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2008-05-16 09:12:39,363 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - total objects hydrated: 1
2008-05-16 09:12:39,365 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [Core.billing.Invoice#11010]
2008-05-16 09:12:39,370 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - creating collection wrapper:[Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,383 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - initializing collection [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,383 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - checking second-level cache
2008-05-16 09:12:39,386 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - collection not cached
2008-05-16 09:12:39,389 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - loading collection: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,395 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Opened new IDbCommand, open IDbCommands: 1
2008-05-16 09:12:39,395 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Building an IDbCommand object for the SqlString: SELECT lineitems0_.INVOICE_VEOID as INVOICE5___1_, lineitems0_.VEOID as VEOID1_, lineitems0_.VEOID as VEOID3_0_, lineitems0_.DESCRIPTION as DESCRIPT2_3_0_, lineitems0_.UNIT_PRICE as UNIT3_3_0_, lineitems0_.QUANTITY as QUANTITY3_0_, lineitems0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitems0_ WHERE lineitems0_.INVOICE_VEOID=?
2008-05-16 09:12:39,396 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - binding '11010' to parameter: 0
2008-05-16 09:12:39,397 [TestRunnerThread] INFO  NHibernate.Loader.Loader - SELECT lineitems0_.INVOICE_VEOID as INVOICE5___1_, lineitems0_.VEOID as VEOID1_, lineitems0_.VEOID as VEOID3_0_, lineitems0_.DESCRIPTION as DESCRIPT2_3_0_, lineitems0_.UNIT_PRICE as UNIT3_3_0_, lineitems0_.QUANTITY as QUANTITY3_0_, lineitems0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitems0_ WHERE lineitems0_.INVOICE_VEOID=@p0
2008-05-16 09:12:39,397 [TestRunnerThread] DEBUG NHibernate.SQL - SELECT lineitems0_.INVOICE_VEOID as INVOICE5___1_, lineitems0_.VEOID as VEOID1_, lineitems0_.VEOID as VEOID3_0_, lineitems0_.DESCRIPTION as DESCRIPT2_3_0_, lineitems0_.UNIT_PRICE as UNIT3_3_0_, lineitems0_.QUANTITY as QUANTITY3_0_, lineitems0_.INVOICE_VEOID as INVOICE5_3_0_ FROM LINE_ITEM lineitems0_ WHERE lineitems0_.INVOICE_VEOID=@p0; @p0 = '11010'
2008-05-16 09:12:39,397 [TestRunnerThread] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2008-05-16 09:12:39,497 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Opened IDataReader, open IDataReaders: 1
2008-05-16 09:12:39,498 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result set contains (possibly empty) collection: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,499 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - uninitialized collection: initializing
2008-05-16 09:12:39,502 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - processing result set
2008-05-16 09:12:39,502 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result set row: 0
2008-05-16 09:12:39,502 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '102' as column: VEOID3_0_
2008-05-16 09:12:39,503 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result row: 102
2008-05-16 09:12:39,503 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [Core.billing.LineItem#102]
2008-05-16 09:12:39,503 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Hydrating entity: Core.billing.LineItem#102
2008-05-16 09:12:39,503 [TestRunnerThread] DEBUG NHibernate.Type.StringType - returning 'test line item' as column: DESCRIPT2_3_0_
2008-05-16 09:12:39,504 [TestRunnerThread] DEBUG NHibernate.Type.SingleType - returning '2' as column: UNIT3_3_0_
2008-05-16 09:12:39,505 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '5' as column: QUANTITY3_0_
2008-05-16 09:12:39,505 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5_3_0_
2008-05-16 09:12:39,507 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5___1_
2008-05-16 09:12:39,507 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - found row of collection: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,507 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - reading row
2008-05-16 09:12:39,509 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '102' as column: VEOID1_
2008-05-16 09:12:39,512 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [LineItem#102]
2008-05-16 09:12:39,514 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [LineItem#102]
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.LineItem#102]
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result set row: 1
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '103' as column: VEOID3_0_
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result row: 103
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [Core.billing.LineItem#103]
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Hydrating entity: Core.billing.LineItem#103
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Type.StringType - returning 'test line item 2' as column: DESCRIPT2_3_0_
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Type.SingleType - returning '2' as column: UNIT3_3_0_
2008-05-16 09:12:39,516 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '5' as column: QUANTITY3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5_3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5___1_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - found row of collection: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - reading row
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '103' as column: VEOID1_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [LineItem#103]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [LineItem#103]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.LineItem#103]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result set row: 2
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '104' as column: VEOID3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result row: 104
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [Core.billing.LineItem#104]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Hydrating entity: Core.billing.LineItem#104
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.StringType - returning 'test line imtem 3' as column: DESCRIPT2_3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.SingleType - returning '2' as column: UNIT3_3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '5' as column: QUANTITY3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5_3_0_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5___1_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - found row of collection: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - reading row
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '104' as column: VEOID1_
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [LineItem#104]
2008-05-16 09:12:39,517 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [LineItem#104]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.LineItem#104]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result set row: 3
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '105' as column: VEOID3_0_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - result row: 105
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: [Core.billing.LineItem#105]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - Hydrating entity: Core.billing.LineItem#105
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.StringType - returning 'test line imtem 4' as column: DESCRIPT2_3_0_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.SingleType - returning '2' as column: UNIT3_3_0_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '5' as column: QUANTITY3_0_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5_3_0_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '11010' as column: INVOICE5___1_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - found row of collection: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - reading row
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - returning '105' as column: VEOID1_
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [LineItem#105]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [LineItem#105]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.LineItem#105]
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - done processing result set (4 rows)
2008-05-16 09:12:39,518 [TestRunnerThread] DEBUG NHibernate.Driver.NHybridDataReader - running NHybridDataReader.Dispose()
2008-05-16 09:12:39,521 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Closed IDataReader, open IDataReaders :0
2008-05-16 09:12:39,521 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Closed IDbCommand, open IDbCommands: 0
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - total objects hydrated: 4
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [Core.billing.LineItem#102]
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#11010]
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#11010]
2008-05-16 09:12:39,522 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.Invoice#11010]
2008-05-16 09:12:39,525 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [Core.billing.LineItem#102]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [Core.billing.LineItem#103]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [Core.billing.LineItem#103]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [Core.billing.LineItem#104]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [Core.billing.LineItem#104]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [Core.billing.LineItem#105]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [Core.billing.Invoice#11010]
2008-05-16 09:12:39,526 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [Core.billing.LineItem#105]
2008-05-16 09:12:39,529 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - 1 collections were found in result set
2008-05-16 09:12:39,533 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - collection fully initialized: [Core.billing.Invoice.lineItems#11010]
2008-05-16 09:12:39,533 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - 1 collections initialized
2008-05-16 09:12:39,534 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - done loading collection
2008-05-16 09:12:39,534 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - collection initialized
2008-05-16 09:12:39,535 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [Core.billing.Invoice#11010]
2008-05-16 09:12:39,535 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - initializing non-lazy collections
2008-05-16 09:12:39,536 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - after autocommit
2008-05-16 09:12:39,538 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,538 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2008-05-16 09:12:39,588 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - SaveOrUpdate() persistent instance
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Transaction.AdoTransaction - commit
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - flushing session
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushing entities and processing referenced collections
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Processing unreferenced collections
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - scheduling collection removes/(re)creates/updates
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
2008-05-16 09:12:39,589 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2008-05-16 09:12:39,590 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - executing flush
2008-05-16 09:12:39,590 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - registering flush begin
2008-05-16 09:12:39,591 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - registering flush end
2008-05-16 09:12:39,592 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - post flush
2008-05-16 09:12:39,593 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - before transaction completion
2008-05-16 09:12:39,597 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,597 [TestRunnerThread] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2008-05-16 09:12:39,597 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2008-05-16 09:12:39,597 [TestRunnerThread] DEBUG NHibernate.Transaction.AdoTransaction - running AdoTransaction.Dispose()
2008-05-16 09:12:39,599 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - loading [LineItem#0]
2008-05-16 09:12:39,747 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - after autocommit
2008-05-16 09:12:39,747 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,747 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - transaction completion
2008-05-16 09:12:39,753 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [LineItem#0]
2008-05-16 09:12:39,753 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - object not resolved in any cache [Core.billing.LineItem#0]
2008-05-16 09:12:39,755 [TestRunnerThread] DEBUG NHibernate.Persister.Entity.AbstractEntityPersister - Fetching entity: [Core.billing.LineItem#0]
2008-05-16 09:12:39,757 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - loading entity: [Core.billing.LineItem#0]
2008-05-16 09:12:39,757 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Opened new IDbCommand, open IDbCommands: 1
2008-05-16 09:12:39,757 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Building an IDbCommand object for the SqlString: SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=?
2008-05-16 09:12:39,757 [TestRunnerThread] DEBUG NHibernate.Type.Int32Type - binding '0' to parameter: 0
2008-05-16 09:12:39,757 [TestRunnerThread] INFO  NHibernate.Loader.Loader - SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=@p0
2008-05-16 09:12:39,757 [TestRunnerThread] DEBUG NHibernate.SQL - SELECT lineitem0_.VEOID as VEOID1_0_, lineitem0_.DESCRIPTION as DESCRIPT2_1_0_, lineitem0_.UNIT_PRICE as UNIT3_1_0_, lineitem0_.QUANTITY as QUANTITY1_0_, lineitem0_.INVOICE_VEOID as INVOICE5_1_0_ FROM LINE_ITEM lineitem0_ WHERE lineitem0_.VEOID=@p0; @p0 = '0'
2008-05-16 09:12:39,760 [TestRunnerThread] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Opened IDataReader, open IDataReaders: 1
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - processing result set
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - done processing result set (0 rows)
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Driver.NHybridDataReader - running NHybridDataReader.Dispose()
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Closed IDataReader, open IDataReaders :0
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - Closed IDbCommand, open IDbCommands: 0
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Connection.ConnectionProvider - Closing connection
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - total objects hydrated: 0
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - initializing non-lazy collections
2008-05-16 09:12:39,772 [TestRunnerThread] DEBUG NHibernate.Loader.Loader - done entity load
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - flushing session
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushing entities and processing referenced collections
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Processing unreferenced collections
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - scheduling collection removes/(re)creates/updates
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - executing flush
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - registering flush begin
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - registering flush end
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.ConnectionManager - aggressively releasing database connection
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - post flush
2008-05-16 09:12:39,774 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - closing session
2008-05-16 09:12:39,775 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - running BatcherImpl.Dispose(true)
2008-05-16 09:12:39,776 [TestRunnerThread] DEBUG NHibernate.Transaction.AdoTransaction - running AdoTransaction.Dispose()
2008-05-16 09:12:40,235 [TestRunnerThread] DEBUG NHibernate.Impl.SessionImpl - closing session
2008-05-16 09:12:40,235 [TestRunnerThread] DEBUG NHibernate.Impl.BatcherImpl - running BatcherImpl.Dispose(true)
2008-05-16 09:12:40,235 [TestRunnerThread] DEBUG NHibernate.Transaction.AdoTransaction - running AdoTransaction.Dispose()
Top
 Profile  
 
Display posts from previous:  Sort by  
Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 7 posts ] 

Board index » Hibernate & Java Persistence » NHibernate Users

All times are UTC - 5 hours [ DST ]

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

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