anyone a clue
this is what log4net tells me:
Code:
2006-04-21 15:12:41,884 [2628] INFO NHibernate.Loader.Loader - select invoice0_.RECH_SYSNR as RECH_SYSNR0_, customer1_.KUNDE_SYSNR as KUNDE_SY1_1_, invoiceite2_.RECHPOS_SYSNR as RECHPOS_1_2_, invoice0_.KUNDE_SYSNR as KUNDE_SY2_0_, invoice0_.BEZAHLTKZ as BEZAHLTKZ0_, customer1_.NACHNAME as NACHNAME1_, customer1_.ROLLE as ROLLE1_, customer1_.LOESCHKZ as LOESCHKZ1_, customer1_.LETZTEAEND as LETZTEAEND1_, customer1_.PLZ as PLZ1_, customer1_.GESCHLECHT as GESCHLECHT1_, customer1_.ORT as ORT1_, customer1_.VORNAME as VORNAME1_, customer1_.TELEFON as TELEFON1_, customer1_.STRASSE as STRASSE1_, customer1_.GEBURTSDATUM as GEBURTSD7_1_, invoiceite2_.MENGE as MENGE2_, invoiceite2_.ARTIKEL_SYSNR as ARTIKEL_4_2_, invoiceite2_.RECH_SYSNR as RECH_SYSNR2_, invoiceite2_.RECH_SYSNR as RECH_SYSNR__, invoiceite2_.RECHPOS_SYSNR as RECHPOS_1___ from RECHNUNG invoice0_ left outer join KUNDE customer1_ on invoice0_.KUNDE_SYSNR=customer1_.KUNDE_SYSNR left outer join RECHNUNGSPOS invoiceite2_ on invoice0_.RECH_SYSNR=invoiceite2_.RECH_SYSNR order by invoice0_.RECH_SYSNR , invoiceite2_.RECHPOS_SYSNR DESC, invoiceite2_.RECHPOS_SYSNR desc
2006-04-21 15:12:41,884 [2628] DEBUG NHibernate.SQL - select invoice0_.RECH_SYSNR as RECH_SYSNR0_, customer1_.KUNDE_SYSNR as KUNDE_SY1_1_, invoiceite2_.RECHPOS_SYSNR as RECHPOS_1_2_, invoice0_.KUNDE_SYSNR as KUNDE_SY2_0_, invoice0_.BEZAHLTKZ as BEZAHLTKZ0_, customer1_.NACHNAME as NACHNAME1_, customer1_.ROLLE as ROLLE1_, customer1_.LOESCHKZ as LOESCHKZ1_, customer1_.LETZTEAEND as LETZTEAEND1_, customer1_.PLZ as PLZ1_, customer1_.GESCHLECHT as GESCHLECHT1_, customer1_.ORT as ORT1_, customer1_.VORNAME as VORNAME1_, customer1_.TELEFON as TELEFON1_, customer1_.STRASSE as STRASSE1_, customer1_.GEBURTSDATUM as GEBURTSD7_1_, invoiceite2_.MENGE as MENGE2_, invoiceite2_.ARTIKEL_SYSNR as ARTIKEL_4_2_, invoiceite2_.RECH_SYSNR as RECH_SYSNR2_, invoiceite2_.RECH_SYSNR as RECH_SYSNR__, invoiceite2_.RECHPOS_SYSNR as RECHPOS_1___ from RECHNUNG invoice0_ left outer join KUNDE customer1_ on invoice0_.KUNDE_SYSNR=customer1_.KUNDE_SYSNR left outer join RECHNUNGSPOS invoiceite2_ on invoice0_.RECH_SYSNR=invoiceite2_.RECH_SYSNR order by invoice0_.RECH_SYSNR , invoiceite2_.RECHPOS_SYSNR DESC, invoiceite2_.RECHPOS_SYSNR desc
2006-04-21 15:12:41,899 [2628] DEBUG NHibernate.Connection.DriverConnectionProvider - Obtaining IDbConnection from Driver
2006-04-21 15:12:42,618 [2628] DEBUG NHibernate.Impl.BatcherImpl - Opened Reader, open Readers :1
2006-04-21 15:12:42,618 [2628] DEBUG NHibernate.Loader.Loader - processing result set
2006-04-21 15:12:42,680 [2628] DEBUG NHibernate.Loader.Loader - result row: 1, 1, 7
2006-04-21 15:12:42,680 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 1
2006-04-21 15:12:42,696 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.Invoice#1
2006-04-21 15:12:42,696 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 1
2006-04-21 15:12:42,696 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.Customer#1
2006-04-21 15:12:42,712 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 7
2006-04-21 15:12:42,712 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#7
2006-04-21 15:12:42,712 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,712 [2628] DEBUG NHibernate.Impl.SessionImpl - new collection: instantiating
2006-04-21 15:12:42,727 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#7]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#7]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#7]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - result row: 1, 1, 6
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 6
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#6
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - reading row
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#6]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#6]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#6]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - result row: 1, 1, 5
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 5
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#5
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - reading row
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#5]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#5]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#5]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - result row: 1, 1, 4
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 4
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#4
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - reading row
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#4]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#4]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#4]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - result row: 1, 1, 3
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 3
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#3
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - reading row
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#3]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#3]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#3]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - result row: 1, 1, 1
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 1
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#1
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - reading row
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#1]
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - result row: 2, 2, 2
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 2
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.Invoice#2
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 2
2006-04-21 15:12:42,743 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.Customer#2
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Loader.Loader - Initializing object from DataReader: 2
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Loader.Loader - Hydrating entity: com.poi.poa.cross.model.InvoiceItem#2
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Loader.Loader - found row of collection: [com.poi.poa.cross.model.Invoice.invoiceItems#2]
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.SessionImpl - new collection: instantiating
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [InvoiceItem#2]
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [InvoiceItem#2]
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.InvoiceItem#2]
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Loader.Loader - done processing result set (7 rows)
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.BatcherImpl - Closed Reader, open Readers :0
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.BatcherImpl - Closed IDbCommand, open IDbCommands :0
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Loader.Loader - total objects hydrated: 11
2006-04-21 15:12:42,759 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:42,774 [2628] DEBUG NHibernate.Impl.SessionImpl - returning loading collection:[com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:42,774 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Customer#1]
2006-04-21 15:12:42,774 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Customer#1]
2006-04-21 15:12:42,774 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Customer#1]
2006-04-21 15:12:42,774 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:42,774 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.Customer#1]
2006-04-21 15:12:42,790 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.Customer#1]
2006-04-21 15:12:42,790 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#7]
2006-04-21 15:12:42,790 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#9]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#7]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#6]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#5]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#6]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#5]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#7]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#5]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#4]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#5]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#4]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#3]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#7]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#3]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#3]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.Invoice#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - returning loading collection:[com.poi.poa.cross.model.Invoice.invoiceItems#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Customer#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Customer#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Customer#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.Invoice#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.Customer#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.Customer#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolving associations for: [com.poi.poa.cross.model.InvoiceItem#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Article#1]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - loading [Invoice#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - attempting to resolve [Invoice#2]
2006-04-21 15:12:43,102 [2628] DEBUG NHibernate.Impl.SessionImpl - resolved object in session cache [com.poi.poa.cross.model.Invoice#2]
2006-04-21 15:12:43,118 [2628] DEBUG NHibernate.Impl.SessionImpl - done materializing entity [com.poi.poa.cross.model.InvoiceItem#2]
2006-04-21 15:12:43,118 [2628] DEBUG NHibernate.Impl.SessionImpl - 2 collections were found in result set
2006-04-21 15:12:43,118 [2628] DEBUG NHibernate.Impl.SessionImpl - collection fully initialized: [com.poi.poa.cross.model.Invoice.invoiceItems#2]
2006-04-21 15:12:43,118 [2628] DEBUG NHibernate.Impl.SessionImpl - collection fully initialized: [com.poi.poa.cross.model.Invoice.invoiceItems#1]
2006-04-21 15:12:43,118 [2628] DEBUG NHibernate.Impl.SessionImpl - 2 collections initialized
2006-04-21 15:12:43,118 [2628] DEBUG NHibernate.Impl.SessionImpl - initializing non-lazy collections