22:21:44,750 ERROR org.apache.hivemind.parse.DescriptorParser - Error at jar:file:/C:/dev/hivemind-dev/hivemind-utilities/dist/lib/hivetranse.core.jar!/META-INF/hivemodule.xml, line 16, column 64: Attribute id (transaction-settings-schema) of element module/schema is improperly formatted. Schema and extension point ids should be simple names with no punctuation.
22:21:45,328 DEBUG hiveutils.PreferencesObjectProvider - Creating SingletonProxy for service hiveutils.PreferencesObjectProvider
22:21:45,343 DEBUG hiveutils.ObjectBuilderObjectProvider - Creating SingletonProxy for service hiveutils.ObjectBuilderObjectProvider
22:21:45,343 DEBUG hiveutils.ContributionObjectProvider - Creating SingletonProxy for service hiveutils.ContributionObjectProvider
22:21:45,359 DEBUG hiveutils.ServicePointObjectProvider - Creating SingletonProxy for service hiveutils.ServicePointObjectProvider
22:21:45,359 DEBUG hiveutils.LiteralObjectProvider - Creating SingletonProxy for service hiveutils.LiteralObjectProvider
22:21:45,390 DEBUG hiveutils.PropertyFileSymbolSource - Constructing core service implementation for service hiveutils.PropertyFileSymbolSource
22:21:45,406 DEBUG test.TestService - Creating ThreadedProxy for service test.TestService
22:21:45,421 DEBUG test.TestService - Applying interceptor factory hivetranse.core.TransactionInterceptor
22:21:45,421 DEBUG hivetranse.core.TransactionInterceptor - Constructing core service implementation for service hivetranse.core.TransactionInterceptor
22:21:45,421 DEBUG hivetranse.core.TransactionService - Creating PooledProxy for service hivetranse.core.TransactionService
22:21:45,453 DEBUG hivetranse.core.TransactionService - Constructing core service implementation for service hivetranse.core.TransactionService
22:21:45,468 DEBUG hivetranse.core.TransactionService - beginTransaction(): starting a new transaction
22:21:45,468 DEBUG test.TestService - Constructing core service implementation for service test.TestService
22:21:45,468 DEBUG test.session - Creating SingletonProxy for service test.session
22:21:45,515 DEBUG test.session - Constructing core service implementation for service test.session
22:21:45,515 DEBUG hivetranse.hibernate3.SessionFactory - Constructing core service implementation for service hivetranse.hibernate3.SessionFactory
22:21:45,515 DEBUG hivetranse.hibernate3.SessionFactoryBuilder - Constructing core service implementation for service hivetranse.hibernate3.SessionFactoryBuilder
22:21:45,531 DEBUG net.sourceforge.hiveutils.service.impl.ShutdownCoordinatorImpl - addRegistryShutdownListener() net.sourceforge.hivetranse.transaction.hibernate3.SessionProxyFactory@ecb281
22:21:45,531 DEBUG net.sourceforge.hiveutils.service.impl.ShutdownCoordinatorImpl - addRegistryShutdownListener() priority=1000
22:21:45,531  INFO hivetranse.hibernate3.SessionFactoryBuilder - initConfiguration
java.lang.ClassNotFoundException: org.hibernate.cfg.AnnotationConfiguration
	at java.net.URLClassLoader$1.run(URLClassLoader.java:200)
	at java.security.AccessController.doPrivileged(Native Method)
	at java.net.URLClassLoader.findClass(URLClassLoader.java:188)
	at java.lang.ClassLoader.loadClass(ClassLoader.java:306)
	at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:268)
	at java.lang.ClassLoader.loadClass(ClassLoader.java:251)
	at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:319)
	at java.lang.Class.forName0(Native Method)
	at java.lang.Class.forName(Class.java:164)
	at net.sourceforge.hivetranse.transaction.hibernate3.SessionFactoryBuilderImpl.initConfiguration(SessionFactoryBuilderImpl.java:119)
	at net.sourceforge.hivetranse.transaction.hibernate3.SessionFactoryBuilderImpl.buildSessionFactory(SessionFactoryBuilderImpl.java:62)
	at net.sourceforge.hivetranse.transaction.hibernate3.SessionProxyFactory.buildSessionFactory(SessionProxyFactory.java:157)
	at net.sourceforge.hivetranse.transaction.hibernate3.SessionProxyFactory.createCoreServiceImplementation(SessionProxyFactory.java:97)
	at org.apache.hivemind.impl.InvokeFactoryServiceConstructor.constructCoreServiceImplementation(InvokeFactoryServiceConstructor.java:62)
	at org.apache.hivemind.impl.servicemodel.AbstractServiceModelImpl.constructCoreServiceImplementation(AbstractServiceModelImpl.java:108)
	at org.apache.hivemind.impl.servicemodel.AbstractServiceModelImpl.constructNewServiceImplementation(AbstractServiceModelImpl.java:158)
	at org.apache.hivemind.impl.servicemodel.AbstractServiceModelImpl.constructServiceImplementation(AbstractServiceModelImpl.java:140)
	at org.apache.hivemind.impl.servicemodel.SingletonServiceModel.getActualServiceImplementation(SingletonServiceModel.java:69)
	at $Session_1180e3cbf1c._service($Session_1180e3cbf1c.java)
	at $Session_1180e3cbf1c.get($Session_1180e3cbf1c.java)
	at $Session_1180e3cbf1b.get($Session_1180e3cbf1b.java)
	at net.sourceforge.hivetranse.transaction.hibernate3.HibernateOpenSessionInViewTest$TestServiceImpl.getParent(HibernateOpenSessionInViewTest.java:65)
	at $HibernateOpenSessionInViewTest$TestService_1180e3cbf17.getParent($HibernateOpenSessionInViewTest$TestService_1180e3cbf17.java)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:585)
	at net.sourceforge.hivetranse.transaction.TransactionInterceptorFactory$Interceptor.invoke(TransactionInterceptorFactory.java:134)
	at $Proxy0.getParent(Unknown Source)
	at $HibernateOpenSessionInViewTest$TestService_1180e3cbf1a.getParent($HibernateOpenSessionInViewTest$TestService_1180e3cbf1a.java)
	at net.sourceforge.hivetranse.transaction.hibernate3.HibernateOpenSessionInViewTest.testOpenSessionInView(HibernateOpenSessionInViewTest.java:45)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:585)
	at junit.framework.TestCase.runTest(TestCase.java:154)
	at org.jmock.core.VerifyingTestCase.runBare(Unknown Source)
	at net.sourceforge.hiveutils.test.HivemindUnitTestCase.runBare(HivemindUnitTestCase.java:42)
	at junit.framework.TestResult$1.protect(TestResult.java:106)
	at junit.framework.TestResult.runProtected(TestResult.java:124)
	at junit.framework.TestResult.run(TestResult.java:109)
	at junit.framework.TestCase.run(TestCase.java:118)
	at junit.framework.TestSuite.runTest(TestSuite.java:208)
	at junit.framework.TestSuite.run(TestSuite.java:203)
	at ch.elca.el4ant.junit.CollectionSuite.runTest(CollectionSuite.java:370)
	at junit.framework.TestSuite.run(TestSuite.java:203)
	at ch.elca.el4ant.junit.CollectionSuite.run(CollectionSuite.java:405)
	at junit.textui.TestRunner.doRun(TestRunner.java:116)
	at junit.textui.TestRunner.start(TestRunner.java:172)
	at junit.textui.TestRunner.main(TestRunner.java:138)
22:21:45,546  INFO org.hibernate.cfg.Environment - Hibernate 3.1.2
22:21:45,546  INFO org.hibernate.cfg.Environment - hibernate.properties not found
22:21:45,546  INFO org.hibernate.cfg.Environment - using CGLIB reflection optimizer
22:21:45,546  INFO org.hibernate.cfg.Environment - using JDK 1.4 java.sql.Timestamp handling
22:21:45,593  INFO org.hibernate.cfg.Configuration - configuring from url: jar:file:/C:/dev/hivemind-dev/hivemind-utilities/dist/lib/hivetranse.hibernate3-itest.jar!/hibernate-opensessioninview.xml
22:21:45,625 DEBUG org.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd in classpath under org/hibernate/
22:21:45,625 DEBUG org.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd in classpath
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - query.substitutions=true 1, false 0, yes 'Y', no 'N'
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - dialect=org.hibernate.dialect.MySQLDialect
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - connection.driver_class=com.mysql.jdbc.Driver
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - connection.provider_class=org.hibernate.connection.C3P0ConnectionProvider
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - connection.url=jdbc:mysql://localhost/hivetransetest
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - connection.username=root
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - connection.password=
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.max_size=10
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.min_size=2
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.timeout=5000
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.max_statements=100
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.idle_test_period=3000
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.acquire_increment=2
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - c3p0.validate=false
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - jdbc.fetch_size=25
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - jdbc.batch_size=0
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - jdbc.use_streams_for_binary=true
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - max_fetch_depth=2
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - cache.region_prefix=hibernate.test
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - cache.use_query_cache=false
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - cache.provider_class=org.hibernate.cache.EhCacheProvider
22:21:45,640 DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@c39a20 [Attribute: name resource value "net/sourceforge/hivetranse/transaction/hibernate3/test/Parent.hbm.xml"]
22:21:45,640  INFO org.hibernate.cfg.Configuration - Reading mappings from resource: net/sourceforge/hivetranse/transaction/hibernate3/test/Parent.hbm.xml
22:21:45,640 DEBUG org.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath under org/hibernate/
22:21:45,640 DEBUG org.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath
22:21:45,765  INFO org.hibernate.cfg.HbmBinder - Mapping class: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent -> PARENT
22:21:45,781 DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> ID
22:21:45,781 DEBUG org.hibernate.cfg.HbmBinder - Mapped property: version -> VERSION
22:21:45,781 DEBUG org.hibernate.cfg.HbmBinder - Mapped property: children
22:21:45,781 DEBUG org.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@633e5e [Attribute: name resource value "net/sourceforge/hivetranse/transaction/hibernate3/test/Child.hbm.xml"]
22:21:45,781  INFO org.hibernate.cfg.Configuration - Reading mappings from resource: net/sourceforge/hivetranse/transaction/hibernate3/test/Child.hbm.xml
22:21:45,796 DEBUG org.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath under org/hibernate/
22:21:45,796 DEBUG org.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath
22:21:45,812  INFO org.hibernate.cfg.HbmBinder - Mapping class: net.sourceforge.hivetranse.transaction.hibernate3.test.Child -> CHILD
22:21:45,812 DEBUG org.hibernate.cfg.HbmBinder - Mapped property: id -> ID
22:21:45,812 DEBUG org.hibernate.cfg.HbmBinder - Mapped property: version -> VERSION
22:21:45,812  INFO org.hibernate.cfg.Configuration - Configured SessionFactory: null
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - properties: {java.vendor=Sun Microsystems Inc., c3p0.min_size=2, hibernate.connection.url=jdbc:mysql://localhost/hivetransetest, sun.management.compiler=HotSpot Client Compiler, os.name=Windows XP, sun.boot.class.path=C:\jdk1.5.0_05\jre\lib\rt.jar;C:\jdk1.5.0_05\jre\lib\i18n.jar;C:\jdk1.5.0_05\jre\lib\sunrsasign.jar;C:\jdk1.5.0_05\jre\lib\jsse.jar;C:\jdk1.5.0_05\jre\lib\jce.jar;C:\jdk1.5.0_05\jre\lib\charsets.jar;C:\jdk1.5.0_05\jre\classes, sun.desktop=windows, hibernate.c3p0.max_size=10, java.vm.specification.vendor=Sun Microsystems Inc., c3p0.max_size=10, java.runtime.version=1.5.0_05-b05, c3p0.acquire_increment=2, hibernate.c3p0.min_size=2, junit.collectionsuite.xmlreport=C:\dev\hivemind-dev\hivemind-utilities/dist/junit/Report_hivetranse.hibernate3-itest_0_{0}_{1}_jfpoilpret.xml, hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider, user.name=jfpoilpret, connection.driver_class=com.mysql.jdbc.Driver, jdbc.fetch_size=25, hibernate.c3p0.timeout=5000, user.language=en, sun.boot.library.path=C:\jdk1.5.0_05\jre\bin, dialect=org.hibernate.dialect.MySQLDialect, max_fetch_depth=2, java.version=1.5.0_05, c3p0.validate=false, user.timezone=Asia/Bangkok, jdbc.batch_size=0, sun.arch.data.model=32, emma.coverage.out.file=C:\dev\hivemind-dev\hivemind-utilities/dist/emma/hivetranse.hibernate3-itest-coverage.ec, java.endorsed.dirs=C:\jdk1.5.0_05\jre\lib\endorsed, junit.collectionsuite.showoutput=false, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86, sun.jnu.encoding=Cp1252, file.encoding.pkg=sun.io, cache.use_query_cache=false, file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=49.0, user.country=US, connection.url=jdbc:mysql://localhost/hivetransetest, java.home=C:\jdk1.5.0_05\jre, java.vm.info=mixed mode, sharing, hibernate.c3p0.validate=false, hibernate.jdbc.fetch_size=25, os.version=5.1, c3p0.idle_test_period=3000, path.separator=;, connection.password=, java.vm.version=1.5.0_05-b05, hibernate.max_fetch_depth=2, hibernate.connection.password=, user.variant=, junit.collectionsuite.source=C:\dev\hivemind-dev\hivemind-utilities/dist\classes\hivetranse.hibernate3-itest, hibernate.jdbc.batch_size=0, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=root, cache.region_prefix=hibernate.test, jdbc.use_streams_for_binary=true, hibernate.cache.region_prefix=hibernate.test, query.substitutions=true 1, false 0, yes 'Y', no 'N', user.home=C:\Documents and Settings\jfpoilpret, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', java.specification.vendor=Sun Microsystems Inc., junit.collectionsuite.date=20080212_222144015, java.library.path=C:\jdk1.5.0_05\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Intel\Wireless\Bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;c:\devtools\svn-win32-1.4.3\bin;c:\jdk1.5.0_05\bin;c:\dev\hivemind-dev\ant\bin;C:\Program Files\PostgreSQL\8.2\bin;C:\Program Files\MySQL\MySQL Server 5.0\bin;c:\devtools\sourceforge\tools\putty;C:\Program Files\IDM Computer Solutions\UltraEdit-32;C:\Program Files\QuickTime\QTSystem\;, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.mysql.jdbc.Driver, connection.username=root, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=org.hibernate.dialect.MySQLDialect, hibernate.jdbc.use_streams_for_binary=true, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.hibernate3-itest.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.hibernate3.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.core.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.exceptions.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hiveutils.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\c3p0-0.9.0.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-collections-3.2.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-dbcp-1.2.2.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-pool-1.3.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\ehcache-1.2.4.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jta.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\odmg-3.0.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\mysql-connector-java-5.0.5-bin.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\postgresql-8.2-504.jdbc3.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\dom4j-1.6.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\hibernate3.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\asm.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\asm-attrs.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\cglib-2.1.2.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-logging-1.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-codec.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\hivemind-1.1.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jakarta-oro-2.0.8.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\javassist.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jmock-1.0.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jmock-cglib-1.0.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\junit-3.8.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\log4j-1.2.15.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\servlet-api.jar;C:\dev\hivemind-dev\el4ant\lib\reports-junit.jar;C:\dev\hivemind-dev\ant\lib\ant-junit.jar;C:\dev\hivemind-dev\ant\lib\ant.jar;C:\dev\hivemind-dev\ant\lib\xml-apis.jar;C:\dev\hivemind-dev\ant\lib\xercesImpl.jar;C:\dev\hivemind-dev\el4ant\lib\emma-2.0.5312.jar, c3p0.timeout=5000, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, hibernate.cache.use_query_cache=false, sun.cpu.endian=little, connection.provider_class=org.hibernate.connection.C3P0ConnectionProvider, sun.os.patch.level=Service Pack 2, hibernate.connection.provider_class=org.hibernate.connection.C3P0ConnectionProvider, java.io.tmpdir=C:\DOCUME~1\JFPOIL~1\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\jdk1.5.0_05\jre\lib\ext, user.dir=C:\dev\hivemind-dev\hivemind-utilities\hivetranse\hibernate3\itest\java, hibernate.c3p0.idle_test_period=3000, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, cache.provider_class=org.hibernate.cache.EhCacheProvider, hibernate.c3p0.acquire_increment=2, file.encoding=Cp1252, java.specification.version=1.5, c3p0.max_statements=100, hibernate.c3p0.max_statements=100}
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - Preparing to build session factory with filters : {}
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - processing extends queue
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - processing collection mappings
22:21:45,812 DEBUG org.hibernate.cfg.CollectionSecondPass - Second pass for collection: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children
22:21:45,812  INFO org.hibernate.cfg.HbmBinder - Mapping collection: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children -> CHILD
22:21:45,812 DEBUG org.hibernate.cfg.CollectionSecondPass - Mapped collection key: PARENT_ID, one-to-many: net.sourceforge.hivetranse.transaction.hibernate3.test.Child
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - processing native query and ResultSetMapping mappings
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - processing association property references
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - processing foreign key constraints
22:21:45,812 DEBUG org.hibernate.cfg.Configuration - resolving reference to class: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent
22:21:45,859  INFO org.hibernate.connection.ConnectionProviderFactory - Initializing connection provider: org.hibernate.connection.C3P0ConnectionProvider
22:21:45,859  INFO org.hibernate.connection.C3P0ConnectionProvider - C3P0 using driver: com.mysql.jdbc.Driver at URL: jdbc:mysql://localhost/hivetransetest
22:21:45,859  INFO org.hibernate.connection.C3P0ConnectionProvider - Connection properties: {user=root, password=****}
22:21:45,859  INFO org.hibernate.connection.C3P0ConnectionProvider - autocommit mode: false
22:21:45,890  INFO com.mchange.v2.log.MLog - MLog clients using log4j logging.
22:21:45,906  INFO com.mchange.v2.c3p0.C3P0Registry - Initializing c3p0-0.9.0 [built 11-July-2005 00:43:29 -0400; debug? true; trace: 10]
22:21:46,046  INFO com.mchange.v2.c3p0.PoolBackedDataSource - Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource@9446e4 [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@f6ac0b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> f6ac0b, idleConnectionTestPeriod -> 3000, initialPoolSize -> 2, maxIdleTime -> 5000, maxPoolSize -> 10, maxStatements -> 100, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@1d2b01b [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 1d2b01b, jdbcUrl -> jdbc:mysql://localhost/hivetransetest, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 9446e4, numHelperThreads -> 3 ]
22:21:46,046 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - awaitAvailable(): [unknown]
22:21:46,062 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 0, unused: 0, excluded: 0]
22:21:46,250 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 1, unused: 1, excluded: 0]
22:21:46,250 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - resource age is okay: com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f ---> age: 0   max: 5000000 [com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b]
22:21:46,250 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 1, unused: 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,265 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,281  INFO org.hibernate.cfg.SettingsFactory - RDBMS: MySQL, version: 5.0.37-community-nt
22:21:46,281  INFO org.hibernate.cfg.SettingsFactory - JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.0.5 ( $Date: 2007-03-01 00:01:06 +0100 (Thu, 01 Mar 2007) $, $Revision: 6329 $ )
22:21:46,281 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
22:21:46,281 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,296 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - ENTER METHOD: closeAll( com.mysql.jdbc.Connection@19eda2c )! -- num_connections: 0
22:21:46,296 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - Set of statements for connection: null
22:21:46,296 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - closeAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
22:21:46,296  INFO org.hibernate.dialect.Dialect - Using dialect: org.hibernate.dialect.MySQLDialect
22:21:46,296  INFO org.hibernate.transaction.TransactionFactoryFactory - Using default transaction strategy (direct JDBC transactions)
22:21:46,296  INFO org.hibernate.transaction.TransactionManagerLookupFactory - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Automatic flush during beforeCompletion(): disabled
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Automatic session close at end of transaction: disabled
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Scrollable result sets: enabled
22:21:46,296 DEBUG org.hibernate.cfg.SettingsFactory - Wrap result sets: disabled
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - JDBC3 getGeneratedKeys(): enabled
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - JDBC result set fetch size: 25
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Connection release mode: auto
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Maximum outer join fetch depth: 2
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Default batch fetch size: 1
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Generate SQL with comments: disabled
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Order SQL updates by primary key: disabled
22:21:46,296  INFO org.hibernate.cfg.SettingsFactory - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
22:21:46,312  INFO org.hibernate.hql.ast.ASTQueryTranslatorFactory - Using ASTQueryTranslatorFactory
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Query language substitutions: {no='N', true=1, yes='Y', false=0}
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Second-level cache: enabled
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Query cache: disabled
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Cache provider: org.hibernate.cache.EhCacheProvider
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: disabled
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Cache region prefix: hibernate.test
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Structured second-level cache entries: disabled
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Statistics: disabled
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Deleted entity synthetic identifier rollback: disabled
22:21:46,312  INFO org.hibernate.cfg.SettingsFactory - Default entity-mode: pojo
22:21:46,343  INFO org.hibernate.impl.SessionFactoryImpl - building session factory
22:21:46,343 DEBUG org.hibernate.impl.SessionFactoryImpl - Session factory constructed with filter configurations : {}
22:21:46,343 DEBUG org.hibernate.impl.SessionFactoryImpl - instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., hibernate.connection.url=jdbc:mysql://localhost/hivetransetest, sun.management.compiler=HotSpot Client Compiler, c3p0.min_size=2, os.name=Windows XP, sun.boot.class.path=C:\jdk1.5.0_05\jre\lib\rt.jar;C:\jdk1.5.0_05\jre\lib\i18n.jar;C:\jdk1.5.0_05\jre\lib\sunrsasign.jar;C:\jdk1.5.0_05\jre\lib\jsse.jar;C:\jdk1.5.0_05\jre\lib\jce.jar;C:\jdk1.5.0_05\jre\lib\charsets.jar;C:\jdk1.5.0_05\jre\classes, sun.desktop=windows, hibernate.c3p0.max_size=10, java.vm.specification.vendor=Sun Microsystems Inc., c3p0.max_size=10, java.runtime.version=1.5.0_05-b05, junit.collectionsuite.xmlreport=C:\dev\hivemind-dev\hivemind-utilities/dist/junit/Report_hivetranse.hibernate3-itest_0_{0}_{1}_jfpoilpret.xml, hibernate.c3p0.min_size=2, c3p0.acquire_increment=2, hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider, user.name=jfpoilpret, connection.driver_class=com.mysql.jdbc.Driver, jdbc.fetch_size=25, hibernate.c3p0.timeout=5000, user.language=en, sun.boot.library.path=C:\jdk1.5.0_05\jre\bin, dialect=org.hibernate.dialect.MySQLDialect, max_fetch_depth=2, java.version=1.5.0_05, c3p0.validate=false, user.timezone=Asia/Bangkok, jdbc.batch_size=0, sun.arch.data.model=32, emma.coverage.out.file=C:\dev\hivemind-dev\hivemind-utilities/dist/emma/hivetranse.hibernate3-itest-coverage.ec, java.endorsed.dirs=C:\jdk1.5.0_05\jre\lib\endorsed, junit.collectionsuite.showoutput=false, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86, sun.jnu.encoding=Cp1252, file.encoding.pkg=sun.io, file.separator=\, cache.use_query_cache=false, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=49.0, user.country=US, connection.url=jdbc:mysql://localhost/hivetransetest, java.home=C:\jdk1.5.0_05\jre, java.vm.info=mixed mode, sharing, hibernate.c3p0.validate=false, os.version=5.1, hibernate.jdbc.fetch_size=25, path.separator=;, c3p0.idle_test_period=3000, connection.password=, java.vm.version=1.5.0_05-b05, hibernate.max_fetch_depth=2, hibernate.connection.password=, user.variant=, junit.collectionsuite.source=C:\dev\hivemind-dev\hivemind-utilities/dist\classes\hivetranse.hibernate3-itest, hibernate.jdbc.batch_size=0, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=root, cache.region_prefix=hibernate.test, jdbc.use_streams_for_binary=true, hibernate.cache.region_prefix=hibernate.test, query.substitutions=true 1, false 0, yes 'Y', no 'N', user.home=C:\Documents and Settings\jfpoilpret, hibernate.query.substitutions=true 1, false 0, yes 'Y', no 'N', java.specification.vendor=Sun Microsystems Inc., junit.collectionsuite.date=20080212_222144015, java.library.path=C:\jdk1.5.0_05\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Intel\Wireless\Bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;c:\devtools\svn-win32-1.4.3\bin;c:\jdk1.5.0_05\bin;c:\dev\hivemind-dev\ant\bin;C:\Program Files\PostgreSQL\8.2\bin;C:\Program Files\MySQL\MySQL Server 5.0\bin;c:\devtools\sourceforge\tools\putty;C:\Program Files\IDM Computer Solutions\UltraEdit-32;C:\Program Files\QuickTime\QTSystem\;, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.mysql.jdbc.Driver, java.vm.vendor=Sun Microsystems Inc., connection.username=root, hibernate.dialect=org.hibernate.dialect.MySQLDialect, hibernate.jdbc.use_streams_for_binary=true, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.hibernate3-itest.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.hibernate3.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.core.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hivetranse.exceptions.jar;C:\dev\hivemind-dev\hivemind-utilities\dist\lib\hiveutils.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\c3p0-0.9.0.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-collections-3.2.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-dbcp-1.2.2.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-pool-1.3.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\ehcache-1.2.4.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jta.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\odmg-3.0.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\mysql-connector-java-5.0.5-bin.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\postgresql-8.2-504.jdbc3.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\dom4j-1.6.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\hibernate3.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\asm.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\asm-attrs.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\cglib-2.1.2.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-logging-1.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\commons-codec.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\hivemind-1.1.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jakarta-oro-2.0.8.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\javassist.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jmock-1.0.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\jmock-cglib-1.0.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\junit-3.8.1.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\log4j-1.2.15.jar;C:\dev\hivemind-dev\hivemind-utilities\lib\servlet-api.jar;C:\dev\hivemind-dev\el4ant\lib\reports-junit.jar;C:\dev\hivemind-dev\ant\lib\ant-junit.jar;C:\dev\hivemind-dev\ant\lib\ant.jar;C:\dev\hivemind-dev\ant\lib\xml-apis.jar;C:\dev\hivemind-dev\ant\lib\xercesImpl.jar;C:\dev\hivemind-dev\el4ant\lib\emma-2.0.5312.jar, c3p0.timeout=5000, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, sun.os.patch.level=Service Pack 2, connection.provider_class=org.hibernate.connection.C3P0ConnectionProvider, sun.cpu.endian=little, hibernate.cache.use_query_cache=false, hibernate.connection.provider_class=org.hibernate.connection.C3P0ConnectionProvider, java.io.tmpdir=C:\DOCUME~1\JFPOIL~1\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, os.arch=x86, java.ext.dirs=C:\jdk1.5.0_05\jre\lib\ext, user.dir=C:\dev\hivemind-dev\hivemind-utilities\hivetranse\hibernate3\itest\java, hibernate.c3p0.idle_test_period=3000, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, cache.provider_class=org.hibernate.cache.EhCacheProvider, hibernate.c3p0.acquire_increment=2, file.encoding=Cp1252, java.specification.version=1.5, c3p0.max_statements=100, hibernate.c3p0.max_statements=100}
22:21:46,343 DEBUG net.sf.ehcache.CacheManager - Creating new CacheManager with default config
22:21:46,343 DEBUG net.sf.ehcache.CacheManager - Configuring ehcache from classpath.
22:21:46,343  WARN net.sf.ehcache.config.ConfigurationFactory - No configuration found. Configuring ehcache from ehcache-failsafe.xml  found in the classpath: jar:file:/C:/dev/hivemind-dev/hivemind-utilities/lib/ehcache-1.2.4.jar!/ehcache-failsafe.xml
22:21:46,343 DEBUG net.sf.ehcache.config.ConfigurationFactory - Configuring ehcache from URL: jar:file:/C:/dev/hivemind-dev/hivemind-utilities/lib/ehcache-1.2.4.jar!/ehcache-failsafe.xml
22:21:46,343 DEBUG net.sf.ehcache.config.ConfigurationFactory - Configuring ehcache from InputStream
22:21:46,359 DEBUG net.sf.ehcache.config.DiskStoreConfiguration - Disk Store Path: C:\DOCUME~1\JFPOIL~1\LOCALS~1\Temp\
22:21:46,359 DEBUG net.sf.ehcache.config.ConfigurationHelper - No CacheManagerEventListenerFactory class specified. Skipping...
22:21:46,359 DEBUG net.sf.ehcache.config.ConfigurationHelper - No CachePeerListenerFactoryConfiguration specified. Not configuring a CacheManagerPeerListener.
22:21:46,359 DEBUG net.sf.ehcache.config.ConfigurationHelper - No CachePeerProviderFactoryConfiguration specified. Not configuring a CacheManagerPeerProvider.
22:21:46,421 DEBUG net.sf.ehcache.config.ConfigurationHelper - No BootstrapCacheLoaderFactory class specified. Skipping...
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Version select: select VERSION from PARENT where ID =?
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Snapshot select: select parent_.ID, parent_.VERSION as VERSION0_ from PARENT parent_ where parent_.ID=?
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Insert 0: insert into PARENT (VERSION, ID) values (?, ?)
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Update 0: update PARENT set VERSION=? where ID=? and VERSION=?
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Delete 0: delete from PARENT where ID=? and VERSION=?
22:21:46,703 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Identity insert: insert into PARENT (VERSION) values (?)
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: net.sourceforge.hivetranse.transaction.hibernate3.test.Child
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Version select: select VERSION from CHILD where ID =?
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Snapshot select: select child_.ID, child_.VERSION as VERSION1_ from CHILD child_ where child_.ID=?
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Insert 0: insert into CHILD (VERSION, ID) values (?, ?)
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Update 0: update CHILD set VERSION=? where ID=? and VERSION=?
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Delete 0: delete from CHILD where ID=? and VERSION=?
22:21:46,718 DEBUG org.hibernate.persister.entity.AbstractEntityPersister -  Identity insert: insert into CHILD (VERSION) values (?)
22:21:46,718 DEBUG org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children
22:21:46,718 DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row insert: update CHILD set PARENT_ID=? where ID=?
22:21:46,718 DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  Row delete: update CHILD set PARENT_ID=null where PARENT_ID=? and ID=?
22:21:46,718 DEBUG org.hibernate.persister.collection.AbstractCollectionPersister -  One-shot delete: update CHILD set PARENT_ID=null where PARENT_ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Parent: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Parent: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Parent: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=? for update
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Parent: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=? for update
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity net.sourceforge.hivetranse.transaction.hibernate3.test.Parent: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity net.sourceforge.hivetranse.transaction.hibernate3.test.Parent: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Child: select child0_.ID as ID1_0_, child0_.VERSION as VERSION1_0_ from CHILD child0_ where child0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Child: select child0_.ID as ID1_0_, child0_.VERSION as VERSION1_0_ from CHILD child0_ where child0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Child: select child0_.ID as ID1_0_, child0_.VERSION as VERSION1_0_ from CHILD child0_ where child0_.ID=? for update
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for entity net.sourceforge.hivetranse.transaction.hibernate3.test.Child: select child0_.ID as ID1_0_, child0_.VERSION as VERSION1_0_ from CHILD child0_ where child0_.ID=? for update
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity net.sourceforge.hivetranse.transaction.hibernate3.test.Child: select child0_.ID as ID1_0_, child0_.VERSION as VERSION1_0_ from CHILD child0_ where child0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity net.sourceforge.hivetranse.transaction.hibernate3.test.Child: select child0_.ID as ID1_0_, child0_.VERSION as VERSION1_0_ from CHILD child0_ where child0_.ID=?
22:21:46,750 DEBUG org.hibernate.loader.collection.OneToManyLoader - Static select for one-to-many net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children: select children0_.PARENT_ID as PARENT3_1_, children0_.ID as ID1_, children0_.ID as ID1_0_, children0_.VERSION as VERSION1_0_ from CHILD children0_ where children0_.PARENT_ID=?
22:21:46,750 DEBUG org.hibernate.impl.SessionFactoryObjectFactory - initializing class SessionFactoryObjectFactory
22:21:46,765 DEBUG org.hibernate.impl.SessionFactoryObjectFactory - registered: 40288183180e3cc401180e3cc5fe0000 (unnamed)
22:21:46,765  INFO org.hibernate.impl.SessionFactoryObjectFactory - Not binding factory to JNDI, no JNDI name configured
22:21:46,765 DEBUG org.hibernate.impl.SessionFactoryImpl - instantiated session factory
22:21:46,765 DEBUG org.hibernate.impl.SessionFactoryImpl - Checking 0 named HQL queries
22:21:46,765 DEBUG org.hibernate.impl.SessionFactoryImpl - Checking 0 named SQL queries
22:21:46,812 DEBUG org.hibernate.impl.SessionImpl - opened session at timestamp: 4926790478974976
22:21:46,812 DEBUG org.hibernate.transaction.JDBCTransaction - begin
22:21:46,812 DEBUG org.hibernate.jdbc.ConnectionManager - opening JDBC connection
22:21:46,812 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - resource age is okay: com.mchange.v2.c3p0.impl.NewPooledConnection@401369 ---> age: 547   max: 5000000 [com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b]
22:21:46,812 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,812 DEBUG org.hibernate.transaction.JDBCTransaction - current autocommit status: false
22:21:46,812 DEBUG org.hibernate.loader.Loader - loading entity: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent#1]
22:21:46,812 DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
22:21:46,812 DEBUG org.hibernate.SQL - select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=?
22:21:46,828 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - cxnStmtMgr.statementSet( com.mysql.jdbc.Connection@1922f46 ).size(): 1
22:21:46,828 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 1; checked out: 1; num connections: 1; num keys: 1
22:21:46,843 DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
22:21:46,843 DEBUG org.hibernate.loader.Loader - result row: EntityKey[net.sourceforge.hivetranse.transaction.hibernate3.test.Parent#1]
22:21:46,843 DEBUG org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
22:21:46,843 DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
22:21:46,843 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 1; checked out: 0; num connections: 1; num keys: 1
22:21:46,843 DEBUG org.hibernate.engine.TwoPhaseLoad - resolving associations for [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent#1]
22:21:46,859 DEBUG org.hibernate.engine.TwoPhaseLoad - done materializing entity [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent#1]
22:21:46,859 DEBUG org.hibernate.engine.StatefulPersistenceContext - initializing non-lazy collections
22:21:46,859 DEBUG org.hibernate.loader.Loader - done entity load
22:21:46,859 DEBUG hivetranse.core.TransactionService - cleanUp(): ending transaction, commit=true
22:21:46,859 DEBUG org.hibernate.transaction.JDBCTransaction - commit
22:21:46,859 DEBUG org.hibernate.event.def.AbstractFlushingEventListener - processing flush-time cascades
22:21:46,859 DEBUG org.hibernate.event.def.AbstractFlushingEventListener - dirty checking collections
22:21:46,875 DEBUG org.hibernate.engine.Collections - Collection found: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children#1], was: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children#1] (uninitialized)
22:21:46,875 DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
22:21:46,875 DEBUG org.hibernate.event.def.AbstractFlushingEventListener - Flushed: 0 (re)creations, 0 updates, 0 removals to 1 collections
22:21:46,875 DEBUG org.hibernate.pretty.Printer - listing entities:
22:21:46,875 DEBUG org.hibernate.pretty.Printer - net.sourceforge.hivetranse.transaction.hibernate3.test.Parent{children=<uninitialized>, id=1, version=1}
22:21:46,875 DEBUG org.hibernate.transaction.JDBCTransaction - committed JDBC Connection
22:21:46,875 DEBUG org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
22:21:46,875 DEBUG org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 1; checked out: 0; num connections: 1; num keys: 1
22:21:46,875 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,875 DEBUG org.hibernate.transaction.JDBCTransaction - begin
22:21:46,875 DEBUG org.hibernate.jdbc.ConnectionManager - opening JDBC connection
22:21:46,875 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - resource age is okay: com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f ---> age: 594   max: 5000000 [com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b]
22:21:46,875 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,875 DEBUG org.hibernate.transaction.JDBCTransaction - current autocommit status: false
22:21:46,875 DEBUG org.hibernate.loader.Loader - loading collection: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children#1]
22:21:46,875 DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
22:21:46,875 DEBUG org.hibernate.SQL - select children0_.PARENT_ID as PARENT3_1_, children0_.ID as ID1_, children0_.ID as ID1_0_, children0_.VERSION as VERSION1_0_ from CHILD children0_ where children0_.PARENT_ID=?
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - cxnStmtMgr.statementSet( com.mysql.jdbc.Connection@16614e7 ).size(): 1
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkoutStatement: com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 2; checked out: 1; num connections: 2; num keys: 2
22:21:46,875 DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
22:21:46,875 DEBUG org.hibernate.loader.Loader - result set contains (possibly empty) collection: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children#1]
22:21:46,875 DEBUG org.hibernate.loader.Loader - result row: EntityKey[net.sourceforge.hivetranse.transaction.hibernate3.test.Child#1]
22:21:46,875 DEBUG org.hibernate.loader.Loader - found row of collection: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children#1]
22:21:46,875 DEBUG org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
22:21:46,875 DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkinStatement(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 2; checked out: 0; num connections: 2; num keys: 2
22:21:46,875 DEBUG org.hibernate.engine.TwoPhaseLoad - resolving associations for [net.sourceforge.hivetranse.transaction.hibernate3.test.Child#1]
22:21:46,875 DEBUG org.hibernate.engine.TwoPhaseLoad - done materializing entity [net.sourceforge.hivetranse.transaction.hibernate3.test.Child#1]
22:21:46,875 DEBUG org.hibernate.engine.CollectionLoadContext - 1 collections were found in result set for role: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children
22:21:46,875 DEBUG org.hibernate.engine.CollectionLoadContext - collection fully initialized: [net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children#1]
22:21:46,875 DEBUG org.hibernate.engine.CollectionLoadContext - 1 collections initialized for role: net.sourceforge.hivetranse.transaction.hibernate3.test.Parent.children
22:21:46,875 DEBUG org.hibernate.engine.StatefulPersistenceContext - initializing non-lazy collections
22:21:46,875 DEBUG org.hibernate.loader.Loader - done loading collection
22:21:46,875 DEBUG org.hibernate.transaction.JDBCTransaction - rollback
22:21:46,875 DEBUG org.hibernate.transaction.JDBCTransaction - rolled back JDBC Connection
22:21:46,875 DEBUG org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
22:21:46,875 DEBUG org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 2; checked out: 0; num connections: 2; num keys: 2
22:21:46,875 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,875 DEBUG net.sourceforge.hiveutils.service.impl.ShutdownCoordinatorImpl - shutdown()
22:21:46,875 DEBUG hivetranse.hibernate3.SessionFactory - registryDidShutdown()
22:21:46,875 DEBUG hivetranse.hibernate3.SessionFactory - closeSessionFactory(test.session)
22:21:46,875  INFO org.hibernate.impl.SessionFactoryImpl - closing
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - ENTER METHOD: closeAll( com.mysql.jdbc.Connection@16614e7 )! -- num_connections: 2
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - Set of statements for connection: [com.mysql.jdbc.PreparedStatement@430b6: select children0_.PARENT_ID as PARENT3_1_, children0_.ID as ID1_, children0_.ID as ID1_0_, children0_.VERSION as VERSION1_0_ from CHILD children0_ where children0_.PARENT_ID=** NOT SPECIFIED **]; size: 1
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - closeAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 1; checked out: 0; num connections: 1; num keys: 1
22:21:46,875 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 1, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - ENTER METHOD: closeAll( com.mysql.jdbc.Connection@1922f46 )! -- num_connections: 1
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - Set of statements for connection: [com.mysql.jdbc.PreparedStatement@114b82b: select parent0_.ID as ID0_0_, parent0_.VERSION as VERSION0_0_ from PARENT parent0_ where parent0_.ID=** NOT SPECIFIED **]; size: 1
22:21:46,875 DEBUG com.mchange.v2.c3p0.stmt.GooGooStatementCache - closeAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
22:21:46,875 DEBUG com.mchange.v2.resourcepool.BasicResourcePool - trace com.mchange.v2.resourcepool.BasicResourcePool@1d53f5b [managed: 0, unused: 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1dc423f)
22:21:46,890 DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner - Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main] interrupted. Shutting down.
22:21:46,890 DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner - Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0,5,main] interrupted. Shutting down.
22:21:46,890 DEBUG com.mchange.v2.async.ThreadPoolAsynchronousRunner - Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2,5,main] interrupted. Shutting down.
22:21:46,890 DEBUG com.mchange.v2.c3p0.PoolBackedDataSource - com.mchange.v2.c3p0.PoolBackedDataSource@9446e4 has been closed. force_destroy == false
java.lang.Exception: Debug -- PoolBackedDataSource.close() stack trace.
	at com.mchange.v2.c3p0.PoolBackedDataSource.close(PoolBackedDataSource.java:214)
	at com.mchange.v2.c3p0.DataSources.destroy(DataSources.java:259)
	at com.mchange.v2.c3p0.DataSources.destroy(DataSources.java:226)
	at org.hibernate.connection.C3P0ConnectionProvider.close(C3P0ConnectionProvider.java:118)
	at org.hibernate.impl.SessionFactoryImpl.close(SessionFactoryImpl.java:758)
	at net.sourceforge.hivetranse.transaction.hibernate3.SessionProxyFactory.closeSessionFactory(SessionProxyFactory.java:174)
	at net.sourceforge.hivetranse.transaction.hibernate3.SessionProxyFactory.registryDidShutdown(SessionProxyFactory.java:73)
	at net.sourceforge.hiveutils.service.impl.ShutdownCoordinatorImpl.shutdown(ShutdownCoordinatorImpl.java:96)
	at org.apache.hivemind.impl.RegistryInfrastructureImpl.shutdown(RegistryInfrastructureImpl.java:371)
	at org.apache.hivemind.impl.RegistryImpl.shutdown(RegistryImpl.java:94)
	at net.sourceforge.hivetranse.transaction.hibernate3.HibernateOpenSessionInViewTest.tearDown(HibernateOpenSessionInViewTest.java:39)
	at org.jmock.core.VerifyingTestCase.runBare(Unknown Source)
	at net.sourceforge.hiveutils.test.HivemindUnitTestCase.runBare(HivemindUnitTestCase.java:42)
	at junit.framework.TestResult$1.protect(TestResult.java:106)
	at junit.framework.TestResult.runProtected(TestResult.java:124)
	at junit.framework.TestResult.run(TestResult.java:109)
	at junit.framework.TestCase.run(TestCase.java:118)
	at junit.framework.TestSuite.runTest(TestSuite.java:208)
	at junit.framework.TestSuite.run(TestSuite.java:203)
	at ch.elca.el4ant.junit.CollectionSuite.runTest(CollectionSuite.java:370)
	at junit.framework.TestSuite.run(TestSuite.java:203)
	at ch.elca.el4ant.junit.CollectionSuite.run(CollectionSuite.java:405)
	at junit.textui.TestRunner.doRun(TestRunner.java:116)
	at junit.textui.TestRunner.start(TestRunner.java:172)
	at junit.textui.TestRunner.main(TestRunner.java:138)