gistfile1.txt

2010-12-09 17:57:22,326 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (http-127.0.0.1-8080-1) deploy, ctxPath=/jmx-console
2010-12-09 17:57:28,283 INFO  [com.arjuna.ats.arjuna] (http-127.0.0.1-8080-1) ARJUNA-12163 Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 4713
2010-12-09 17:57:28,286 INFO  [com.arjuna.ats.arjuna] (http-127.0.0.1-8080-1) ARJUNA-12337 TransactionStatusManagerItem host: 127.0.0.1 port: 4713
2010-12-09 17:57:28,292 INFO  [com.arjuna.ats.arjuna] (http-127.0.0.1-8080-1) ARJUNA-12170 TransactionStatusManager started on port 4713 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
2010-12-09 17:57:28,390 DEBUG [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) opened session at timestamp: 5291679123005440
2010-12-09 17:57:28,390 TRACE [org.hibernate.jdbc.JDBCContext] (http-127.0.0.1-8080-1) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,390 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (http-127.0.0.1-8080-1) Looking for a JTA transaction to join
2010-12-09 17:57:28,409 DEBUG [org.hibernate.jdbc.JDBCContext] (http-127.0.0.1-8080-1) successfully registered Synchronization
2010-12-09 17:57:28,410 TRACE [org.hibernate.ejb.AbstractEntityManagerImpl] (http-127.0.0.1-8080-1) Adding flush() and close() synchronization
2010-12-09 17:57:28,431 TRACE [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) setting flush mode to: AUTO
2010-12-09 17:57:28,431 TRACE [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) setting cache mode to: NORMAL
2010-12-09 17:57:28,433 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (http-127.0.0.1-8080-1) Looking for a JTA transaction to join
2010-12-09 17:57:28,434 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (http-127.0.0.1-8080-1) Transaction already joined
2010-12-09 17:57:28,444 TRACE [org.hibernate.search.spi.internals.PolymorphicIndexHierarchy] (http-127.0.0.1-8080-1) Targeted indexed classes for [class java.lang.Object]: [class at.mw.contacts.jpa.Contact]
2010-12-09 17:57:28,445 DEBUG [org.hibernate.search.impl.MassIndexerImpl] (http-127.0.0.1-8080-1) Targets for indexing job: [class at.mw.contacts.jpa.Contact]
2010-12-09 17:57:28,449 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (http-127.0.0.1-8080-1) JTA sync : beforeCompletion()
2010-12-09 17:57:28,449 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (http-127.0.0.1-8080-1) transaction before completion callback
2010-12-09 17:57:28,449 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (http-127.0.0.1-8080-1) automatically flushing session
2010-12-09 17:57:28,449 TRACE [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) automatically flushing session
2010-12-09 17:57:28,450 TRACE [org.hibernate.jdbc.JDBCContext] (http-127.0.0.1-8080-1) before transaction completion
2010-12-09 17:57:28,451 TRACE [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) before transaction completion
2010-12-09 17:57:28,451 TRACE [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) closing session
2010-12-09 17:57:28,451 TRACE [org.hibernate.jdbc.ConnectionManager] (http-127.0.0.1-8080-1) connection already null in cleanup : no action
2010-12-09 17:57:28,453 TRACE [org.hibernate.search.spi.internals.PolymorphicIndexHierarchy] (Hibernate Search: batch coordinator-1) Targeted indexed classes for [class at.mw.contacts.jpa.Contact]: [class at.mw.contacts.jpa.Contact]
2010-12-09 17:57:28,455 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (http-127.0.0.1-8080-1) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,455 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (http-127.0.0.1-8080-1) transaction after completion callback [status=3]
2010-12-09 17:57:28,456 TRACE [org.hibernate.search.backend.Workspace] (Hibernate Search: batch coordinator-1) IndexWriter opened
2010-12-09 17:57:28,456 TRACE [org.hibernate.search.backend.impl.lucene.works.PurgeAllWorkDelegate] (Hibernate Search: batch coordinator-1) purgeAll Lucene index using IndexWriter for type: class at.mw.contacts.jpa.Contact
2010-12-09 17:57:28,458 TRACE [org.hibernate.jdbc.JDBCContext] (http-127.0.0.1-8080-1) after transaction completion
2010-12-09 17:57:28,459 TRACE [org.hibernate.impl.SessionImpl] (http-127.0.0.1-8080-1) after transaction completion
2010-12-09 17:57:28,473 TRACE [org.hibernate.search.backend.impl.lucene.works.OptimizeWorkDelegate] (Hibernate Search: batch coordinator-1) optimize Lucene index: class at.mw.contacts.jpa.Contact
2010-12-09 17:57:28,481 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-1) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,481 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-3) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,484 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-5) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,484 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-3) opened session at timestamp: 5291679123390464
2010-12-09 17:57:28,484 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-5) opened session at timestamp: 5291679123390465
2010-12-09 17:57:28,485 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-6) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,485 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-6) opened session at timestamp: 5291679123394560
2010-12-09 17:57:28,485 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-7) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,485 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-7) opened session at timestamp: 5291679123394561
2010-12-09 17:57:28,482 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-1) opened session at timestamp: 5291679123382272
2010-12-09 17:57:28,481 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-2) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,486 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-2) opened session at timestamp: 5291679123398656
2010-12-09 17:57:28,486 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-8) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,486 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-8) opened session at timestamp: 5291679123398657
2010-12-09 17:57:28,482 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: collectionsloader-4) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,486 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-4) opened session at timestamp: 5291679123398658
2010-12-09 17:57:28,491 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-4) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,491 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-4) setting flush mode to: MANUAL
2010-12-09 17:57:28,491 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-4) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,491 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-4) setting cache mode to: NORMAL
2010-12-09 17:57:28,487 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-3) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,493 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-3) setting flush mode to: MANUAL
2010-12-09 17:57:28,494 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-3) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,494 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-3) setting cache mode to: NORMAL
2010-12-09 17:57:28,496 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-2) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,496 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-2) setting flush mode to: MANUAL
2010-12-09 17:57:28,496 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-2) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,496 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-2) setting cache mode to: NORMAL
2010-12-09 17:57:28,496 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-2) begin
2010-12-09 17:57:28,496 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-6) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,497 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-2) successfully registered Synchronization
2010-12-09 17:57:28,497 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-6) setting flush mode to: MANUAL
2010-12-09 17:57:28,497 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-2) after transaction begin
2010-12-09 17:57:28,497 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-8) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,497 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-8) setting flush mode to: MANUAL
2010-12-09 17:57:28,497 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-6) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,498 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-8) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,498 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-6) setting cache mode to: NORMAL
2010-12-09 17:57:28,498 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-8) setting cache mode to: NORMAL
2010-12-09 17:57:28,498 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-6) begin
2010-12-09 17:57:28,498 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-8) begin
2010-12-09 17:57:28,498 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-6) successfully registered Synchronization
2010-12-09 17:57:28,498 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-1) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,498 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-6) after transaction begin
2010-12-09 17:57:28,498 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-1) setting flush mode to: MANUAL
2010-12-09 17:57:28,496 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-4) begin
2010-12-09 17:57:28,499 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-1) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,499 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-4) successfully registered Synchronization
2010-12-09 17:57:28,499 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-5) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,498 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-8) successfully registered Synchronization
2010-12-09 17:57:28,499 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-7) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,499 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-1) setting cache mode to: NORMAL
2010-12-09 17:57:28,499 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-1) begin
2010-12-09 17:57:28,499 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-1) successfully registered Synchronization
2010-12-09 17:57:28,499 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-1) after transaction begin
2010-12-09 17:57:28,500 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-7) setting flush mode to: MANUAL
2010-12-09 17:57:28,500 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-7) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,500 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-7) setting cache mode to: NORMAL
2010-12-09 17:57:28,501 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: BatchIndexingWorkspace-1) created
2010-12-09 17:57:28,499 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-8) after transaction begin
2010-12-09 17:57:28,502 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-3) begin
2010-12-09 17:57:28,502 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-3) successfully registered Synchronization
2010-12-09 17:57:28,503 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-3) after transaction begin
2010-12-09 17:57:28,499 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-4) after transaction begin
2010-12-09 17:57:28,499 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-5) setting flush mode to: MANUAL
2010-12-09 17:57:28,504 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-5) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,504 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-5) setting cache mode to: NORMAL
2010-12-09 17:57:28,504 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-5) begin
2010-12-09 17:57:28,504 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-5) successfully registered Synchronization
2010-12-09 17:57:28,504 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-5) after transaction begin
2010-12-09 17:57:28,508 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-7) begin
2010-12-09 17:57:28,508 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-7) successfully registered Synchronization
2010-12-09 17:57:28,508 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-7) after transaction begin
2010-12-09 17:57:28,525 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: BatchIndexingWorkspace-1) created
2010-12-09 17:57:28,598 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: entityloader-1) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,614 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: BatchIndexingWorkspace-1) created
2010-12-09 17:57:28,614 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: entityloader-2) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,615 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: BatchIndexingWorkspace-1) created
2010-12-09 17:57:28,615 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: entityloader-3) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,599 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-1) started
2010-12-09 17:57:28,614 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-2) started
2010-12-09 17:57:28,615 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-3) started
2010-12-09 17:57:28,624 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: entityloader-4) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,624 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-4) started
2010-12-09 17:57:28,629 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) opened session at timestamp: 5291679123984384
2010-12-09 17:57:28,629 TRACE [org.hibernate.search.batchindexing.IdentifierProducer] (Hibernate Search: BatchIndexingWorkspace-1) created
2010-12-09 17:57:28,629 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-4) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,630 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) setting flush mode to: MANUAL
2010-12-09 17:57:28,630 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-4) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,630 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) setting cache mode to: NORMAL
2010-12-09 17:57:28,630 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-4) begin
2010-12-09 17:57:28,630 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-4) successfully registered Synchronization
2010-12-09 17:57:28,630 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-4) after transaction begin
2010-12-09 17:57:28,629 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-1) opened session at timestamp: 5291679123984387
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-1) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,629 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-2) opened session at timestamp: 5291679123984386
2010-12-09 17:57:28,629 DEBUG [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-3) opened session at timestamp: 5291679123984385
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-2) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-3) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,631 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-2) setting flush mode to: MANUAL
2010-12-09 17:57:28,631 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-3) setting flush mode to: MANUAL
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-2) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-3) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,631 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-2) setting cache mode to: NORMAL
2010-12-09 17:57:28,631 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-3) setting cache mode to: NORMAL
2010-12-09 17:57:28,631 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-2) begin
2010-12-09 17:57:28,631 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-3) begin
2010-12-09 17:57:28,631 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-3) successfully registered Synchronization
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-3) after transaction begin
2010-12-09 17:57:28,631 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-1) setting flush mode to: MANUAL
2010-12-09 17:57:28,631 TRACE [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: identifierloader-1) No Transaction in progress, needs to start a JTA transaction
2010-12-09 17:57:28,631 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-1) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,632 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-1) setting cache mode to: NORMAL
2010-12-09 17:57:28,632 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-1) begin
2010-12-09 17:57:28,632 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-1) successfully registered Synchronization
2010-12-09 17:57:28,631 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-2) successfully registered Synchronization
2010-12-09 17:57:28,633 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-2) after transaction begin
2010-12-09 17:57:28,633 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-1) after transaction begin
2010-12-09 17:57:28,632 TRACE [org.hibernate.search.batchindexing.IdentifierProducer] (Hibernate Search: identifierloader-1) started
2010-12-09 17:57:28,644 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: identifierloader-1) TransactionFactory reported no active transaction; Synchronization not registered
2010-12-09 17:57:28,645 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: identifierloader-1) begin
2010-12-09 17:57:28,645 DEBUG [org.hibernate.jdbc.JDBCContext] (Hibernate Search: identifierloader-1) successfully registered Synchronization
2010-12-09 17:57:28,645 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: identifierloader-1) after transaction begin
2010-12-09 17:57:28,688 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2010-12-09 17:57:28,688 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) opening JDBC connection
2010-12-09 17:57:28,730 DEBUG [org.hibernate.SQL] (Hibernate Search: identifierloader-1) select count(*) as y0_ from Contact this_
2010-12-09 17:57:28,731 TRACE [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) preparing statement
2010-12-09 17:57:28,734 TRACE [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) Bound [1] parameters total
2010-12-09 17:57:28,759 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to open ResultSet (open ResultSets: 0, globally: 0)
2010-12-09 17:57:28,759 TRACE [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) processing result set
2010-12-09 17:57:28,759 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result set row: 0
2010-12-09 17:57:28,760 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,767 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [19] as column [y0_]
2010-12-09 17:57:28,767 TRACE [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) done processing result set (1 rows)
2010-12-09 17:57:28,772 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to close ResultSet (open ResultSets: 1, globally: 1)
2010-12-09 17:57:28,773 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2010-12-09 17:57:28,773 TRACE [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) closing statement
2010-12-09 17:57:28,773 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) aggressively releasing JDBC connection
2010-12-09 17:57:28,773 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2010-12-09 17:57:28,778 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,778 DEBUG [org.hibernate.search.batchindexing.IdentifierProducer] (Hibernate Search: identifierloader-1) going to fetch 19 primary keys
2010-12-09 17:57:28,778 INFO  [org.hibernate.search.impl.SimpleIndexingProgressMonitor] (Hibernate Search: identifierloader-1) Going to reindex 19 entities
2010-12-09 17:57:28,781 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2010-12-09 17:57:28,782 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) opening JDBC connection
2010-12-09 17:57:28,782 DEBUG [org.hibernate.SQL] (Hibernate Search: identifierloader-1) select this_.id as y0_ from Contact this_
2010-12-09 17:57:28,782 TRACE [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) preparing statement
2010-12-09 17:57:28,789 TRACE [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) Bound [1] parameters total
2010-12-09 17:57:28,789 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to open ResultSet (open ResultSets: 0, globally: 0)
2010-12-09 17:57:28,800 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,800 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [1] as column [y0_]
2010-12-09 17:57:28,800 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,801 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,801 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [2] as column [y0_]
2010-12-09 17:57:28,801 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,801 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,801 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [3] as column [y0_]
2010-12-09 17:57:28,801 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,801 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,801 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [4] as column [y0_]
2010-12-09 17:57:28,801 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,801 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,801 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [5] as column [y0_]
2010-12-09 17:57:28,801 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,802 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,802 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [6] as column [y0_]
2010-12-09 17:57:28,802 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,802 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,802 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [7] as column [y0_]
2010-12-09 17:57:28,802 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,802 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,802 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [8] as column [y0_]
2010-12-09 17:57:28,803 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,803 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,803 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [9] as column [y0_]
2010-12-09 17:57:28,803 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,803 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,803 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [10] as column [y0_]
2010-12-09 17:57:28,803 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,803 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,803 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [11] as column [y0_]
2010-12-09 17:57:28,803 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,803 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,803 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [12] as column [y0_]
2010-12-09 17:57:28,803 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,804 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,804 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [13] as column [y0_]
2010-12-09 17:57:28,804 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,804 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,804 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [14] as column [y0_]
2010-12-09 17:57:28,804 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,804 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,804 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [15] as column [y0_]
2010-12-09 17:57:28,804 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,804 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,804 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [16] as column [y0_]
2010-12-09 17:57:28,804 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,804 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,805 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [17] as column [y0_]
2010-12-09 17:57:28,805 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,805 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,805 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [18] as column [y0_]
2010-12-09 17:57:28,805 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,805 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: identifierloader-1) result row: 
2010-12-09 17:57:28,805 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: identifierloader-1) found [19] as column [y0_]
2010-12-09 17:57:28,805 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: identifierloader-1) initializing non-lazy collections
2010-12-09 17:57:28,805 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to close ResultSet (open ResultSets: 1, globally: 1)
2010-12-09 17:57:28,805 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2010-12-09 17:57:28,805 TRACE [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: identifierloader-1) closing statement
2010-12-09 17:57:28,805 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) aggressively releasing JDBC connection
2010-12-09 17:57:28,805 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2010-12-09 17:57:28,819 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-4) received list of ids [1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19]
2010-12-09 17:57:28,826 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) setting flush mode to: MANUAL
2010-12-09 17:57:28,826 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) setting cache mode to: NORMAL
2010-12-09 17:57:28,829 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: entityloader-4) about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2010-12-09 17:57:28,835 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-4) opening JDBC connection
2010-12-09 17:57:28,836 DEBUG [org.hibernate.SQL] (Hibernate Search: entityloader-4) select this_.id as id7_0_, this_.firstName as firstName7_0_, this_.lastName as lastName7_0_ from Contact this_ where this_.id in (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
2010-12-09 17:57:28,836 TRACE [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: entityloader-4) preparing statement
2010-12-09 17:57:28,838 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [1] as [BIGINT] - 1
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [2] as [BIGINT] - 2
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [3] as [BIGINT] - 3
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [4] as [BIGINT] - 4
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [5] as [BIGINT] - 5
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [6] as [BIGINT] - 6
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [7] as [BIGINT] - 7
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [8] as [BIGINT] - 8
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [9] as [BIGINT] - 9
2010-12-09 17:57:28,839 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [10] as [BIGINT] - 10
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [11] as [BIGINT] - 11
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [12] as [BIGINT] - 12
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [13] as [BIGINT] - 13
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [14] as [BIGINT] - 14
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [15] as [BIGINT] - 15
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [16] as [BIGINT] - 16
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [17] as [BIGINT] - 17
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [18] as [BIGINT] - 18
2010-12-09 17:57:28,840 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (Hibernate Search: entityloader-4) binding parameter [19] as [BIGINT] - 19
2010-12-09 17:57:28,841 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Bound [20] parameters total
2010-12-09 17:57:28,841 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: entityloader-4) about to open ResultSet (open ResultSets: 0, globally: 0)
2010-12-09 17:57:28,841 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) processing result set
2010-12-09 17:57:28,841 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 0
2010-12-09 17:57:28,841 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [1] as column [id7_0_]
2010-12-09 17:57:28,855 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#1]
2010-12-09 17:57:28,856 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#1]
2010-12-09 17:57:28,858 TRACE [org.hibernate.search.batchindexing.IdentifierProducer] (Hibernate Search: identifierloader-1) produced a list of ids [1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19]
2010-12-09 17:57:28,863 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: identifierloader-1) commit
2010-12-09 17:57:28,863 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: identifierloader-1) connection already null in cleanup : no action
2010-12-09 17:57:28,863 TRACE [org.hibernate.search.batchindexing.IdentifierProducer] (Hibernate Search: identifierloader-1) finished
2010-12-09 17:57:28,862 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#1]
2010-12-09 17:57:28,867 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-1) commit
2010-12-09 17:57:28,869 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-1) closing session
2010-12-09 17:57:28,869 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-1) connection already null in cleanup : no action
2010-12-09 17:57:28,869 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-1) finished
2010-12-09 17:57:28,869 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-1) JTA sync : beforeCompletion()
2010-12-09 17:57:28,867 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-2) commit
2010-12-09 17:57:28,869 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-2) closing session
2010-12-09 17:57:28,869 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-2) connection already null in cleanup : no action
2010-12-09 17:57:28,869 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-2) finished
2010-12-09 17:57:28,869 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-2) JTA sync : beforeCompletion()
2010-12-09 17:57:28,867 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-3) commit
2010-12-09 17:57:28,869 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-3) closing session
2010-12-09 17:57:28,869 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-3) connection already null in cleanup : no action
2010-12-09 17:57:28,870 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-3) finished
2010-12-09 17:57:28,870 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-3) JTA sync : beforeCompletion()
2010-12-09 17:57:28,870 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Emmanuel] as column [firstName7_0_]
2010-12-09 17:57:28,870 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [bernard] as column [lastName7_0_]
2010-12-09 17:57:28,872 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 1
2010-12-09 17:57:28,872 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [2] as column [id7_0_]
2010-12-09 17:57:28,872 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#2]
2010-12-09 17:57:28,872 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#2]
2010-12-09 17:57:28,872 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#2]
2010-12-09 17:57:28,872 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Emmanuel] as column [firstName7_0_]
2010-12-09 17:57:28,872 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Bernard] as column [lastName7_0_]
2010-12-09 17:57:28,872 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 2
2010-12-09 17:57:28,873 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [3] as column [id7_0_]
2010-12-09 17:57:28,873 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#3]
2010-12-09 17:57:28,873 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#3]
2010-12-09 17:57:28,873 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#3]
2010-12-09 17:57:28,873 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Emme] as column [firstName7_0_]
2010-12-09 17:57:28,873 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdsklcs] as column [lastName7_0_]
2010-12-09 17:57:28,873 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 3
2010-12-09 17:57:28,873 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [4] as column [id7_0_]
2010-12-09 17:57:28,873 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#4]
2010-12-09 17:57:28,873 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#4]
2010-12-09 17:57:28,873 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#4]
2010-12-09 17:57:28,873 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Emmmm] as column [firstName7_0_]
2010-12-09 17:57:28,873 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Cdscds] as column [lastName7_0_]
2010-12-09 17:57:28,874 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 4
2010-12-09 17:57:28,874 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [5] as column [id7_0_]
2010-12-09 17:57:28,874 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#5]
2010-12-09 17:57:28,874 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#5]
2010-12-09 17:57:28,874 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#5]
2010-12-09 17:57:28,874 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Blah] as column [firstName7_0_]
2010-12-09 17:57:28,874 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [Blah] as column [lastName7_0_]
2010-12-09 17:57:28,874 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 5
2010-12-09 17:57:28,874 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [6] as column [id7_0_]
2010-12-09 17:57:28,874 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#6]
2010-12-09 17:57:28,874 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#6]
2010-12-09 17:57:28,874 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#6]
2010-12-09 17:57:28,874 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdsc] as column [firstName7_0_]
2010-12-09 17:57:28,875 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [gthtr] as column [lastName7_0_]
2010-12-09 17:57:28,875 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 6
2010-12-09 17:57:28,875 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [7] as column [id7_0_]
2010-12-09 17:57:28,875 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#7]
2010-12-09 17:57:28,875 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#7]
2010-12-09 17:57:28,875 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#7]
2010-12-09 17:57:28,875 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [asklml] as column [firstName7_0_]
2010-12-09 17:57:28,875 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vfdv] as column [lastName7_0_]
2010-12-09 17:57:28,875 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 7
2010-12-09 17:57:28,875 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [8] as column [id7_0_]
2010-12-09 17:57:28,875 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#8]
2010-12-09 17:57:28,875 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#8]
2010-12-09 17:57:28,875 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#8]
2010-12-09 17:57:28,876 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [nhtyjty] as column [firstName7_0_]
2010-12-09 17:57:28,876 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [juyujuy] as column [lastName7_0_]
2010-12-09 17:57:28,876 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 8
2010-12-09 17:57:28,876 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [9] as column [id7_0_]
2010-12-09 17:57:28,876 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#9]
2010-12-09 17:57:28,876 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#9]
2010-12-09 17:57:28,876 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#9]
2010-12-09 17:57:28,876 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vfvdf] as column [firstName7_0_]
2010-12-09 17:57:28,876 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vfdvfd] as column [lastName7_0_]
2010-12-09 17:57:28,876 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 9
2010-12-09 17:57:28,876 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [10] as column [id7_0_]
2010-12-09 17:57:28,876 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#10]
2010-12-09 17:57:28,876 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#10]
2010-12-09 17:57:28,877 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#10]
2010-12-09 17:57:28,877 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscds] as column [firstName7_0_]
2010-12-09 17:57:28,877 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cbrt] as column [lastName7_0_]
2010-12-09 17:57:28,877 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 10
2010-12-09 17:57:28,877 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [11] as column [id7_0_]
2010-12-09 17:57:28,877 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#11]
2010-12-09 17:57:28,877 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#11]
2010-12-09 17:57:28,877 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#11]
2010-12-09 17:57:28,877 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vdfvfd] as column [firstName7_0_]
2010-12-09 17:57:28,877 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vfdvdf] as column [lastName7_0_]
2010-12-09 17:57:28,877 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 11
2010-12-09 17:57:28,877 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [12] as column [id7_0_]
2010-12-09 17:57:28,879 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#12]
2010-12-09 17:57:28,879 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#12]
2010-12-09 17:57:28,879 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#12]
2010-12-09 17:57:28,880 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscds] as column [firstName7_0_]
2010-12-09 17:57:28,880 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscdscds] as column [lastName7_0_]
2010-12-09 17:57:28,880 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 12
2010-12-09 17:57:28,880 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [13] as column [id7_0_]
2010-12-09 17:57:28,880 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#13]
2010-12-09 17:57:28,880 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#13]
2010-12-09 17:57:28,880 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#13]
2010-12-09 17:57:28,880 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [bgrbgr] as column [firstName7_0_]
2010-12-09 17:57:28,880 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [erfer] as column [lastName7_0_]
2010-12-09 17:57:28,880 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 13
2010-12-09 17:57:28,880 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [14] as column [id7_0_]
2010-12-09 17:57:28,880 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#14]
2010-12-09 17:57:28,881 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#14]
2010-12-09 17:57:28,881 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#14]
2010-12-09 17:57:28,888 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscds] as column [firstName7_0_]
2010-12-09 17:57:28,888 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [bggbfbgf] as column [lastName7_0_]
2010-12-09 17:57:28,888 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 14
2010-12-09 17:57:28,889 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [15] as column [id7_0_]
2010-12-09 17:57:28,889 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#15]
2010-12-09 17:57:28,890 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#15]
2010-12-09 17:57:28,890 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#15]
2010-12-09 17:57:28,890 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscds] as column [firstName7_0_]
2010-12-09 17:57:28,890 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [bggbfbgf] as column [lastName7_0_]
2010-12-09 17:57:28,891 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 15
2010-12-09 17:57:28,891 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [16] as column [id7_0_]
2010-12-09 17:57:28,892 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#16]
2010-12-09 17:57:28,893 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#16]
2010-12-09 17:57:28,893 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#16]
2010-12-09 17:57:28,893 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [yhyth] as column [firstName7_0_]
2010-12-09 17:57:28,893 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [hythyt] as column [lastName7_0_]
2010-12-09 17:57:28,894 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 16
2010-12-09 17:57:28,894 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [17] as column [id7_0_]
2010-12-09 17:57:28,894 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#17]
2010-12-09 17:57:28,894 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#17]
2010-12-09 17:57:28,895 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#17]
2010-12-09 17:57:28,895 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdcds] as column [firstName7_0_]
2010-12-09 17:57:28,895 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscds] as column [lastName7_0_]
2010-12-09 17:57:28,895 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 17
2010-12-09 17:57:28,895 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [18] as column [id7_0_]
2010-12-09 17:57:28,895 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#18]
2010-12-09 17:57:28,895 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#18]
2010-12-09 17:57:28,895 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#18]
2010-12-09 17:57:28,896 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [cdscds] as column [firstName7_0_]
2010-12-09 17:57:28,896 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [bgbg] as column [lastName7_0_]
2010-12-09 17:57:28,896 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result set row: 18
2010-12-09 17:57:28,896 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [19] as column [id7_0_]
2010-12-09 17:57:28,896 DEBUG [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) result row: EntityKey[at.mw.contacts.jpa.Contact#19]
2010-12-09 17:57:28,896 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) Initializing object from ResultSet: [at.mw.contacts.jpa.Contact#19]
2010-12-09 17:57:28,896 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (Hibernate Search: entityloader-4) Hydrating entity: [at.mw.contacts.jpa.Contact#19]
2010-12-09 17:57:28,896 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vfdvfd] as column [firstName7_0_]
2010-12-09 17:57:28,896 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (Hibernate Search: entityloader-4) found [vfdvdfvdf] as column [lastName7_0_]
2010-12-09 17:57:28,896 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) done processing result set (19 rows)
2010-12-09 17:57:28,896 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: entityloader-4) about to close ResultSet (open ResultSets: 1, globally: 1)
2010-12-09 17:57:28,896 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: entityloader-4) about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2010-12-09 17:57:28,897 TRACE [org.hibernate.jdbc.AbstractBatcher] (Hibernate Search: entityloader-4) closing statement
2010-12-09 17:57:28,897 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-4) aggressively releasing JDBC connection
2010-12-09 17:57:28,897 DEBUG [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-4) releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2010-12-09 17:57:28,897 TRACE [org.hibernate.loader.Loader] (Hibernate Search: entityloader-4) total objects hydrated: 19
2010-12-09 17:57:28,897 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#1]
2010-12-09 17:57:28,869 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-1) transaction before completion callback
2010-12-09 17:57:28,903 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-1) before transaction completion
2010-12-09 17:57:28,903 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-1) before transaction completion
2010-12-09 17:57:28,903 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-1) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,903 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-1) transaction after completion callback [status=3]
2010-12-09 17:57:28,903 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-1) after transaction completion
2010-12-09 17:57:28,903 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-1) after transaction completion
2010-12-09 17:57:28,869 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-2) transaction before completion callback
2010-12-09 17:57:28,904 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-2) before transaction completion
2010-12-09 17:57:28,904 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-2) before transaction completion
2010-12-09 17:57:28,904 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-2) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,904 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-2) transaction after completion callback [status=3]
2010-12-09 17:57:28,904 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-2) after transaction completion
2010-12-09 17:57:28,904 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-2) after transaction completion
2010-12-09 17:57:28,870 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-3) transaction before completion callback
2010-12-09 17:57:28,904 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-3) before transaction completion
2010-12-09 17:57:28,904 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-3) before transaction completion
2010-12-09 17:57:28,904 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-3) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,904 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-3) transaction after completion callback [status=3]
2010-12-09 17:57:28,904 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-3) after transaction completion
2010-12-09 17:57:28,905 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-3) after transaction completion
2010-12-09 17:57:28,863 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: identifierloader-1) JTA sync : beforeCompletion()
2010-12-09 17:57:28,907 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: identifierloader-1) transaction before completion callback
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#1]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#2]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#2]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#3]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#3]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#4]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#4]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#5]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#5]
2010-12-09 17:57:28,913 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#6]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#6]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#7]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#7]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#8]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#8]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#9]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#9]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#10]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#10]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#11]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#11]
2010-12-09 17:57:28,914 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#12]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#12]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#13]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#13]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#14]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#14]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#15]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#15]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#16]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#16]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#17]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#17]
2010-12-09 17:57:28,915 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#18]
2010-12-09 17:57:28,916 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#18]
2010-12-09 17:57:28,916 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) resolving associations for [at.mw.contacts.jpa.Contact#19]
2010-12-09 17:57:28,916 DEBUG [org.hibernate.engine.TwoPhaseLoad] (Hibernate Search: entityloader-4) done materializing entity [at.mw.contacts.jpa.Contact#19]
2010-12-09 17:57:28,916 DEBUG [org.hibernate.engine.StatefulPersistenceContext] (Hibernate Search: entityloader-4) initializing non-lazy collections
2010-12-09 17:57:28,920 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: identifierloader-1) automatically flushing session
2010-12-09 17:57:28,921 DEBUG [org.hibernate.transform.DistinctResultTransformer] (Hibernate Search: entityloader-4) transformed: 19 rows to: 19 distinct results
2010-12-09 17:57:28,922 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) setting flush mode to: MANUAL
2010-12-09 17:57:28,922 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) setting cache mode to: NORMAL
2010-12-09 17:57:28,922 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: entityloader-4) commit
2010-12-09 17:57:28,922 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) closing session
2010-12-09 17:57:28,922 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: entityloader-4) connection already null in cleanup : no action
2010-12-09 17:57:28,922 TRACE [org.hibernate.search.batchindexing.IdentifierConsumerEntityProducer] (Hibernate Search: entityloader-4) finished
2010-12-09 17:57:28,922 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-4) JTA sync : beforeCompletion()
2010-12-09 17:57:28,922 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-4) transaction before completion callback
2010-12-09 17:57:28,922 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-4) before transaction completion
2010-12-09 17:57:28,922 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) before transaction completion
2010-12-09 17:57:28,923 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: entityloader-4) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,923 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: entityloader-4) transaction after completion callback [status=3]
2010-12-09 17:57:28,923 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: entityloader-4) after transaction completion
2010-12-09 17:57:28,923 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: entityloader-4) after transaction completion
2010-12-09 17:57:28,924 ERROR [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: identifierloader-1) Error during managed flush [Session is closed!]
2010-12-09 17:57:28,924 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: identifierloader-1) before transaction completion
2010-12-09 17:57:28,928 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-6) commit
2010-12-09 17:57:28,928 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-1) commit
2010-12-09 17:57:28,928 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-8) commit
2010-12-09 17:57:28,928 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-3) commit
2010-12-09 17:57:28,928 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-3) closing session
2010-12-09 17:57:28,928 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-3) connection already null in cleanup : no action
2010-12-09 17:57:28,929 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-3) finished
2010-12-09 17:57:28,929 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-3) JTA sync : beforeCompletion()
2010-12-09 17:57:28,929 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-3) transaction before completion callback
2010-12-09 17:57:28,929 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-3) before transaction completion
2010-12-09 17:57:28,929 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-3) before transaction completion
2010-12-09 17:57:28,929 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-3) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,929 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-3) transaction after completion callback [status=3]
2010-12-09 17:57:28,929 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-3) after transaction completion
2010-12-09 17:57:28,932 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-4) commit
2010-12-09 17:57:28,932 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-3) after transaction completion
2010-12-09 17:57:28,932 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-4) closing session
2010-12-09 17:57:28,932 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-4) connection already null in cleanup : no action
2010-12-09 17:57:28,932 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-4) finished
2010-12-09 17:57:28,932 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-4) JTA sync : beforeCompletion()
2010-12-09 17:57:28,932 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-5) commit
2010-12-09 17:57:28,932 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-4) transaction before completion callback
2010-12-09 17:57:28,932 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-5) closing session
2010-12-09 17:57:28,932 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-4) before transaction completion
2010-12-09 17:57:28,932 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-5) connection already null in cleanup : no action
2010-12-09 17:57:28,932 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-4) before transaction completion
2010-12-09 17:57:28,932 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-5) finished
2010-12-09 17:57:28,932 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-4) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,932 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-4) transaction after completion callback [status=3]
2010-12-09 17:57:28,932 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-7) commit
2010-12-09 17:57:28,932 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-1) closing session
2010-12-09 17:57:28,932 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-1) connection already null in cleanup : no action
2010-12-09 17:57:28,932 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-1) finished
2010-12-09 17:57:28,933 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-1) JTA sync : beforeCompletion()
2010-12-09 17:57:28,933 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-1) transaction before completion callback
2010-12-09 17:57:28,933 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-1) before transaction completion
2010-12-09 17:57:28,933 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-1) before transaction completion
2010-12-09 17:57:28,933 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-1) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,933 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-1) transaction after completion callback [status=3]
2010-12-09 17:57:28,933 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-1) after transaction completion
2010-12-09 17:57:28,933 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-1) after transaction completion
2010-12-09 17:57:28,931 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-8) closing session
2010-12-09 17:57:28,933 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-8) connection already null in cleanup : no action
2010-12-09 17:57:28,933 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-8) finished
2010-12-09 17:57:28,934 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-8) JTA sync : beforeCompletion()
2010-12-09 17:57:28,934 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-8) transaction before completion callback
2010-12-09 17:57:28,934 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-8) before transaction completion
2010-12-09 17:57:28,934 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-8) before transaction completion
2010-12-09 17:57:28,934 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-8) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,934 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-8) transaction after completion callback [status=3]
2010-12-09 17:57:28,934 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-8) after transaction completion
2010-12-09 17:57:28,934 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-8) after transaction completion
2010-12-09 17:57:28,929 TRACE [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-2) received a list of objects to index: [[#1] bernard, Emmanuel, [#2] Bernard, Emmanuel, [#3] cdsklcs, Emme, [#4] Cdscds, Emmmm, [#5] Blah, Blah, [#6] gthtr, cdsc, [#7] vfdv, asklml, [#8] juyujuy, nhtyjty, [#9] vfdvfd, vfvdf, [#10] cbrt, cdscds, [#11] vfdvdf, vdfvfd, [#12] cdscdscds, cdscds, [#13] erfer, bgrbgr, [#14] bggbfbgf, cdscds, [#15] bggbfbgf, cdscds, [#16] hythyt, yhyth, [#17] cdscds, cdcds, [#18] bgbg, cdscds, [#19] vfdvdfvdf, vfdvfd]
2010-12-09 17:57:28,933 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-6) closing session
2010-12-09 17:57:28,954 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-6) connection already null in cleanup : no action
2010-12-09 17:57:28,954 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-6) finished
2010-12-09 17:57:28,954 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-6) JTA sync : beforeCompletion()
2010-12-09 17:57:28,954 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-6) transaction before completion callback
2010-12-09 17:57:28,954 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-6) before transaction completion
2010-12-09 17:57:28,932 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-7) closing session
2010-12-09 17:57:28,958 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-7) connection already null in cleanup : no action
2010-12-09 17:57:28,958 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-7) finished
2010-12-09 17:57:28,958 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-7) JTA sync : beforeCompletion()
2010-12-09 17:57:28,958 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-7) transaction before completion callback
2010-12-09 17:57:28,958 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-7) before transaction completion
2010-12-09 17:57:28,958 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-7) before transaction completion
2010-12-09 17:57:28,958 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-7) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,959 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-7) transaction after completion callback [status=3]
2010-12-09 17:57:28,959 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-7) after transaction completion
2010-12-09 17:57:28,959 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-7) after transaction completion
2010-12-09 17:57:28,932 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-4) after transaction completion
2010-12-09 17:57:28,961 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-4) after transaction completion
2010-12-09 17:57:28,924 WARN  [com.arjuna.ats.arjuna] (Hibernate Search: identifierloader-1) ARJUNA-12125 TwoPhaseCoordinator.beforeCompletion - failed for SynchronizationImple< 0:ffff0a20c1ee:126a:4d010a55:28, org.hibernate.transaction.synchronization.HibernateSynchronizationImpl@347a99fb >: org.hibernate.SessionException: Session is closed!
	at org.hibernate.impl.AbstractSessionImpl.errorIfClosed(AbstractSessionImpl.java:72) [:3.6.0.Final]
	at org.hibernate.impl.StatelessSessionImpl.managedFlush(StatelessSessionImpl.java:332) [:3.6.0.Final]
	at org.hibernate.transaction.synchronization.CallbackCoordinator.beforeCompletion(CallbackCoordinator.java:117) [:3.6.0.Final]
	at org.hibernate.transaction.synchronization.HibernateSynchronizationImpl.beforeCompletion(HibernateSynchronizationImpl.java:51) [:3.6.0.Final]
	at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:97) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:274) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:94) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:159) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1156) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:119) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75) [:6.0.0.20101110-CR1]
	at org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction.run(OptionallyWrapInJTATransaction.java:55) [:3.3.0-SNAPSHOT]
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
	at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]

2010-12-09 17:57:28,962 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: identifierloader-1) JTA sync : afterCompletion(4)
2010-12-09 17:57:28,963 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: identifierloader-1) transaction after completion callback [status=4]
2010-12-09 17:57:28,963 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: identifierloader-1) after transaction completion
2010-12-09 17:57:28,932 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-5) JTA sync : beforeCompletion()
2010-12-09 17:57:28,964 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-5) transaction before completion callback
2010-12-09 17:57:28,964 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-5) before transaction completion
2010-12-09 17:57:28,964 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-5) before transaction completion
2010-12-09 17:57:28,964 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-5) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,964 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-5) transaction after completion callback [status=3]
2010-12-09 17:57:28,964 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-5) after transaction completion
2010-12-09 17:57:28,964 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-5) after transaction completion
2010-12-09 17:57:28,960 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-6) before transaction completion
2010-12-09 17:57:28,965 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-6) JTA sync : afterCompletion(3)
2010-12-09 17:57:28,965 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-6) transaction after completion callback [status=3]
2010-12-09 17:57:28,965 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-6) after transaction completion
2010-12-09 17:57:28,966 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-6) after transaction completion
2010-12-09 17:57:28,969 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:28,970 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#1]
2010-12-09 17:57:28,978 ERROR [org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction] (Hibernate Search: identifierloader-1) Error while executing runnable wrapped in a JTA transaction: javax.transaction.RollbackException: ARJUNA-16053 Could not commit transaction.
	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1168) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:119) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75) [:6.0.0.20101110-CR1]
	at org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction.run(OptionallyWrapInJTATransaction.java:55) [:3.3.0-SNAPSHOT]
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
	at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]
Caused by: java.lang.Throwable: setRollbackOnly called from:
	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.setRollbackOnly(TransactionImple.java:306) [:6.0.0.20101110-CR1]
	at org.hibernate.transaction.synchronization.CallbackCoordinator.setRollbackOnly(CallbackCoordinator.java:131) [:3.6.0.Final]
	at org.hibernate.transaction.synchronization.CallbackCoordinator.beforeCompletion(CallbackCoordinator.java:121) [:3.6.0.Final]
	at org.hibernate.transaction.synchronization.HibernateSynchronizationImpl.beforeCompletion(HibernateSynchronizationImpl.java:51) [:3.6.0.Final]
	at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:97) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:274) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:94) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:159) [:6.0.0.20101110-CR1]
	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1156) [:6.0.0.20101110-CR1]
	... 6 more

2010-12-09 17:57:29,026 ERROR [STDERR] (Hibernate Search: identifierloader-1) Exception in thread "Hibernate Search: identifierloader-1" java.lang.IllegalStateException: BaseTransaction.rollback - ARJUNA-16074 no transaction!
2010-12-09 17:57:29,026 ERROR [STDERR] (Hibernate Search: identifierloader-1) 	at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.rollback(BaseTransaction.java:132)
2010-12-09 17:57:29,026 ERROR [STDERR] (Hibernate Search: identifierloader-1) 	at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.rollback(BaseTransactionManagerDelegate.java:114)
2010-12-09 17:57:29,026 ERROR [STDERR] (Hibernate Search: identifierloader-1) 	at org.hibernate.search.batchindexing.OptionallyWrapInJTATransaction.run(OptionallyWrapInJTATransaction.java:61)
2010-12-09 17:57:29,027 ERROR [STDERR] (Hibernate Search: identifierloader-1) 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
2010-12-09 17:57:29,027 ERROR [STDERR] (Hibernate Search: identifierloader-1) 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
2010-12-09 17:57:29,027 ERROR [STDERR] (Hibernate Search: identifierloader-1) 	at java.lang.Thread.run(Thread.java:680)
2010-12-09 17:57:29,039 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,039 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#2]
2010-12-09 17:57:29,040 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 1, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:1> indexed,tokenized<name:Emmanuel> indexed,tokenized<name:bernard>>]#{}:{}
2010-12-09 17:57:29,040 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,040 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#3]
2010-12-09 17:57:29,042 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-2) add to Lucene index: [class at.mw.contacts.jpa.Contact, 2, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:2> indexed,tokenized<name:Emmanuel> indexed,tokenized<name:Bernard>>]#{}:{}
2010-12-09 17:57:29,043 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,043 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#4]
2010-12-09 17:57:29,043 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,044 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#5]
2010-12-09 17:57:29,044 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,044 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#6]
2010-12-09 17:57:29,044 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,044 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#7]
2010-12-09 17:57:29,045 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,045 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#8]
2010-12-09 17:57:29,045 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,045 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#9]
2010-12-09 17:57:29,045 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,045 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#10]
2010-12-09 17:57:29,046 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,046 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#11]
2010-12-09 17:57:29,046 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,046 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#12]
2010-12-09 17:57:29,046 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,046 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#13]
2010-12-09 17:57:29,046 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,046 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#14]
2010-12-09 17:57:29,047 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,047 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#15]
2010-12-09 17:57:29,047 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,047 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#16]
2010-12-09 17:57:29,047 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,048 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#17]
2010-12-09 17:57:29,048 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,048 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#18]
2010-12-09 17:57:29,048 TRACE [org.hibernate.engine.IdentifierValue] (Hibernate Search: collectionsloader-2) id unsaved-value: 0
2010-12-09 17:57:29,048 TRACE [org.hibernate.event.def.AbstractReassociateEventListener] (Hibernate Search: collectionsloader-2) reassociating transient instance: [at.mw.contacts.jpa.Contact#19]
2010-12-09 17:57:29,048 DEBUG [org.hibernate.transaction.CMTTransaction] (Hibernate Search: collectionsloader-2) commit
2010-12-09 17:57:29,048 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-2) closing session
2010-12-09 17:57:29,050 DEBUG [org.hibernate.search.batchindexing.BatchIndexingWorkspace] (Hibernate Search: BatchIndexingWorkspace-1) All work for type at.mw.contacts.jpa.Contact has been produced
2010-12-09 17:57:29,050 TRACE [org.hibernate.jdbc.ConnectionManager] (Hibernate Search: collectionsloader-2) connection already null in cleanup : no action
2010-12-09 17:57:29,050 DEBUG [org.hibernate.search.batchindexing.EntityConsumerLuceneworkProducer] (Hibernate Search: collectionsloader-2) finished
2010-12-09 17:57:29,051 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-2) JTA sync : beforeCompletion()
2010-12-09 17:57:29,051 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-2) transaction before completion callback
2010-12-09 17:57:29,051 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-2) before transaction completion
2010-12-09 17:57:29,051 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-2) before transaction completion
2010-12-09 17:57:29,051 TRACE [org.hibernate.transaction.synchronization.HibernateSynchronizationImpl] (Hibernate Search: collectionsloader-2) JTA sync : afterCompletion(3)
2010-12-09 17:57:29,051 TRACE [org.hibernate.transaction.synchronization.CallbackCoordinator] (Hibernate Search: collectionsloader-2) transaction after completion callback [status=3]
2010-12-09 17:57:29,051 TRACE [org.hibernate.jdbc.JDBCContext] (Hibernate Search: collectionsloader-2) after transaction completion
2010-12-09 17:57:29,051 TRACE [org.hibernate.impl.SessionImpl] (Hibernate Search: collectionsloader-2) after transaction completion
2010-12-09 17:57:29,079 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 3, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:3> indexed,tokenized<name:Emme> indexed,tokenized<name:cdsklcs>>]#{}:{}
2010-12-09 17:57:29,079 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-2) add to Lucene index: [class at.mw.contacts.jpa.Contact, 4, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:4> indexed,tokenized<name:Emmmm> indexed,tokenized<name:Cdscds>>]#{}:{}
2010-12-09 17:57:29,081 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 5, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:5> indexed,tokenized<name:Blah> indexed,tokenized<name:Blah>>]#{}:{}
2010-12-09 17:57:29,081 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 6, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:6> indexed,tokenized<name:cdsc> indexed,tokenized<name:gthtr>>]#{}:{}
2010-12-09 17:57:29,081 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-2) add to Lucene index: [class at.mw.contacts.jpa.Contact, 7, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:7> indexed,tokenized<name:asklml> indexed,tokenized<name:vfdv>>]#{}:{}
2010-12-09 17:57:29,082 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-2) add to Lucene index: [class at.mw.contacts.jpa.Contact, 8, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:8> indexed,tokenized<name:nhtyjty> indexed,tokenized<name:juyujuy>>]#{}:{}
2010-12-09 17:57:29,083 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 9, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:9> indexed,tokenized<name:vfvdf> indexed,tokenized<name:vfdvfd>>]#{}:{}
2010-12-09 17:57:29,083 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 10, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:10> indexed,tokenized<name:cdscds> indexed,tokenized<name:cbrt>>]#{}:{}
2010-12-09 17:57:29,084 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 11, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:11> indexed,tokenized<name:vdfvfd> indexed,tokenized<name:vfdvdf>>]#{}:{}
2010-12-09 17:57:29,084 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 12, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:12> indexed,tokenized<name:cdscds> indexed,tokenized<name:cdscdscds>>]#{}:{}
2010-12-09 17:57:29,084 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 13, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:13> indexed,tokenized<name:bgrbgr> indexed,tokenized<name:erfer>>]#{}:{}
2010-12-09 17:57:29,085 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 14, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:14> indexed,tokenized<name:cdscds> indexed,tokenized<name:bggbfbgf>>]#{}:{}
2010-12-09 17:57:29,085 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 15, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:15> indexed,tokenized<name:cdscds> indexed,tokenized<name:bggbfbgf>>]#{}:{}
2010-12-09 17:57:29,085 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 16, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:16> indexed,tokenized<name:yhyth> indexed,tokenized<name:hythyt>>]#{}:{}
2010-12-09 17:57:29,085 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 17, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:17> indexed,tokenized<name:cdcds> indexed,tokenized<name:cdscds>>]#{}:{}
2010-12-09 17:57:29,086 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 18, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:18> indexed,tokenized<name:cdscds> indexed,tokenized<name:bgbg>>]#{}:{}
2010-12-09 17:57:29,086 TRACE [org.hibernate.search.backend.impl.lucene.works.AddWorkDelegate] (Hibernate Search: indexwriter-1) add to Lucene index: [class at.mw.contacts.jpa.Contact, 19, Document<stored,indexed,omitNorms<_hibernate_class:at.mw.contacts.jpa.Contact> stored,indexed,omitNorms<id:19> indexed,tokenized<name:vfdvfd> indexed,tokenized<name:vfdvdfvdf>>]#{}:{}
2010-12-09 17:57:29,105 TRACE [org.hibernate.search.backend.Workspace] (Hibernate Search: batch coordinator-1) Index changes commited.
2010-12-09 17:57:29,105 TRACE [org.hibernate.search.spi.internals.PolymorphicIndexHierarchy] (Hibernate Search: batch coordinator-1) Targeted indexed classes for [class at.mw.contacts.jpa.Contact]: [class at.mw.contacts.jpa.Contact]
2010-12-09 17:57:29,106 TRACE [org.hibernate.search.backend.impl.lucene.works.OptimizeWorkDelegate] (Hibernate Search: batch coordinator-1) optimize Lucene index: class at.mw.contacts.jpa.Contact
2010-12-09 17:57:29,106 TRACE [org.hibernate.search.backend.Workspace] (Hibernate Search: batch coordinator-1) IndexWriter closed
2010-12-09 17:57:29,106 INFO  [org.hibernate.search.impl.SimpleIndexingProgressMonitor] (Hibernate Search: batch coordinator-1) Reindexed 19 entities