Hi,
I spent all day reading the docs, looking at examples, etc. had many problems, solved many of them, but now I am stuck. It must be a very simple problem as its a very simple code, but I fail to figure out what it is. The problem is that hibernate does not fill in the "owner"-column in the "antwort"-table (which is a foreign key that references "frage") resulting in an exception due to the not-null constraint I made (without it, I got no exceptions but filled the tables with useless rows).
To make it less abstract what the classes do: Frage means question, Antwort means answer. I have one answer-object and 4 possible answers mapped to it. The answers are saved in an array-list in the frage (=question) object.
Hibernate version:
3.0.1
Mapping documents:
Code:
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<hibernate-mapping package="core">
<class name="Antwort">
<id name="id">
<generator class="native"/>
</id>
<property name="antwortText"/>
<property name="anmerkung"/>
<property name="richtig"/>
</class>
</hibernate-mapping>
Code:
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<hibernate-mapping package="core">
<class name="Frage">
<id name="id">
<generator class="native"/>
</id>
<property name="myId"/>
<property name="titel"/>
<property name="momentaneAntwort"/>
<property name="bildLink"/>
<bag name="antwortenListe" inverse="true" cascade="save-update">
<key column="owner" not-null="true"/>
<one-to-many class="Antwort"/>
</bag>
</class>
</hibernate-mapping>
Code between sessionFactory.openSession() and session.close():Code:
Session s = factory.openSession();
Transaction tx = s.beginTransaction();
ArrayList l = new ArrayList();
Antwort a1 = new Antwort("Richtig", "", true);
Antwort a2 = new Antwort("asdfsarrbd", "sadfasd", false);
Antwort a3 = new Antwort("asdfsdsfdd", "", false);
Antwort a4 = new Antwort("sdfsdadfsd", "", false);
l.add(a1);
l.add(a2);
l.add(a3);
l.add(a4);
Frage f = new Frage("was ist wohl die antwort", l);
s.save(f);
tx.commit();
s.close();
Full stack trace of any exception that occurs:Code:
Exception in thread "main" org.hibernate.exception.ConstraintViolationException: could not insert: [core.Antwort]
at org.hibernate.exception.ErrorCodeConverter.convert(ErrorCodeConverter.java:74)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.persister.entity.BasicEntityPersister.insert(BasicEntityPersister.java:1878)
at org.hibernate.persister.entity.BasicEntityPersister.insert(BasicEntityPersister.java:2209)
at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:46)
at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:223)
at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:136)
at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:274)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:678)
at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:309)
at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:86)
at test.HibernateTests.main(HibernateTests.java:35)
Caused by: java.sql.SQLException: ORA-01400: cannot insert NULL into ("CSAE7072"."ANTWORT"."OWNER")
at oracle.jdbc.dbaccess.DBError.throwSqlException(DBError.java:134)
at oracle.jdbc.ttc7.TTIoer.processError(TTIoer.java:289)
at oracle.jdbc.ttc7.Oall7.receive(Oall7.java:582)
at oracle.jdbc.ttc7.TTC7Protocol.doOall7(TTC7Protocol.java:1986)
at oracle.jdbc.ttc7.TTC7Protocol.parseExecuteFetch(TTC7Protocol.java:1144)
at oracle.jdbc.driver.OracleStatement.executeNonQuery(OracleStatement.java:2152)
at oracle.jdbc.driver.OracleStatement.doExecuteOther(OracleStatement.java:2035)
at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:2876)
at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:609)
at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(NonBatchingBatcher.java:22)
at org.hibernate.persister.entity.BasicEntityPersister.insert(BasicEntityPersister.java:1862)
... 11 more
19:38:34,812 ERROR AbstractFlushingEventListener:277 - Could not synchronize database state with session
org.hibernate.exception.ConstraintViolationException: could not insert: [core.Antwort]
at org.hibernate.exception.ErrorCodeConverter.convert(ErrorCodeConverter.java:74)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.persister.entity.BasicEntityPersister.insert(BasicEntityPersister.java:1878)
at org.hibernate.persister.entity.BasicEntityPersister.insert(BasicEntityPersister.java:2209)
at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:46)
at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:239)
at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:223)
at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:136)
at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:274)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:678)
at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:309)
at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:86)
at test.HibernateTests.main(HibernateTests.java:35)
Caused by: java.sql.SQLException: ORA-01400: cannot insert NULL into ("CSAE7072"."ANTWORT"."OWNER")
at oracle.jdbc.dbaccess.DBError.throwSqlException(DBError.java:134)
at oracle.jdbc.ttc7.TTIoer.processError(TTIoer.java:289)
at oracle.jdbc.ttc7.Oall7.receive(Oall7.java:582)
at oracle.jdbc.ttc7.TTC7Protocol.doOall7(TTC7Protocol.java:1986)
at oracle.jdbc.ttc7.TTC7Protocol.parseExecuteFetch(TTC7Protocol.java:1144)
at oracle.jdbc.driver.OracleStatement.executeNonQuery(OracleStatement.java:2152)
at oracle.jdbc.driver.OracleStatement.doExecuteOther(OracleStatement.java:2035)
at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:2876)
at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:609)
at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(NonBatchingBatcher.java:22)
at org.hibernate.persister.entity.BasicEntityPersister.insert(BasicEntityPersister.java:1862)
... 11 more
Name and version of the database you are using:Oracle 9
The generated SQL (show_sql=true):Code:
Hibernate: insert into Frage (myId, titel, momentaneAntwort, bildLink, id) values (?, ?, ?, ?, ?)
Hibernate: insert into Antwort (antwortText, anmerkung, richtig, id) values (?, ?, ?, ?)
Debug level Hibernate log excerpt:Code:
19:41:51,046 INFO Environment:460 - Hibernate 3.0.1
19:41:51,046 INFO Environment:478 - loaded properties from resource hibernate.properties: {hibernate.order_updates=true, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, hibernate.cglib.use_reflection_optimizer=true, hibernate.cache.provider_class=net.sf.hibernate.cache.EhCacheProvider, hibernate.cache.use_query_cache=true, hibernate.max_fetch_depth=1, hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, hibernate.jdbc.use_streams_for_binary=true, hibernate.jdbc.batch_size=0, hibernate.connection.username=xxx, hibernate.cache.region_prefix=hibernate.test, hibernate.connection.url=jdbc:oracle:thin:@so2.uibk.ac.at:1521:EDU, hibernate.connection.password=****, hibernate.jdbc.batch_versioned_data=true, hibernate.connection.pool_size=1}
19:41:51,046 INFO Environment:505 - using java.io streams to persist binary types
19:41:51,046 INFO Environment:506 - using CGLIB reflection optimizer
19:41:51,062 INFO Environment:536 - using JDK 1.4 java.sql.Timestamp handling
19:41:51,125 INFO Configuration:1160 - configuring from resource: /hibernate.cfg.xml
19:41:51,125 INFO Configuration:1131 - Configuration resource: /hibernate.cfg.xml
19:41:51,375 DEBUG DTDEntityResolver:42 - trying to locate http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd in classpath under org/hibernate/
19:41:51,375 DEBUG DTDEntityResolver:53 - found http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd in classpath
19:41:51,421 DEBUG Configuration:1117 - hibernate.connection.url=jdbc:oracle:thin:@so2.uibk.ac.at:1521:EDU
19:41:51,437 DEBUG Configuration:1117 - hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver
19:41:51,437 DEBUG Configuration:1117 - hibernate.connection.username=xxx
19:41:51,437 DEBUG Configuration:1117 - hibernate.connection.password=xxx
19:41:51,437 DEBUG Configuration:1117 - hibernate.dialect=org.hibernate.dialect.Oracle9Dialect
19:41:51,437 DEBUG Configuration:1117 - hibernate.show_sql=true
19:41:51,437 DEBUG Configuration:1117 - hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory
19:41:51,437 DEBUG Configuration:1117 - hibernate.connection.pool_size=1
19:41:51,437 DEBUG Configuration:1117 - hibernate.jdbc.batch_size=0
19:41:51,437 DEBUG Configuration:1117 - hibernate.jdbc.batch_versioned_data=true
19:41:51,437 DEBUG Configuration:1117 - hibernate.jdbc.use_streams_for_binary=true
19:41:51,437 DEBUG Configuration:1117 - hibernate.max_fetch_depth=1
19:41:51,437 DEBUG Configuration:1117 - hibernate.cache.use_query_cache=true
19:41:51,437 DEBUG Configuration:1117 - hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider
19:41:51,437 DEBUG Configuration:1312 - null<-org.dom4j.tree.DefaultAttribute@cd2c3c [Attribute: name resource value "core/mappings/Antwort.hbm.xml"]
19:41:51,437 INFO Configuration:441 - Mapping resource: core/mappings/Antwort.hbm.xml
19:41:51,437 DEBUG DTDEntityResolver:42 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath under org/hibernate/
19:41:51,437 DEBUG DTDEntityResolver:53 - found http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath
19:41:51,562 INFO HbmBinder:258 - Mapping class: core.Antwort -> Antwort
19:41:51,578 DEBUG HbmBinder:1089 - Mapped property: id -> id
19:41:51,578 DEBUG HbmBinder:1089 - Mapped property: antwortText -> antwortText
19:41:51,578 DEBUG HbmBinder:1089 - Mapped property: anmerkung -> anmerkung
19:41:51,578 DEBUG HbmBinder:1089 - Mapped property: richtig -> richtig
19:41:51,578 DEBUG Configuration:1312 - null<-org.dom4j.tree.DefaultAttribute@121cc40 [Attribute: name resource value "core/mappings/Frage.hbm.xml"]
19:41:51,578 INFO Configuration:441 - Mapping resource: core/mappings/Frage.hbm.xml
19:41:51,593 DEBUG DTDEntityResolver:42 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath under org/hibernate/
19:41:51,593 DEBUG DTDEntityResolver:53 - found http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd in classpath
19:41:51,625 INFO HbmBinder:258 - Mapping class: core.Frage -> Frage
19:41:51,625 DEBUG HbmBinder:1089 - Mapped property: id -> id
19:41:51,625 DEBUG HbmBinder:1089 - Mapped property: myId -> myId
19:41:51,625 DEBUG HbmBinder:1089 - Mapped property: titel -> titel
19:41:51,640 DEBUG HbmBinder:1089 - Mapped property: momentaneAntwort -> momentaneAntwort
19:41:51,640 DEBUG HbmBinder:1089 - Mapped property: bildLink -> bildLink
19:41:51,656 DEBUG HbmBinder:1089 - Mapped property: antwortenListe
19:41:51,656 INFO Configuration:1272 - Configured SessionFactory: null
19:41:51,656 DEBUG Configuration:1273 - properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=xxx, hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider, sun.boot.library.path=C:\Programme\Java\jre1.5.0_02\bin, java.vm.version=1.5.0_02-b09, hibernate.connection.username=csae7072, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, hibernate.cache.use_query_cache=true, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=AT, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\Programme\eclipse\workspace\swe4, java.runtime.version=1.5.0_02-b09, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Programme\Java\jre1.5.0_02\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOKUME~1\David\LOKALE~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, hibernate.order_updates=true, hibernate.jdbc.batch_versioned_data=true, hibernate.cache.region_prefix=hibernate.test, sun.jnu.encoding=Cp1252, java.library.path=C:\Programme\Java\jre1.5.0_02\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Programme\ATI Technologies\ATI.ACE\, java.specification.name=Java Platform API Specification, java.class.version=49.0, hibernate.connection.pool_size=1, sun.management.compiler=HotSpot Client Compiler, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, os.version=5.1, user.home=C:\Dokumente und Einstellungen\David, user.timezone=Europe/Berlin, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.5, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, java.class.path=C:\Programme\eclipse\workspace\swe4\bin;C:\Programme\eclipse\workspace\swe4\libs\ant-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-antlr-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-junit-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-launcher-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\antlr-2.7.5H3.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-swing-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\asm.jar;C:\Programme\eclipse\workspace\swe4\libs\asm-attrs.jar;C:\Programme\eclipse\workspace\swe4\libs\c3p0-0.8.5.2.jar;C:\Programme\eclipse\workspace\swe4\libs\cglib-2.1.jar;C:\Programme\eclipse\workspace\swe4\libs\cleanimports.jar;C:\Programme\eclipse\workspace\swe4\libs\commons-collections-2.1.1.jar;C:\Programme\eclipse\workspace\swe4\libs\commons-logging-1.0.4.jar;C:\Programme\eclipse\workspace\swe4\libs\concurrent-1.3.2.jar;C:\Programme\eclipse\workspace\swe4\libs\connector.jar;C:\Programme\eclipse\workspace\swe4\libs\dom4j-1.6.jar;C:\Programme\eclipse\workspace\swe4\libs\ehcache-1.1.jar;C:\Programme\eclipse\workspace\swe4\libs\hibernate3.jar;C:\Programme\eclipse\workspace\swe4\libs\jaas.jar;C:\Programme\eclipse\workspace\swe4\libs\jacc-1_0-fr.jar;C:\Programme\eclipse\workspace\swe4\libs\jaxen-1.1-beta-4.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-cache.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-common.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-jmx.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-system.jar;C:\Programme\eclipse\workspace\swe4\libs\jdbc2_0-stdext.jar;C:\Programme\eclipse\workspace\swe4\libs\jgroups-2.2.7.jar;C:\Programme\eclipse\workspace\swe4\libs\jogg-0.0.5.jar;C:\Programme\eclipse\workspace\swe4\libs\jorbis-0.0.12.jar;C:\Programme\eclipse\workspace\swe4\libs\jta.jar;C:\Programme\eclipse\workspace\swe4\libs\junit-3.8.1.jar;C:\Programme\eclipse\workspace\swe4\libs\log4j-1.2.9.jar;C:\Programme\eclipse\workspace\swe4\libs\oalnf.jar;C:\Programme\eclipse\workspace\swe4\libs\odmg-3.0.jar;C:\Programme\eclipse\workspace\swe4\libs\ojdbc14.jar;C:\Programme\eclipse\workspace\swe4\libs\oscache-2.1.jar;C:\Programme\eclipse\workspace\swe4\libs\proxool-0.8.3.jar;C:\Programme\eclipse\workspace\swe4\libs\swarmcache-1.0rc2.jar;C:\Programme\eclipse\workspace\swe4\libs\tritonus_share.jar;C:\Programme\eclipse\workspace\swe4\libs\versioncheck.jar;C:\Programme\eclipse\workspace\swe4\libs\vorbisspi1.0.jar;C:\Programme\eclipse\workspace\swe4\libs\xalan-2.4.0.jar;C:\Programme\eclipse\workspace\swe4\libs\xerces-2.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\xml-apis.jar, user.name=David, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\Programme\Java\jre1.5.0_02, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, hibernate.connection.url=jdbc:oracle:thin:@so2.uibk.ac.at:1521:EDU, user.language=de, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, sharing, hibernate.jdbc.use_streams_for_binary=true, java.version=1.5.0_02, java.ext.dirs=C:\Programme\Java\jre1.5.0_02\lib\ext, sun.boot.class.path=C:\Programme\Java\jre1.5.0_02\lib\rt.jar;C:\Programme\Java\jre1.5.0_02\lib\i18n.jar;C:\Programme\Java\jre1.5.0_02\lib\sunrsasign.jar;C:\Programme\Java\jre1.5.0_02\lib\jsse.jar;C:\Programme\Java\jre1.5.0_02\lib\jce.jar;C:\Programme\Java\jre1.5.0_02\lib\charsets.jar;C:\Programme\Java\jre1.5.0_02\classes, java.vendor=Sun Microsystems Inc., hibernate.jdbc.batch_size=0, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, hibernate.max_fetch_depth=1, sun.desktop=windows, sun.cpu.isalist=}
19:41:51,656 DEBUG Configuration:1048 - Preparing to build session factory with filters : {}
19:41:51,656 INFO Configuration:852 - processing extends queue
19:41:51,656 INFO Configuration:856 - processing collection mappings
19:41:51,656 DEBUG HbmBinder:2342 - Second pass for collection: core.Frage.antwortenListe
19:41:51,656 INFO HbmBinder:1962 - Mapping collection: core.Frage.antwortenListe -> Antwort
19:41:51,656 DEBUG HbmBinder:2358 - Mapped collection key: owner, one-to-many: core.Antwort
19:41:51,656 INFO Configuration:865 - processing association property references
19:41:51,656 INFO Configuration:894 - processing foreign key constraints
19:41:51,656 DEBUG Configuration:941 - resolving reference to class: core.Frage
19:41:51,734 INFO Dialect:91 - Using dialect: org.hibernate.dialect.Oracle9Dialect
19:41:51,734 DEBUG SQLExceptionConverterFactory:52 - Using dialect defined converter
19:41:51,734 INFO SettingsFactory:88 - Maximum outer join fetch depth: 1
19:41:51,734 INFO SettingsFactory:91 - Default batch fetch size: 1
19:41:51,734 INFO SettingsFactory:95 - Generate SQL with comments: disabled
19:41:51,734 INFO SettingsFactory:99 - Order SQL updates by primary key: enabled
19:41:51,734 INFO SettingsFactory:285 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
19:41:51,734 INFO ASTQueryTranslatorFactory:21 - Using ASTQueryTranslatorFactory
19:41:51,734 INFO SettingsFactory:107 - Query language substitutions: {}
19:41:51,750 INFO DriverManagerConnectionProvider:41 - Using Hibernate built-in connection pool (not for production use!)
19:41:51,750 INFO DriverManagerConnectionProvider:42 - Hibernate connection pool size: 1
19:41:51,750 INFO DriverManagerConnectionProvider:45 - autocommit mode: false
19:41:51,796 INFO DriverManagerConnectionProvider:80 - using driver: oracle.jdbc.driver.OracleDriver at URL: jdbc:oracle:thin:@so2.uibk.ac.at:1521:EDU
19:41:51,796 INFO DriverManagerConnectionProvider:83 - connection properties: {user=xxx, password=xxx}
19:41:51,796 DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
19:41:51,796 DEBUG DriverManagerConnectionProvider:109 - opening new JDBC connection
19:41:52,421 DEBUG DriverManagerConnectionProvider:115 - created connection to: jdbc:oracle:thin:@so2.uibk.ac.at:1521:EDU, Isolation Level: 2
19:41:52,437 DEBUG DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
19:41:52,453 INFO SettingsFactory:157 - Scrollable result sets: enabled
19:41:52,453 DEBUG SettingsFactory:161 - Wrap result sets: disabled
19:41:52,453 INFO SettingsFactory:165 - JDBC3 getGeneratedKeys(): disabled
19:41:52,453 INFO TransactionFactoryFactory:34 - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
19:41:52,453 INFO TransactionManagerLookupFactory:33 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
19:41:52,453 INFO SettingsFactory:177 - Automatic flush during beforeCompletion(): disabled
19:41:52,453 INFO SettingsFactory:180 - Automatic session close at end of transaction: disabled
19:41:52,453 INFO SettingsFactory:186 - Second-level cache: enabled
19:41:52,453 INFO SettingsFactory:190 - Query cache: enabled
19:41:52,453 INFO SettingsFactory:272 - Cache provider: org.hibernate.cache.EhCacheProvider
19:41:52,453 INFO SettingsFactory:205 - Optimize cache for minimal puts: disabled
19:41:52,453 INFO SettingsFactory:210 - Cache region prefix: hibernate.test
19:41:52,453 INFO SettingsFactory:214 - Structured second-level cache entries: enabled
19:41:52,453 INFO SettingsFactory:259 - Query cache factory: org.hibernate.cache.StandardQueryCacheFactory
19:41:52,468 INFO SettingsFactory:222 - Echoing all SQL to stdout
19:41:52,468 INFO SettingsFactory:226 - Statistics: disabled
19:41:52,468 INFO SettingsFactory:230 - Deleted entity synthetic identifier rollback: disabled
19:41:52,468 INFO SettingsFactory:244 - Default entity-mode: pojo
19:41:52,609 INFO SessionFactoryImpl:147 - building session factory
19:41:52,609 DEBUG SessionFactoryImpl:156 - Session factory constructed with filter configurations : {}
19:41:52,609 DEBUG SessionFactoryImpl:159 - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=xxx, hibernate.cache.provider_class=org.hibernate.cache.EhCacheProvider, sun.boot.library.path=C:\Programme\Java\jre1.5.0_02\bin, java.vm.version=1.5.0_02-b09, hibernate.connection.username=csae7072, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, hibernate.cache.use_query_cache=true, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=AT, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\Programme\eclipse\workspace\swe4, java.runtime.version=1.5.0_02-b09, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Programme\Java\jre1.5.0_02\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOKUME~1\David\LOKALE~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, hibernate.order_updates=true, hibernate.jdbc.batch_versioned_data=true, hibernate.cache.region_prefix=hibernate.test, sun.jnu.encoding=Cp1252, java.library.path=C:\Programme\Java\jre1.5.0_02\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Programme\ATI Technologies\ATI.ACE\, java.specification.name=Java Platform API Specification, java.class.version=49.0, hibernate.connection.pool_size=1, sun.management.compiler=HotSpot Client Compiler, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, os.version=5.1, user.home=C:\Dokumente und Einstellungen\David, user.timezone=Europe/Berlin, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.5, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, java.class.path=C:\Programme\eclipse\workspace\swe4\bin;C:\Programme\eclipse\workspace\swe4\libs\ant-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-antlr-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-junit-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-launcher-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\antlr-2.7.5H3.jar;C:\Programme\eclipse\workspace\swe4\libs\ant-swing-1.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\asm.jar;C:\Programme\eclipse\workspace\swe4\libs\asm-attrs.jar;C:\Programme\eclipse\workspace\swe4\libs\c3p0-0.8.5.2.jar;C:\Programme\eclipse\workspace\swe4\libs\cglib-2.1.jar;C:\Programme\eclipse\workspace\swe4\libs\cleanimports.jar;C:\Programme\eclipse\workspace\swe4\libs\commons-collections-2.1.1.jar;C:\Programme\eclipse\workspace\swe4\libs\commons-logging-1.0.4.jar;C:\Programme\eclipse\workspace\swe4\libs\concurrent-1.3.2.jar;C:\Programme\eclipse\workspace\swe4\libs\connector.jar;C:\Programme\eclipse\workspace\swe4\libs\dom4j-1.6.jar;C:\Programme\eclipse\workspace\swe4\libs\ehcache-1.1.jar;C:\Programme\eclipse\workspace\swe4\libs\hibernate3.jar;C:\Programme\eclipse\workspace\swe4\libs\jaas.jar;C:\Programme\eclipse\workspace\swe4\libs\jacc-1_0-fr.jar;C:\Programme\eclipse\workspace\swe4\libs\jaxen-1.1-beta-4.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-cache.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-common.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-jmx.jar;C:\Programme\eclipse\workspace\swe4\libs\jboss-system.jar;C:\Programme\eclipse\workspace\swe4\libs\jdbc2_0-stdext.jar;C:\Programme\eclipse\workspace\swe4\libs\jgroups-2.2.7.jar;C:\Programme\eclipse\workspace\swe4\libs\jogg-0.0.5.jar;C:\Programme\eclipse\workspace\swe4\libs\jorbis-0.0.12.jar;C:\Programme\eclipse\workspace\swe4\libs\jta.jar;C:\Programme\eclipse\workspace\swe4\libs\junit-3.8.1.jar;C:\Programme\eclipse\workspace\swe4\libs\log4j-1.2.9.jar;C:\Programme\eclipse\workspace\swe4\libs\oalnf.jar;C:\Programme\eclipse\workspace\swe4\libs\odmg-3.0.jar;C:\Programme\eclipse\workspace\swe4\libs\ojdbc14.jar;C:\Programme\eclipse\workspace\swe4\libs\oscache-2.1.jar;C:\Programme\eclipse\workspace\swe4\libs\proxool-0.8.3.jar;C:\Programme\eclipse\workspace\swe4\libs\swarmcache-1.0rc2.jar;C:\Programme\eclipse\workspace\swe4\libs\tritonus_share.jar;C:\Programme\eclipse\workspace\swe4\libs\versioncheck.jar;C:\Programme\eclipse\workspace\swe4\libs\vorbisspi1.0.jar;C:\Programme\eclipse\workspace\swe4\libs\xalan-2.4.0.jar;C:\Programme\eclipse\workspace\swe4\libs\xerces-2.6.2.jar;C:\Programme\eclipse\workspace\swe4\libs\xml-apis.jar, user.name=David, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\Programme\Java\jre1.5.0_02, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, hibernate.connection.url=jdbc:oracle:thin:@so2.uibk.ac.at:1521:EDU, user.language=de, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, sharing, hibernate.jdbc.use_streams_for_binary=true, java.version=1.5.0_02, java.ext.dirs=C:\Programme\Java\jre1.5.0_02\lib\ext, sun.boot.class.path=C:\Programme\Java\jre1.5.0_02\lib\rt.jar;C:\Programme\Java\jre1.5.0_02\lib\i18n.jar;C:\Programme\Java\jre1.5.0_02\lib\sunrsasign.jar;C:\Programme\Java\jre1.5.0_02\lib\jsse.jar;C:\Programme\Java\jre1.5.0_02\lib\jce.jar;C:\Programme\Java\jre1.5.0_02\lib\charsets.jar;C:\Programme\Java\jre1.5.0_02\classes, java.vendor=Sun Microsystems Inc., hibernate.jdbc.batch_size=0, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, hibernate.max_fetch_depth=1, sun.desktop=windows, sun.cpu.isalist=}
19:41:52,625 DEBUG CacheManager:191 - Creating new CacheManager with default config
19:41:52,625 DEBUG CacheManager:164 - Configuring ehcache from classpath.
19:41:52,625 DEBUG Configurator:121 - Configuring ehcache from ehcache.xml found in the classpath: file:/C:/Programme/eclipse/workspace/swe4/bin/ehcache.xml
19:41:52,640 DEBUG Configuration$DiskStore:185 - Disk Store Path: C:\DOKUME~1\David\LOKALE~1\Temp\
19:41:52,640 DEBUG MemoryStore:147 - sampleCache2 Cache: Using SpoolingLinkedHashMap implementation
19:41:52,640 DEBUG MemoryStore:128 - initialized MemoryStore for sampleCache2
19:41:52,640 DEBUG Cache:277 - Initialised cache: sampleCache2
19:41:52,656 DEBUG DiskStore:194 - Deleting data file sampleCache1.data
19:41:52,656 DEBUG MemoryStore:147 - sampleCache1 Cache: Using SpoolingLinkedHashMap implementation
19:41:52,656 DEBUG MemoryStore:128 - initialized MemoryStore for sampleCache1
19:41:52,656 DEBUG Cache:277 - Initialised cache: sampleCache1
19:41:52,890 DEBUG BasicEntityPersister:2229 - Static SQL for entity: core.Antwort
19:41:52,890 DEBUG BasicEntityPersister:2231 - Version select: select id from Antwort where id =?
19:41:52,890 DEBUG BasicEntityPersister:2232 - Snapshot select: select antwort_.id, antwort_.antwortText as antwortT2_0_, antwort_.anmerkung as anmerkung0_, antwort_.richtig as richtig0_ from Antwort antwort_ where antwort_.id=?
19:41:52,890 DEBUG BasicEntityPersister:2234 - Insert 0: insert into Antwort (antwortText, anmerkung, richtig, id) values (?, ?, ?, ?)
19:41:52,890 DEBUG BasicEntityPersister:2235 - Update 0: update Antwort set antwortText=?, anmerkung=?, richtig=? where id=?
19:41:52,890 DEBUG BasicEntityPersister:2236 - Delete 0: delete from Antwort where id=?
19:41:53,125 DEBUG BasicEntityPersister:2229 - Static SQL for entity: core.Frage
19:41:53,125 DEBUG BasicEntityPersister:2231 - Version select: select id from Frage where id =?
19:41:53,125 DEBUG BasicEntityPersister:2232 - Snapshot select: select frage_.id, frage_.myId as myId1_, frage_.titel as titel1_, frage_.momentaneAntwort as momentan4_1_, frage_.bildLink as bildLink1_ from Frage frage_ where frage_.id=?
19:41:53,125 DEBUG BasicEntityPersister:2234 - Insert 0: insert into Frage (myId, titel, momentaneAntwort, bildLink, id) values (?, ?, ?, ?, ?)
19:41:53,125 DEBUG BasicEntityPersister:2235 - Update 0: update Frage set myId=?, titel=?, momentaneAntwort=?, bildLink=? where id=?
19:41:53,125 DEBUG BasicEntityPersister:2236 - Delete 0: delete from Frage where id=?
19:41:53,125 DEBUG AbstractCollectionPersister:479 - Static SQL for collection: core.Frage.antwortenListe
19:41:53,125 DEBUG AbstractCollectionPersister:480 - Row insert: update Antwort set owner=? where id=?
19:41:53,125 DEBUG AbstractCollectionPersister:482 - Row delete: update Antwort set owner=null where owner=? and id=?
19:41:53,125 DEBUG AbstractCollectionPersister:483 - One-shot delete: update Antwort set owner=null where owner=?
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Antwort: select antwort0_.id as id0_, antwort0_.antwortText as antwortT2_0_0_, antwort0_.anmerkung as anmerkung0_0_, antwort0_.richtig as richtig0_0_ from Antwort antwort0_ where antwort0_.id=?
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Antwort: select antwort0_.id as id0_, antwort0_.antwortText as antwortT2_0_0_, antwort0_.anmerkung as anmerkung0_0_, antwort0_.richtig as richtig0_0_ from Antwort antwort0_ where antwort0_.id=?
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Antwort: select antwort0_.id as id0_, antwort0_.antwortText as antwortT2_0_0_, antwort0_.anmerkung as anmerkung0_0_, antwort0_.richtig as richtig0_0_ from Antwort antwort0_ where antwort0_.id=? for update
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Antwort: select antwort0_.id as id0_, antwort0_.antwortText as antwortT2_0_0_, antwort0_.anmerkung as anmerkung0_0_, antwort0_.richtig as richtig0_0_ from Antwort antwort0_ where antwort0_.id=? for update nowait
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Frage: select frage0_.id as id0_, frage0_.myId as myId1_0_, frage0_.titel as titel1_0_, frage0_.momentaneAntwort as momentan4_1_0_, frage0_.bildLink as bildLink1_0_ from Frage frage0_ where frage0_.id=?
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Frage: select frage0_.id as id0_, frage0_.myId as myId1_0_, frage0_.titel as titel1_0_, frage0_.momentaneAntwort as momentan4_1_0_, frage0_.bildLink as bildLink1_0_ from Frage frage0_ where frage0_.id=?
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Frage: select frage0_.id as id0_, frage0_.myId as myId1_0_, frage0_.titel as titel1_0_, frage0_.momentaneAntwort as momentan4_1_0_, frage0_.bildLink as bildLink1_0_ from Frage frage0_ where frage0_.id=? for update
19:41:53,156 DEBUG EntityLoader:120 - Static select for entity core.Frage: select frage0_.id as id0_, frage0_.myId as myId1_0_, frage0_.titel as titel1_0_, frage0_.momentaneAntwort as momentan4_1_0_, frage0_.bildLink as bildLink1_0_ from Frage frage0_ where frage0_.id=? for update nowait
19:41:53,171 DEBUG OneToManyLoader:98 - Static select for one-to-many core.Frage.antwortenListe: select antwortenl0_.owner as owner__, antwortenl0_.id as id__, antwortenl0_.id as id0_, antwortenl0_.antwortText as antwortT2_0_0_, antwortenl0_.anmerkung as anmerkung0_0_, antwortenl0_.richtig as richtig0_0_ from Antwort antwortenl0_ where antwortenl0_.owner=?
19:41:53,171 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory
19:41:53,171 DEBUG SessionFactoryObjectFactory:76 - registered: 0a688955038f0cd401038f0cd7930000 (unnamed)
19:41:53,171 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
19:41:53,171 DEBUG SessionFactoryImpl:256 - instantiated session factory
19:41:53,171 INFO UpdateTimestampsCache:43 - starting update timestamps cache at region: hibernate.test.org.hibernate.cache.UpdateTimestampsCache
19:41:53,171 WARN EhCacheProvider:97 - Could not find configuration [hibernate.test.org.hibernate.cache.UpdateTimestampsCache]; using defaults.
19:41:53,171 DEBUG DiskStore:194 - Deleting data file hibernate.test.org.hibernate.cache.UpdateTimestampsCache.data
19:41:53,171 DEBUG MemoryStore:147 - hibernate.test.org.hibernate.cache.UpdateTimestampsCache Cache: Using SpoolingLinkedHashMap implementation
19:41:53,171 DEBUG MemoryStore:128 - initialized MemoryStore for hibernate.test.org.hibernate.cache.UpdateTimestampsCache
19:41:53,171 DEBUG Cache:277 - Initialised cache: hibernate.test.org.hibernate.cache.UpdateTimestampsCache
19:41:53,171 DEBUG EhCacheProvider:100 - started EHCache region: hibernate.test.org.hibernate.cache.UpdateTimestampsCache
19:41:53,187 INFO StandardQueryCache:50 - starting query cache at region: hibernate.test.org.hibernate.cache.StandardQueryCache
19:41:53,187 WARN EhCacheProvider:97 - Could not find configuration [hibernate.test.org.hibernate.cache.StandardQueryCache]; using defaults.
19:41:53,187 DEBUG DiskStore:194 - Deleting data file hibernate.test.org.hibernate.cache.StandardQueryCache.data
19:41:53,187 DEBUG MemoryStore:147 - hibernate.test.org.hibernate.cache.StandardQueryCache Cache: Using SpoolingLinkedHashMap implementation
19:41:53,187 DEBUG MemoryStore:128 - initialized MemoryStore for hibernate.test.org.hibernate.cache.StandardQueryCache
19:41:53,187 DEBUG Cache:277 - Initialised cache: hibernate.test.org.hibernate.cache.StandardQueryCache
19:41:53,187 DEBUG EhCacheProvider:100 - started EHCache region: hibernate.test.org.hibernate.cache.StandardQueryCache
19:41:53,187 INFO SessionFactoryImpl:373 - Checking 0 named queries
19:41:53,218 DEBUG SessionImpl:237 - opened session at timestamp: 4566206518013952
19:41:53,218 DEBUG JDBCTransaction:46 - begin
19:41:53,218 DEBUG AbstractBatcher:414 - opening JDBC connection
19:41:53,218 DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
19:41:53,218 DEBUG DriverManagerConnectionProvider:99 - using pooled JDBC connection, pool size: 0
19:41:53,218 DEBUG JDBCTransaction:50 - current autocommit status: false
-1
19:41:53,218 DEBUG DefaultSaveOrUpdateEventListener:159 - saving transient instance
19:41:53,218 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,218 DEBUG SQL:310 - select hibernate_sequence.nextval from dual
Hibernate: select hibernate_sequence.nextval from dual
19:41:53,218 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,296 DEBUG SequenceGenerator:87 - Sequence identifier generated: 11
19:41:53,296 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,296 DEBUG AbstractBatcher:392 - closing statement
19:41:53,296 DEBUG AbstractSaveEventListener:89 - generated identifier: 11, using strategy: org.hibernate.id.SequenceGenerator
19:41:53,312 DEBUG AbstractSaveEventListener:132 - saving [core.Frage#11]
19:41:53,312 DEBUG Cascades:806 - processing cascade ACTION_SAVE_UPDATE for: core.Frage
19:41:53,312 DEBUG Cascades:831 - done processing cascade ACTION_SAVE_UPDATE for: core.Frage
19:41:53,312 DEBUG WrapVisitor:86 - Wrapped collection in role: core.Frage.antwortenListe
19:41:53,328 DEBUG Cascades:806 - processing cascade ACTION_SAVE_UPDATE for: core.Frage
19:41:53,328 DEBUG Cascades:855 - cascade ACTION_SAVE_UPDATE for collection: core.Frage.antwortenListe
19:41:53,328 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,328 DEBUG Cascades:525 - id unsaved-value: 0
19:41:53,328 DEBUG AbstractSaveEventListener:408 - transient instance of: core.Antwort
19:41:53,328 DEBUG DefaultSaveOrUpdateEventListener:159 - saving transient instance
19:41:53,328 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,328 DEBUG SQL:310 - select hibernate_sequence.nextval from dual
Hibernate: select hibernate_sequence.nextval from dual
19:41:53,328 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,359 DEBUG SequenceGenerator:87 - Sequence identifier generated: 12
19:41:53,359 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,359 DEBUG AbstractBatcher:392 - closing statement
19:41:53,359 DEBUG AbstractSaveEventListener:89 - generated identifier: 12, using strategy: org.hibernate.id.SequenceGenerator
19:41:53,359 DEBUG AbstractSaveEventListener:132 - saving [core.Antwort#12]
19:41:53,359 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,359 DEBUG Cascades:525 - id unsaved-value: 0
19:41:53,359 DEBUG AbstractSaveEventListener:408 - transient instance of: core.Antwort
19:41:53,359 DEBUG DefaultSaveOrUpdateEventListener:159 - saving transient instance
19:41:53,359 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,359 DEBUG SQL:310 - select hibernate_sequence.nextval from dual
Hibernate: select hibernate_sequence.nextval from dual
19:41:53,359 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,390 DEBUG SequenceGenerator:87 - Sequence identifier generated: 13
19:41:53,390 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,390 DEBUG AbstractBatcher:392 - closing statement
19:41:53,390 DEBUG AbstractSaveEventListener:89 - generated identifier: 13, using strategy: org.hibernate.id.SequenceGenerator
19:41:53,390 DEBUG AbstractSaveEventListener:132 - saving [core.Antwort#13]
19:41:53,390 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,390 DEBUG Cascades:525 - id unsaved-value: 0
19:41:53,390 DEBUG AbstractSaveEventListener:408 - transient instance of: core.Antwort
19:41:53,390 DEBUG DefaultSaveOrUpdateEventListener:159 - saving transient instance
19:41:53,390 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,390 DEBUG SQL:310 - select hibernate_sequence.nextval from dual
Hibernate: select hibernate_sequence.nextval from dual
19:41:53,390 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,406 DEBUG SequenceGenerator:87 - Sequence identifier generated: 14
19:41:53,406 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,406 DEBUG AbstractBatcher:392 - closing statement
19:41:53,406 DEBUG AbstractSaveEventListener:89 - generated identifier: 14, using strategy: org.hibernate.id.SequenceGenerator
19:41:53,421 DEBUG AbstractSaveEventListener:132 - saving [core.Antwort#14]
19:41:53,421 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,421 DEBUG Cascades:525 - id unsaved-value: 0
19:41:53,421 DEBUG AbstractSaveEventListener:408 - transient instance of: core.Antwort
19:41:53,421 DEBUG DefaultSaveOrUpdateEventListener:159 - saving transient instance
19:41:53,421 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,421 DEBUG SQL:310 - select hibernate_sequence.nextval from dual
Hibernate: select hibernate_sequence.nextval from dual
19:41:53,421 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,625 DEBUG SequenceGenerator:87 - Sequence identifier generated: 15
19:41:53,625 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,625 DEBUG AbstractBatcher:392 - closing statement
19:41:53,625 DEBUG AbstractSaveEventListener:89 - generated identifier: 15, using strategy: org.hibernate.id.SequenceGenerator
19:41:53,625 DEBUG AbstractSaveEventListener:132 - saving [core.Antwort#15]
19:41:53,625 DEBUG Cascades:873 - done cascade ACTION_SAVE_UPDATE for collection: core.Frage.antwortenListe
19:41:53,640 DEBUG Cascades:831 - done processing cascade ACTION_SAVE_UPDATE for: core.Frage
19:41:53,640 DEBUG JDBCTransaction:83 - commit
19:41:53,640 DEBUG SessionImpl:308 - automatically flushing session
19:41:53,640 DEBUG AbstractFlushingEventListener:52 - flushing session
19:41:53,640 DEBUG AbstractFlushingEventListener:102 - processing flush-time cascades
19:41:53,640 DEBUG Cascades:806 - processing cascade ACTION_SAVE_UPDATE for: core.Frage
19:41:53,640 DEBUG Cascades:855 - cascade ACTION_SAVE_UPDATE for collection: core.Frage.antwortenListe
19:41:53,640 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,640 DEBUG AbstractSaveEventListener:392 - persistent instance of: core.Antwort
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:103 - ignoring persistent instance
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:140 - object already associated with session: [core.Antwort#12]
19:41:53,640 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,640 DEBUG AbstractSaveEventListener:392 - persistent instance of: core.Antwort
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:103 - ignoring persistent instance
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:140 - object already associated with session: [core.Antwort#13]
19:41:53,640 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,640 DEBUG AbstractSaveEventListener:392 - persistent instance of: core.Antwort
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:103 - ignoring persistent instance
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:140 - object already associated with session: [core.Antwort#14]
19:41:53,640 DEBUG Cascades:152 - cascading to saveOrUpdate: core.Antwort
19:41:53,640 DEBUG AbstractSaveEventListener:392 - persistent instance of: core.Antwort
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:103 - ignoring persistent instance
19:41:53,640 DEBUG DefaultSaveOrUpdateEventListener:140 - object already associated with session: [core.Antwort#15]
19:41:53,640 DEBUG Cascades:873 - done cascade ACTION_SAVE_UPDATE for collection: core.Frage.antwortenListe
19:41:53,640 DEBUG Cascades:831 - done processing cascade ACTION_SAVE_UPDATE for: core.Frage
19:41:53,640 DEBUG AbstractFlushingEventListener:150 - dirty checking collections
19:41:53,640 DEBUG AbstractFlushingEventListener:167 - Flushing entities and processing referenced collections
19:41:53,656 DEBUG Collections:140 - Collection found: [core.Frage.antwortenListe#11], was: [<unreferenced>] (initialized)
19:41:53,656 DEBUG AbstractFlushingEventListener:203 - Processing unreferenced collections
19:41:53,656 DEBUG AbstractFlushingEventListener:217 - Scheduling collection removes/(re)creates/updates
19:41:53,656 DEBUG AbstractFlushingEventListener:79 - Flushed: 5 insertions, 0 updates, 0 deletions to 5 objects
19:41:53,656 DEBUG AbstractFlushingEventListener:85 - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections
19:41:53,656 DEBUG Printer:83 - listing entities:
19:41:53,656 DEBUG Printer:90 - core.Antwort{anmerkung=sadfasd, antwortText=asdfsarrbd, richtig=false, id=13}
19:41:53,656 DEBUG Printer:90 - core.Antwort{anmerkung=, antwortText=asdfsdsfdd, richtig=false, id=12}
19:41:53,656 DEBUG Printer:90 - core.Antwort{anmerkung=, antwortText=sdfsdadfsd, richtig=false, id=15}
19:41:53,656 DEBUG Printer:90 - core.Antwort{anmerkung=, antwortText=Richtig, richtig=true, id=14}
19:41:53,656 DEBUG Printer:90 - core.Frage{titel=was ist wohl die antwort, antwortenListe=[core.Antwort#12, core.Antwort#13, core.Antwort#14, core.Antwort#15], momentaneAntwort=-1, bildLink=null, myId=3, id=11}
19:41:53,656 DEBUG AbstractFlushingEventListener:267 - executing flush
19:41:53,656 DEBUG UpdateTimestampsCache:51 - Pre-invalidating space [Frage]
19:41:53,656 DEBUG Cache:878 - Frage now: 1114796513656
19:41:53,656 DEBUG Cache:879 - Frage Creation Time: 1114796513656 Next To Last Access Time: 0
19:41:53,656 DEBUG Cache:881 - Frage mostRecentTime: 1114796513656
19:41:53,656 DEBUG Cache:882 - Frage Age to Idle: 120000 Age Idled: 0
19:41:53,656 DEBUG Cache:906 - hibernate.test.org.hibernate.cache.UpdateTimestampsCache: Is element with key Frage expired?: false
19:41:53,656 DEBUG BasicEntityPersister:1834 - Inserting entity: [core.Frage#11]
19:41:53,656 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,656 DEBUG SQL:310 - insert into Frage (myId, titel, momentaneAntwort, bildLink, id) values (?, ?, ?, ?, ?)
19:41:53,656 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,656 DEBUG BasicEntityPersister:1621 - Dehydrating entity: [core.Frage#11]
19:41:53,671 DEBUG IntegerType:59 - binding '3' to parameter: 1
19:41:53,671 DEBUG StringType:59 - binding 'was ist wohl die antwort' to parameter: 2
19:41:53,671 DEBUG IntegerType:59 - binding '-1' to parameter: 3
19:41:53,671 DEBUG StringType:52 - binding null to parameter: 4
19:41:53,671 DEBUG LongType:59 - binding '11' to parameter: 5
19:41:53,734 DEBUG UpdateTimestampsCache:51 - Pre-invalidating space [Antwort]
19:41:53,734 DEBUG Cache:878 - Frage now: 1114796513734
19:41:53,750 DEBUG Cache:879 - Frage Creation Time: 1114796513656 Next To Last Access Time: 0
19:41:53,750 DEBUG Cache:881 - Frage mostRecentTime: 1114796513656
19:41:53,750 DEBUG Cache:882 - Frage Age to Idle: 120000 Age Idled: 78
19:41:53,750 DEBUG Cache:906 - hibernate.test.org.hibernate.cache.UpdateTimestampsCache: Is element with key Frage expired?: false
19:41:53,750 DEBUG BasicEntityPersister:1834 - Inserting entity: [core.Antwort#12]
19:41:53,750 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,750 DEBUG AbstractBatcher:392 - closing statement
19:41:53,750 DEBUG AbstractBatcher:276 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
19:41:53,750 DEBUG SQL:310 - insert into Antwort (antwortText, anmerkung, richtig, id) values (?, ?, ?, ?)
19:41:53,750 DEBUG AbstractBatcher:364 - preparing statement
19:41:53,750 DEBUG BasicEntityPersister:1621 - Dehydrating entity: [core.Antwort#12]
19:41:53,750 DEBUG StringType:59 - binding 'asdfsdsfdd' to parameter: 1
19:41:53,750 DEBUG StringType:59 - binding '' to parameter: 2
19:41:53,750 DEBUG BooleanType:59 - binding 'false' to parameter: 3
19:41:53,750 DEBUG LongType:59 - binding '12' to parameter: 4
19:41:53,765 DEBUG AbstractBatcher:284 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
19:41:53,765 DEBUG AbstractBatcher:392 - closing statement
19:41:53,765 DEBUG JDBCExceptionReporter:49 - could not insert: [core.Antwort] [insert into Antwort (antwortText, anmerkung, richtig, id) values (?, ?, ?, ?)]