I have 3 persistent classes: Cycle, Task and Subtask.
Cycle has a collection of tasks and Task has a collection of subtasks.
When I try to save the Cycle, I get an error informing me that a task backreference property(see below) is null. The problem is that I don't have this property defined anywhere, so I guess it is some Hibernate internal thing that I'm not getting. Any help appreciated.

Hibernate version:

3.2.1

Mapping documents:

//DTD declaration omitted

<hibernate-mapping>
<class name="com.gm.hibernate.impl.Cycle" table="CYCLE">
<id name="number">
<generator class="native" />
</id>
<property name="start" column="start_time" type="timestamp" />
<property name="end" column="end_time" type="timestamp"></property>
<set name="tasks" cascade="all">
<key column="number" not-null="true" />
<one-to-many class="com.gm.hibernate.impl.Task" />
</set>
</class>
</hibernate-mapping>

<hibernate-mapping>
<class name="com.gm.hibernate.impl.Subtask" table="SUBTASK">
<id name="uuid" column="UUID">
<generator class="native" />
</id>
<property name="name" />
<property name="description" />
<property name="estimatedTime" column="estimated_time" type="time" />
<property name="start" column="start_time" type="timestamp" />
<property name="end" column="end_time" type="timestamp" />
</class>
</hibernate-mapping>

<hibernate-mapping>
<class name="com.gm.hibernate.impl.Task" table="TASK">
<id name="uuid" >
<generator class="native" />
</id>
<property name="name" />
<property name="description" />
<property name="estimatedTime" column="estimated_time" type="time" />
<property name="start" column="start_time" type="timestamp" />
<property name="end" column="end_time" type="timestamp" />
<set name="subtasks" table="SUBTASK" cascade="all">
<key column="parent_uuid" not-null="true"/>
<one-to-many class="com.gm.hibernate.impl.Subtask" />
</set>
</class>
</hibernate-mapping>

Code between sessionFactory.openSession() and session.close():


Full stack trace of any exception that occurs:

org.hibernate.PropertyValueException: not-null property references a null or transient value: com.gm.hibernate.impl.Task._tasksBackref
at org.hibernate.engine.Nullability.checkNullability(Nullability.java:72)
at org.hibernate.event.def.AbstractSaveEventListener.performSaveOrReplicate(AbstractSaveEventListener.java:284)
at org.hibernate.event.def.AbstractSaveEventListener.performSave(AbstractSaveEventListener.java:180)
at org.hibernate.event.def.AbstractSaveEventListener.saveWithGeneratedId(AbstractSaveEventListener.java:121)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.saveWithGeneratedOrRequestedId(DefaultSaveOrUpdateEventListener.java:186)
at org.hibernate.event.def.DefaultSaveEventListener.saveWithGeneratedOrRequestedId(DefaultSaveEventListener.java:33)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.entityIsTransient(DefaultSaveOrUpdateEventListener.java:175)
at org.hibernate.event.def.DefaultSaveEventListener.performSaveOrUpdate(DefaultSaveEventListener.java:27)
at org.hibernate.event.def.DefaultSaveOrUpdateEventListener.onSaveOrUpdate(DefaultSaveOrUpdateEventListener.java:70)
at org.hibernate.impl.SessionImpl.fireSave(SessionImpl.java:535)
at org.hibernate.impl.SessionImpl.save(SessionImpl.java:523)
at org.hibernate.impl.SessionImpl.save(SessionImpl.java:519)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.hibernate.context.ThreadLocalSessionContext$TransactionProtectionWrapper.invoke(ThreadLocalSessionContext.java:301)
at $Proxy5.save(Unknown Source)
at com.gm.hibernate.tests.HibernateTest.testHB3(HibernateTest.java:36)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.junit.internal.runners.TestMethodRunner.executeMethodBody(TestMethodRunner.java:99)
at org.junit.internal.runners.TestMethodRunner.runUnprotected(TestMethodRunner.java:81)
at org.junit.internal.runners.BeforeAndAfterRunner.runProtected(BeforeAndAfterRunner.java:34)
at org.junit.internal.runners.TestMethodRunner.runMethod(TestMethodRunner.java:75)
at org.junit.internal.runners.TestMethodRunner.run(TestMethodRunner.java:45)
at org.junit.internal.runners.TestClassMethodsRunner.invokeTestMethod(TestClassMethodsRunner.java:71)
at org.junit.internal.runners.TestClassMethodsRunner.run(TestClassMethodsRunner.java:35)
at org.junit.internal.runners.TestClassRunner$1.runUnprotected(TestClassRunner.java:42)
at org.junit.internal.runners.BeforeAndAfterRunner.runProtected(BeforeAndAfterRunner.java:34)
at org.junit.internal.runners.TestClassRunner.run(TestClassRunner.java:52)
at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:38)
at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:38)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:460)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:673)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:386)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:196)


Name and version of the database you are using:

Apache Derby 10.3.1.4

The generated SQL (show_sql=true):



Debug level Hibernate log excerpt:

12:14:52,750 INFO Environment:500 - Hibernate 3.2.1
12:14:52,766 INFO Environment:533 - hibernate.properties not found
12:14:52,766 INFO Environment:667 - Bytecode provider name : cglib
12:14:52,781 INFO Environment:584 - using JDK 1.4 java.sql.Timestamp handling
12:14:52,859 INFO Configuration:1423 - configuring from resource: /hibernate.cfg.xml
12:14:52,859 INFO Configuration:1400 - Configuration resource: /hibernate.cfg.xml
12:14:53,156 DEBUG DTDEntityResolver:38 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]
12:14:53,156 DEBUG DTDEntityResolver:40 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
12:14:53,156 DEBUG DTDEntityResolver:50 - located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath
12:14:53,203 DEBUG Configuration:1384 - connection.driver_class=org.apache.derby.jdbc.EmbeddedDriver
12:14:53,203 DEBUG Configuration:1384 - connection.url=jdbc:derby:JAXB_HIBERNATE
12:14:53,203 DEBUG Configuration:1384 - connection.username=
12:14:53,219 DEBUG Configuration:1384 - connection.password=
12:14:53,219 DEBUG Configuration:1384 - connection.pool_size=1
12:14:53,219 DEBUG Configuration:1384 - dialect=org.hibernate.dialect.DerbyDialect
12:14:53,219 DEBUG Configuration:1384 - current_session_context_class=thread
12:14:53,219 DEBUG Configuration:1384 - cache.provider_class=org.hibernate.cache.NoCacheProvider
12:14:53,219 DEBUG Configuration:1384 - show_sql=true
12:14:53,219 DEBUG Configuration:1583 - null<-org.dom4j.tree.DefaultAttribute@17fa65e [Attribute: name resource value "com/gm/hibernate/impl/Task.hbm.xml"]
12:14:53,219 INFO Configuration:553 - Reading mappings from resource : com/gm/hibernate/impl/Task.hbm.xml
12:14:53,234 DEBUG DTDEntityResolver:38 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
12:14:53,234 DEBUG DTDEntityResolver:40 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
12:14:53,234 DEBUG DTDEntityResolver:50 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
12:14:53,406 INFO HbmBinder:300 - Mapping class: com.gm.hibernate.impl.Task -> TASK
12:14:53,422 DEBUG HbmBinder:1270 - Mapped property: uuid -> uuid
12:14:53,437 DEBUG HbmBinder:1270 - Mapped property: name -> name
12:14:53,437 DEBUG HbmBinder:1270 - Mapped property: description -> description
12:14:53,437 DEBUG HbmBinder:1270 - Mapped property: estimatedTime -> estimated_time
12:14:53,437 DEBUG HbmBinder:1270 - Mapped property: start -> start_time
12:14:53,437 DEBUG HbmBinder:1270 - Mapped property: end -> end_time
12:14:53,453 DEBUG HbmBinder:1270 - Mapped property: subtasks
12:14:53,453 DEBUG Configuration:1583 - null<-org.dom4j.tree.DefaultAttribute@b8f82d [Attribute: name resource value "com/gm/hibernate/impl/Cycle.hbm.xml"]
12:14:53,453 INFO Configuration:553 - Reading mappings from resource : com/gm/hibernate/impl/Cycle.hbm.xml
12:14:53,453 DEBUG DTDEntityResolver:38 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
12:14:53,453 DEBUG DTDEntityResolver:40 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
12:14:53,453 DEBUG DTDEntityResolver:50 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
12:14:53,516 INFO HbmBinder:300 - Mapping class: com.gm.hibernate.impl.Cycle -> CYCLE
12:14:53,516 DEBUG HbmBinder:1270 - Mapped property: number -> number
12:14:53,516 DEBUG HbmBinder:1270 - Mapped property: start -> start_time
12:14:53,516 DEBUG HbmBinder:1270 - Mapped property: end -> end_time
12:14:53,531 DEBUG HbmBinder:1270 - Mapped property: tasks
12:14:53,531 DEBUG Configuration:1583 - null<-org.dom4j.tree.DefaultAttribute@d2906a [Attribute: name resource value "com/gm/hibernate/impl/Subtask.hbm.xml"]
12:14:53,531 INFO Configuration:553 - Reading mappings from resource : com/gm/hibernate/impl/Subtask.hbm.xml
12:14:53,531 DEBUG DTDEntityResolver:38 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
12:14:53,531 DEBUG DTDEntityResolver:40 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
12:14:53,531 DEBUG DTDEntityResolver:50 - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
12:14:53,578 INFO HbmBinder:300 - Mapping class: com.gm.hibernate.impl.Subtask -> SUBTASK
12:14:53,578 DEBUG HbmBinder:1270 - Mapped property: uuid -> UUID
12:14:53,578 DEBUG HbmBinder:1270 - Mapped property: name -> name
12:14:53,578 DEBUG HbmBinder:1270 - Mapped property: description -> description
12:14:53,578 DEBUG HbmBinder:1270 - Mapped property: estimatedTime -> estimated_time
12:14:53,578 DEBUG HbmBinder:1270 - Mapped property: start -> start_time
12:14:53,578 DEBUG HbmBinder:1270 - Mapped property: end -> end_time
12:14:53,578 INFO Configuration:1538 - Configured SessionFactory: null
12:14:53,578 DEBUG Configuration:1539 - properties: {hibernate.connection.password=, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.cache.provider_class=org.hibernate.cache.NoCacheProvider, sun.boot.library.path=C:\Program Files\Java\jre1.5.0\bin, java.vm.version=1.5.0-b64, hibernate.connection.username=, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=BR, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\dev\eclipse\workspace\JAXBHibernateExample, java.runtime.version=1.5.0-b64, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, hibernate.current_session_context_class=thread, java.endorsed.dirs=C:\Program Files\Java\jre1.5.0\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\mirog\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., cache.provider_class=org.hibernate.cache.NoCacheProvider, user.variant=, os.name=Windows XP, sun.jnu.encoding=Cp1252, java.library.path=C:\Program Files\Java\jre1.5.0\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files\HP\Systems Insight Manager\bin;C:\Program Files\HP\Systems Insight Manager\lbin;C:\Program Files\HP\Systems Insight Manager\lib;C:\Program Files\HP\Systems Insight Manager\j2re\bin\server;C:\Program Files\HP\Systems Insight Manager\j2re\bin;C:\Program Files\OpenSSH\bin;c:\ruby\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Subversion\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\Program Files\The Open Group\WMI Mapper\bin;C:\Program Files\Inno Setup 5;D:\Programming\Java\tools\apache-ant-1.7.0-bin\bin;C:\Program Files\Java\jdk1.6.0\bin;rubygems;C:\Program Files\Common Files\GTK\2.0\bin;D:\dev\tomcat\apache-tomcat-5.5.23;C:\Program Files\QuickTime Alternative\QTSystem\;D:\utils\JAD, java.specification.name=Java Platform API Specification, java.class.version=49.0, hibernate.connection.pool_size=1, sun.management.compiler=HotSpot Client Compiler, os.version=5.1, connection.password=, user.home=C:\Documents and Settings\mirog, user.timezone=America/Sao_Paulo, connection.username=, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.5, hibernate.connection.driver_class=org.apache.derby.jdbc.EmbeddedDriver, show_sql=true, user.name=mirog, java.class.path=D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derby.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbyclient.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbynet.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbyrun.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbytools.jar;D:\pluginsRepo\eclipse\plugins\org.hibernate.eclipse_3.2.0.200708310102-nightly\lib\tools\hibernate-tools.jar;D:\pluginsRepo\eclipse\plugins\org.hibernate.eclipse_3.2.0.200708310102-nightly\lib\tools\jtidy-r8-20060801.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-antlr-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-junit-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-launcher-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\antlr-2.7.6.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-swing-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\asm.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\asm-attrs.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\c3p0-0.9.0.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\cglib-2.1.3.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\checkstyle-all.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\cleanimports.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\commons-collections-2.1.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\commons-logging-1.0.4.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\concurrent-1.3.2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\connector.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\dom4j-1.6.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ehcache-1.2.3.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jaas.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jacc-1_0-fr.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\javassist.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jaxen-1.1-beta-7.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-cache.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-common.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-jmx.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-system.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jdbc2_0-stdext.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jgroups-2.2.8.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jta.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\junit-3.8.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\log4j-1.2.11.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\oscache-2.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\proxool-0.8.3.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\swarmcache-1.0rc2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\syndiag2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\versioncheck.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\xerces-2.6.2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\xml-apis.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\build\hibernate3-client.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\build\hibernate3.jar;C:\dev\eclipse\workspace\JAXBHibernateExample\build\classes;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\commons-el.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jasper-compiler-jdt.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jasper-compiler.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jasper-runtime.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jsp-api.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\naming-factory-dbcp.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\naming-factory.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\naming-resources.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\servlet-api.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\activation.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb1-impl.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb-api.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb-impl.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb-xjc.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jsr173_1.0_api.jar;C:\dev\eclipse\plugins\org.junit4_4.1.0.1\junit-4.1.jar;/C:/dev/eclipse/configuration/org.eclipse.osgi/bundles/47/1/.cp/;/C:/dev/eclipse/plugins/org.eclipse.jdt.junit_3.2.1.r321_v20060810/junitsupport.jar;/C:/dev/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.2.1.r321_v20060721/junitruntime.jar, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, current_session_context_class=thread, java.vm.specification.version=1.0, java.home=C:\Program Files\Java\jre1.5.0, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.DerbyDialect, hibernate.connection.url=jdbc:derby:JAXB_HIBERNATE, connection.pool_size=1, user.language=pt, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, sharing, java.version=1.5.0, java.ext.dirs=C:\Program Files\Java\jre1.5.0\lib\ext, sun.boot.class.path=C:\Program Files\Java\jre1.5.0\lib\rt.jar;C:\Program Files\Java\jre1.5.0\lib\i18n.jar;C:\Program Files\Java\jre1.5.0\lib\sunrsasign.jar;C:\Program Files\Java\jre1.5.0\lib\jsse.jar;C:\Program Files\Java\jre1.5.0\lib\jce.jar;C:\Program Files\Java\jre1.5.0\lib\charsets.jar;C:\Program Files\Java\jre1.5.0\classes, java.vendor=Sun Microsystems Inc., connection.driver_class=org.apache.derby.jdbc.EmbeddedDriver, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, sun.desktop=windows, connection.url=jdbc:derby:JAXB_HIBERNATE, dialect=org.hibernate.dialect.DerbyDialect, sun.cpu.isalist=}
12:14:53,578 DEBUG Configuration:1282 - Preparing to build session factory with filters : {}
12:14:53,594 DEBUG Configuration:1117 - processing extends queue
12:14:53,594 DEBUG Configuration:1121 - processing collection mappings
12:14:53,594 DEBUG CollectionSecondPass:41 - Second pass for collection: com.gm.hibernate.impl.Task.subtasks
12:14:53,594 INFO HbmBinder:2375 - Mapping collection: com.gm.hibernate.impl.Task.subtasks -> SUBTASK
12:14:53,594 DEBUG CollectionSecondPass:57 - Mapped collection key: parent_uuid, one-to-many: com.gm.hibernate.impl.Subtask
12:14:53,594 DEBUG CollectionSecondPass:41 - Second pass for collection: com.gm.hibernate.impl.Cycle.tasks
12:14:53,594 INFO HbmBinder:2375 - Mapping collection: com.gm.hibernate.impl.Cycle.tasks -> TASK
12:14:53,594 DEBUG CollectionSecondPass:57 - Mapped collection key: number, one-to-many: com.gm.hibernate.impl.Task
12:14:53,594 DEBUG Configuration:1132 - processing native query and ResultSetMapping mappings
12:14:53,594 DEBUG Configuration:1140 - processing association property references
12:14:53,594 DEBUG Configuration:1162 - processing foreign key constraints
12:14:53,594 DEBUG Configuration:1245 - resolving reference to class: com.gm.hibernate.impl.Task
12:14:53,594 DEBUG Configuration:1245 - resolving reference to class: com.gm.hibernate.impl.Cycle
12:14:53,687 INFO DriverManagerConnectionProvider:41 - Using Hibernate built-in connection pool (not for production use!)
12:14:53,687 INFO DriverManagerConnectionProvider:42 - Hibernate connection pool size: 1
12:14:53,703 INFO DriverManagerConnectionProvider:45 - autocommit mode: false
12:14:54,016 INFO DriverManagerConnectionProvider:80 - using driver: org.apache.derby.jdbc.EmbeddedDriver at URL: jdbc:derby:JAXB_HIBERNATE
12:14:54,016 INFO DriverManagerConnectionProvider:83 - connection properties: {user=, password=}
12:14:54,016 DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
12:14:54,016 DEBUG DriverManagerConnectionProvider:109 - opening new JDBC connection
12:14:55,687 DEBUG DriverManagerConnectionProvider:115 - created connection to: jdbc:derby:JAXB_HIBERNATE, Isolation Level: 2
12:14:55,687 INFO SettingsFactory:81 - RDBMS: Apache Derby, version: 10.3.1.4 - (561794)
12:14:55,687 INFO SettingsFactory:82 - JDBC driver: Apache Derby Embedded JDBC Driver, version: 10.3.1.4 - (561794)
12:14:55,687 DEBUG DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
12:14:55,719 INFO Dialect:151 - Using dialect: org.hibernate.dialect.DerbyDialect
12:14:55,719 INFO TransactionFactoryFactory:31 - Using default transaction strategy (direct JDBC transactions)
12:14:55,734 INFO TransactionManagerLookupFactory:33 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
12:14:55,734 INFO SettingsFactory:134 - Automatic flush during beforeCompletion(): disabled
12:14:55,734 INFO SettingsFactory:138 - Automatic session close at end of transaction: disabled
12:14:55,734 INFO SettingsFactory:153 - Scrollable result sets: enabled
12:14:55,734 DEBUG SettingsFactory:157 - Wrap result sets: disabled
12:14:55,734 INFO SettingsFactory:161 - JDBC3 getGeneratedKeys(): disabled
12:14:55,734 INFO SettingsFactory:169 - Connection release mode: auto
12:14:55,734 INFO SettingsFactory:196 - Default batch fetch size: 1
12:14:55,734 INFO SettingsFactory:200 - Generate SQL with comments: disabled
12:14:55,734 INFO SettingsFactory:204 - Order SQL updates by primary key: disabled
12:14:55,734 INFO SettingsFactory:369 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
12:14:55,750 INFO ASTQueryTranslatorFactory:24 - Using ASTQueryTranslatorFactory
12:14:55,750 INFO SettingsFactory:212 - Query language substitutions: {}
12:14:55,750 INFO SettingsFactory:217 - JPA-QL strict compliance: disabled
12:14:55,750 INFO SettingsFactory:222 - Second-level cache: enabled
12:14:55,750 INFO SettingsFactory:226 - Query cache: disabled
12:14:55,750 INFO SettingsFactory:356 - Cache provider: org.hibernate.cache.NoCacheProvider
12:14:55,750 INFO SettingsFactory:241 - Optimize cache for minimal puts: disabled
12:14:55,750 INFO SettingsFactory:250 - Structured second-level cache entries: disabled
12:14:55,750 DEBUG SQLExceptionConverterFactory:52 - Using dialect defined converter
12:14:55,750 INFO SettingsFactory:270 - Echoing all SQL to stdout
12:14:55,750 INFO SettingsFactory:277 - Statistics: disabled
12:14:55,750 INFO SettingsFactory:281 - Deleted entity synthetic identifier rollback: disabled
12:14:55,750 INFO SettingsFactory:296 - Default entity-mode: pojo
12:14:55,812 INFO SessionFactoryImpl:161 - building session factory
12:14:55,812 DEBUG SessionFactoryImpl:173 - Session factory constructed with filter configurations : {}
12:14:55,812 DEBUG SessionFactoryImpl:177 - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=, hibernate.cache.provider_class=org.hibernate.cache.NoCacheProvider, sun.boot.library.path=C:\Program Files\Java\jre1.5.0\bin, java.vm.version=1.5.0-b64, hibernate.connection.username=, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=BR, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\dev\eclipse\workspace\JAXBHibernateExample, java.runtime.version=1.5.0-b64, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, hibernate.current_session_context_class=thread, java.endorsed.dirs=C:\Program Files\Java\jre1.5.0\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\mirog\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, cache.provider_class=org.hibernate.cache.NoCacheProvider, os.name=Windows XP, sun.jnu.encoding=Cp1252, java.library.path=C:\Program Files\Java\jre1.5.0\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files\HP\Systems Insight Manager\bin;C:\Program Files\HP\Systems Insight Manager\lbin;C:\Program Files\HP\Systems Insight Manager\lib;C:\Program Files\HP\Systems Insight Manager\j2re\bin\server;C:\Program Files\HP\Systems Insight Manager\j2re\bin;C:\Program Files\OpenSSH\bin;c:\ruby\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Subversion\bin;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\Program Files\The Open Group\WMI Mapper\bin;C:\Program Files\Inno Setup 5;D:\Programming\Java\tools\apache-ant-1.7.0-bin\bin;C:\Program Files\Java\jdk1.6.0\bin;rubygems;C:\Program Files\Common Files\GTK\2.0\bin;D:\dev\tomcat\apache-tomcat-5.5.23;C:\Program Files\QuickTime Alternative\QTSystem\;D:\utils\JAD, java.specification.name=Java Platform API Specification, java.class.version=49.0, hibernate.connection.pool_size=1, sun.management.compiler=HotSpot Client Compiler, os.version=5.1, user.home=C:\Documents and Settings\mirog, connection.password=, user.timezone=America/Sao_Paulo, java.awt.printerjob=sun.awt.windows.WPrinterJob, connection.username=, java.specification.version=1.5, file.encoding=Cp1252, hibernate.connection.driver_class=org.apache.derby.jdbc.EmbeddedDriver, show_sql=true, java.class.path=D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derby.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbyclient.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbynet.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbyrun.jar;D:\Programming\Java\tools\db-derby-10.3.1.4-bin\lib\derbytools.jar;D:\pluginsRepo\eclipse\plugins\org.hibernate.eclipse_3.2.0.200708310102-nightly\lib\tools\hibernate-tools.jar;D:\pluginsRepo\eclipse\plugins\org.hibernate.eclipse_3.2.0.200708310102-nightly\lib\tools\jtidy-r8-20060801.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-antlr-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-junit-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-launcher-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\antlr-2.7.6.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ant-swing-1.6.5.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\asm.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\asm-attrs.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\c3p0-0.9.0.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\cglib-2.1.3.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\checkstyle-all.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\cleanimports.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\commons-collections-2.1.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\commons-logging-1.0.4.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\concurrent-1.3.2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\connector.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\dom4j-1.6.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\ehcache-1.2.3.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jaas.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jacc-1_0-fr.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\javassist.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jaxen-1.1-beta-7.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-cache.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-common.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-jmx.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jboss-system.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jdbc2_0-stdext.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jgroups-2.2.8.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\jta.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\junit-3.8.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\log4j-1.2.11.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\oscache-2.1.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\proxool-0.8.3.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\swarmcache-1.0rc2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\syndiag2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\versioncheck.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\xerces-2.6.2.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\lib\xml-apis.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\build\hibernate3-client.jar;D:\Programming\Java\tools\Hibernate\hibernate-3.2\build\hibernate3.jar;C:\dev\eclipse\workspace\JAXBHibernateExample\build\classes;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\commons-el.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jasper-compiler-jdt.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jasper-compiler.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jasper-runtime.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\jsp-api.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\naming-factory-dbcp.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\naming-factory.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\naming-resources.jar;D:\dev\tomcat\apache-tomcat-5.5.23\common\lib\servlet-api.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\activation.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb1-impl.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb-api.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb-impl.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jaxb-xjc.jar;D:\Programming\Java\tools\JAXB\jaxb-ri-20070711\lib\jsr173_1.0_api.jar;C:\dev\eclipse\plugins\org.junit4_4.1.0.1\junit-4.1.jar;/C:/dev/eclipse/configuration/org.eclipse.osgi/bundles/47/1/.cp/;/C:/dev/eclipse/plugins/org.eclipse.jdt.junit_3.2.1.r321_v20060810/junitsupport.jar;/C:/dev/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.2.1.r321_v20060721/junitruntime.jar, user.name=mirog, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, current_session_context_class=thread, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=C:\Program Files\Java\jre1.5.0, hibernate.connection.url=jdbc:derby:JAXB_HIBERNATE, hibernate.dialect=org.hibernate.dialect.DerbyDialect, java.specification.vendor=Sun Microsystems Inc., user.language=pt, connection.pool_size=1, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, sharing, java.version=1.5.0, java.ext.dirs=C:\Program Files\Java\jre1.5.0\lib\ext, sun.boot.class.path=C:\Program Files\Java\jre1.5.0\lib\rt.jar;C:\Program Files\Java\jre1.5.0\lib\i18n.jar;C:\Program Files\Java\jre1.5.0\lib\sunrsasign.jar;C:\Program Files\Java\jre1.5.0\lib\jsse.jar;C:\Program Files\Java\jre1.5.0\lib\jce.jar;C:\Program Files\Java\jre1.5.0\lib\charsets.jar;C:\Program Files\Java\jre1.5.0\classes, java.vendor=Sun Microsystems Inc., file.separator=\, connection.driver_class=org.apache.derby.jdbc.EmbeddedDriver, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.desktop=windows, connection.url=jdbc:derby:JAXB_HIBERNATE, sun.cpu.isalist=, dialect=org.hibernate.dialect.DerbyDialect}
12:14:56,172 DEBUG AbstractEntityPersister:2688 - Static SQL for entity: com.gm.hibernate.impl.Subtask
12:14:56,172 DEBUG AbstractEntityPersister:2693 - Version select: select UUID from SUBTASK where UUID =?
12:14:56,172 DEBUG AbstractEntityPersister:2696 - Snapshot select: select subtask_.UUID, subtask_.name as name2_, subtask_.description as descript3_2_, subtask_.estimated_time as estimated4_2_, subtask_.start_time as start5_2_, subtask_.end_time as end6_2_ from SUBTASK subtask_ where subtask_.UUID=?
12:14:56,172 DEBUG AbstractEntityPersister:2699 - Insert 0: insert into SUBTASK (name, description, estimated_time, start_time, end_time, parent_uuid, UUID) values (?, ?, ?, ?, ?, ?, ?)
12:14:56,172 DEBUG AbstractEntityPersister:2700 - Update 0: update SUBTASK set name=?, description=?, estimated_time=?, start_time=?, end_time=? where UUID=?
12:14:56,172 DEBUG AbstractEntityPersister:2701 - Delete 0: delete from SUBTASK where UUID=?
12:14:56,187 DEBUG AbstractEntityPersister:2688 - Static SQL for entity: com.gm.hibernate.impl.Cycle
12:14:56,187 DEBUG AbstractEntityPersister:2693 - Version select: select number from CYCLE where number =?
12:14:56,187 DEBUG AbstractEntityPersister:2696 - Snapshot select: select cycle_.number, cycle_.start_time as start2_1_, cycle_.end_time as end3_1_ from CYCLE cycle_ where cycle_.number=?
12:14:56,187 DEBUG AbstractEntityPersister:2699 - Insert 0: insert into CYCLE (start_time, end_time, number) values (?, ?, ?)
12:14:56,187 DEBUG AbstractEntityPersister:2700 - Update 0: update CYCLE set start_time=?, end_time=? where number=?
12:14:56,187 DEBUG AbstractEntityPersister:2701 - Delete 0: delete from CYCLE where number=?
12:14:56,203 DEBUG AbstractEntityPersister:2688 - Static SQL for entity: com.gm.hibernate.impl.Task
12:14:56,203 DEBUG AbstractEntityPersister:2693 - Version select: select uuid from TASK where uuid =?
12:14:56,203 DEBUG AbstractEntityPersister:2696 - Snapshot select: select task_.uuid, task_.name as name0_, task_.description as descript3_0_, task_.estimated_time as estimated4_0_, task_.start_time as start5_0_, task_.end_time as end6_0_ from TASK task_ where task_.uuid=?
12:14:56,219 DEBUG AbstractEntityPersister:2699 - Insert 0: insert into TASK (name, description, estimated_time, start_time, end_time, number, uuid) values (?, ?, ?, ?, ?, ?, ?)
12:14:56,219 DEBUG AbstractEntityPersister:2700 - Update 0: update TASK set name=?, description=?, estimated_time=?, start_time=?, end_time=? where uuid=?
12:14:56,219 DEBUG AbstractEntityPersister:2701 - Delete 0: delete from TASK where uuid=?
12:14:56,219 DEBUG AbstractCollectionPersister:548 - Static SQL for collection: com.gm.hibernate.impl.Cycle.tasks
12:14:56,219 DEBUG AbstractCollectionPersister:550 - Row insert: update TASK set number=? where uuid=?
12:14:56,234 DEBUG AbstractCollectionPersister:556 - Row delete: update TASK set number=null where number=? and uuid=?
12:14:56,234 DEBUG AbstractCollectionPersister:559 - One-shot delete: update TASK set number=null where number=?
12:14:56,234 DEBUG AbstractCollectionPersister:548 - Static SQL for collection: com.gm.hibernate.impl.Task.subtasks
12:14:56,234 DEBUG AbstractCollectionPersister:550 - Row insert: update SUBTASK set parent_uuid=? where UUID=?
12:14:56,234 DEBUG AbstractCollectionPersister:556 - Row delete: update SUBTASK set parent_uuid=null where parent_uuid=? and UUID=?
12:14:56,234 DEBUG AbstractCollectionPersister:559 - One-shot delete: update SUBTASK set parent_uuid=null where parent_uuid=?
12:14:56,266 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=?
12:14:56,266 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=?
12:14:56,266 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=? for read only with rs
12:14:56,266 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=? for read only with rs
12:14:56,266 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=? for read only with rs
12:14:56,281 DEBUG EntityLoader:34 - Static select for action ACTION_MERGE on entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=?
12:14:56,281 DEBUG EntityLoader:34 - Static select for action ACTION_REFRESH on entity com.gm.hibernate.impl.Subtask: select subtask0_.UUID as UUID2_0_, subtask0_.name as name2_0_, subtask0_.description as descript3_2_0_, subtask0_.estimated_time as estimated4_2_0_, subtask0_.start_time as start5_2_0_, subtask0_.end_time as end6_2_0_ from SUBTASK subtask0_ where subtask0_.UUID=?
12:14:56,281 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_0_, cycle0_.start_time as start2_1_0_, cycle0_.end_time as end3_1_0_ from CYCLE cycle0_ where cycle0_.number=?
12:14:56,281 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_0_, cycle0_.start_time as start2_1_0_, cycle0_.end_time as end3_1_0_ from CYCLE cycle0_ where cycle0_.number=?
12:14:56,281 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_0_, cycle0_.start_time as start2_1_0_, cycle0_.end_time as end3_1_0_ from CYCLE cycle0_ where cycle0_.number=? for read only with rs
12:14:56,281 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_0_, cycle0_.start_time as start2_1_0_, cycle0_.end_time as end3_1_0_ from CYCLE cycle0_ where cycle0_.number=? for read only with rs
12:14:56,281 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_0_, cycle0_.start_time as start2_1_0_, cycle0_.end_time as end3_1_0_ from CYCLE cycle0_ where cycle0_.number=? for read only with rs
12:14:56,297 DEBUG EntityLoader:34 - Static select for action ACTION_MERGE on entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_1_, cycle0_.start_time as start2_1_1_, cycle0_.end_time as end3_1_1_, tasks1_.number as number3_, tasks1_.uuid as uuid3_, tasks1_.uuid as uuid0_0_, tasks1_.name as name0_0_, tasks1_.description as descript3_0_0_, tasks1_.estimated_time as estimated4_0_0_, tasks1_.start_time as start5_0_0_, tasks1_.end_time as end6_0_0_ from CYCLE cycle0_ left outer join TASK tasks1_ on cycle0_.number=tasks1_.number where cycle0_.number=?
12:14:56,297 DEBUG EntityLoader:34 - Static select for action ACTION_REFRESH on entity com.gm.hibernate.impl.Cycle: select cycle0_.number as number1_1_, cycle0_.start_time as start2_1_1_, cycle0_.end_time as end3_1_1_, tasks1_.number as number3_, tasks1_.uuid as uuid3_, tasks1_.uuid as uuid0_0_, tasks1_.name as name0_0_, tasks1_.description as descript3_0_0_, tasks1_.estimated_time as estimated4_0_0_, tasks1_.start_time as start5_0_0_, tasks1_.end_time as end6_0_0_ from CYCLE cycle0_ left outer join TASK tasks1_ on cycle0_.number=tasks1_.number where cycle0_.number=?
12:14:56,297 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_0_, task0_.name as name0_0_, task0_.description as descript3_0_0_, task0_.estimated_time as estimated4_0_0_, task0_.start_time as start5_0_0_, task0_.end_time as end6_0_0_ from TASK task0_ where task0_.uuid=?
12:14:56,297 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_0_, task0_.name as name0_0_, task0_.description as descript3_0_0_, task0_.estimated_time as estimated4_0_0_, task0_.start_time as start5_0_0_, task0_.end_time as end6_0_0_ from TASK task0_ where task0_.uuid=?
12:14:56,297 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_0_, task0_.name as name0_0_, task0_.description as descript3_0_0_, task0_.estimated_time as estimated4_0_0_, task0_.start_time as start5_0_0_, task0_.end_time as end6_0_0_ from TASK task0_ where task0_.uuid=? for read only with rs
12:14:56,297 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_0_, task0_.name as name0_0_, task0_.description as descript3_0_0_, task0_.estimated_time as estimated4_0_0_, task0_.start_time as start5_0_0_, task0_.end_time as end6_0_0_ from TASK task0_ where task0_.uuid=? for read only with rs
12:14:56,297 DEBUG EntityLoader:79 - Static select for entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_0_, task0_.name as name0_0_, task0_.description as descript3_0_0_, task0_.estimated_time as estimated4_0_0_, task0_.start_time as start5_0_0_, task0_.end_time as end6_0_0_ from TASK task0_ where task0_.uuid=? for read only with rs
12:14:56,297 DEBUG EntityLoader:34 - Static select for action ACTION_MERGE on entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_1_, task0_.name as name0_1_, task0_.description as descript3_0_1_, task0_.estimated_time as estimated4_0_1_, task0_.start_time as start5_0_1_, task0_.end_time as end6_0_1_, subtasks1_.parent_uuid as parent7_3_, subtasks1_.UUID as UUID3_, subtasks1_.UUID as UUID2_0_, subtasks1_.name as name2_0_, subtasks1_.description as descript3_2_0_, subtasks1_.estimated_time as estimated4_2_0_, subtasks1_.start_time as start5_2_0_, subtasks1_.end_time as end6_2_0_ from TASK task0_ left outer join SUBTASK subtasks1_ on task0_.uuid=subtasks1_.parent_uuid where task0_.uuid=?
12:14:56,297 DEBUG EntityLoader:34 - Static select for action ACTION_REFRESH on entity com.gm.hibernate.impl.Task: select task0_.uuid as uuid0_1_, task0_.name as name0_1_, task0_.description as descript3_0_1_, task0_.estimated_time as estimated4_0_1_, task0_.start_time as start5_0_1_, task0_.end_time as end6_0_1_, subtasks1_.parent_uuid as parent7_3_, subtasks1_.UUID as UUID3_, subtasks1_.UUID as UUID2_0_, subtasks1_.name as name2_0_, subtasks1_.description as descript3_2_0_, subtasks1_.estimated_time as estimated4_2_0_, subtasks1_.start_time as start5_2_0_, subtasks1_.end_time as end6_2_0_ from TASK task0_ left outer join SUBTASK subtasks1_ on task0_.uuid=subtasks1_.parent_uuid where task0_.uuid=?
12:14:56,312 DEBUG OneToManyLoader:64 - Static select for one-to-many com.gm.hibernate.impl.Cycle.tasks: select tasks0_.number as number1_, tasks0_.uuid as uuid1_, tasks0_.uuid as uuid0_0_, tasks0_.name as name0_0_, tasks0_.description as descript3_0_0_, tasks0_.estimated_time as estimated4_0_0_, tasks0_.start_time as start5_0_0_, tasks0_.end_time as end6_0_0_ from TASK tasks0_ where tasks0_.number=?
12:14:56,312 DEBUG OneToManyLoader:64 - Static select for one-to-many com.gm.hibernate.impl.Task.subtasks: select subtasks0_.parent_uuid as parent7_1_, subtasks0_.UUID as UUID1_, subtasks0_.UUID as UUID2_0_, subtasks0_.name as name2_0_, subtasks0_.description as descript3_2_0_, subtasks0_.estimated_time as estimated4_2_0_, subtasks0_.start_time as start5_2_0_, subtasks0_.end_time as end6_2_0_ from SUBTASK subtasks0_ where subtasks0_.parent_uuid=?
12:14:56,312 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory
12:14:56,312 DEBUG SessionFactoryObjectFactory:76 - registered: 90ffc9f914d63d000114d63d02b80000 (unnamed)
12:14:56,328 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
12:14:56,328 DEBUG SessionFactoryImpl:308 - instantiated session factory
12:14:56,344 DEBUG SessionFactoryImpl:390 - Checking 0 named HQL queries
12:14:56,344 DEBUG SessionFactoryImpl:410 - Checking 0 named SQL queries
12:14:56,422 DEBUG SessionImpl:220 - opened session at timestamp: 11890052963
12:14:56,594 DEBUG ThreadLocalSessionContext:290 - allowing method [beginTransaction] in non-transacted context
12:14:56,594 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
12:14:56,594 DEBUG JDBCTransaction:54 - begin
12:14:56,594 DEBUG ConnectionManager:415 - opening JDBC connection
12:14:56,594 DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
12:14:56,594 DEBUG DriverManagerConnectionProvider:99 - using pooled JDBC connection, pool size: 0
12:14:56,594 DEBUG JDBCTransaction:59 - current autocommit status: false
12:14:56,594 DEBUG JDBCContext:210 - after transaction begin
12:14:56,609 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [save] to proceed to real session
12:14:56,609 DEBUG DefaultSaveOrUpdateEventListener:161 - saving transient instance
12:14:56,609 DEBUG AbstractBatcher:548 - opening JDBC connection
12:14:56,609 DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 1
12:14:56,609 DEBUG DriverManagerConnectionProvider:109 - opening new JDBC connection
12:14:56,609 DEBUG DriverManagerConnectionProvider:115 - created connection to: jdbc:derby:JAXB_HIBERNATE, Isolation Level: 2
12:14:56,609 DEBUG SQL:130 - select next_hi from hibernate_unique_key for read only with rs
12:14:57,031 DEBUG SQL:151 - update hibernate_unique_key set next_hi = ? where next_hi = ?
12:14:57,187 DEBUG AbstractBatcher:563 - closing JDBC connection (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)
12:14:57,187 DEBUG DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
12:14:57,187 DEBUG TableHiLoGenerator:64 - new hi value: 0
12:14:57,187 DEBUG AbstractSaveEventListener:113 - generated identifier: 1, using strategy: org.hibernate.id.TableHiLoGenerator
12:14:57,203 DEBUG AbstractSaveEventListener:152 - saving [com.gm.hibernate.impl.Task#1]
12:14:57,203 DEBUG Cascade:115 - processing cascade ACTION_SAVE_UPDATE for: com.gm.hibernate.impl.Task
12:14:57,203 DEBUG Cascade:150 - done processing cascade ACTION_SAVE_UPDATE for: com.gm.hibernate.impl.Task
12:14:57,219 DEBUG WrapVisitor:87 - Wrapped collection in role: com.gm.hibernate.impl.Task.subtasks

I'm having exactly the same problem, anyone has a solution for it?

I guess the problem is caused by the type of id (native), so hibernate is not bringing back the database generated ID to assign it to the sets configured in the hbm.

Ok, so I read a little bit further and found guidelines to work with Parent/Child relationships.
If one follows the guidelines in chapter 21 of hibernate reference document, it should work just fine.

Now, I know I'm a newbie( just like the little title below my name states ), but I find quite annoying that something as simple as a parent/child relation must be mapped in a way that feels like the child is responsible for the control of the relation - not to mention the replication of field names in both parent and child tables.

Anyway, I couldn't make the configuration I posted work, but after following the example in ch.21, everything worked.