Citation:
21:16:30,817 INFO Version:15 - Hibernate EntityManager 3.2.0.CR3
21:16:30,831 INFO Version:15 - Hibernate Annotations 3.2.0.CR3
21:16:30,837 INFO Environment:500 - Hibernate 3.2.0.cr5
21:16:30,840 INFO Environment:533 - hibernate.properties not found
21:16:30,841 INFO Environment:667 - Bytecode provider name : cglib
21:16:30,844 INFO Environment:584 - using JDK 1.4 java.sql.Timestamp handling
21:16:30,971 DEBUG NamingHelper:30 - Not Ejb3Configuration to JNDI, no JNDI name configured
21:16:30,973 DEBUG Configuration:1209 - Preparing to build session factory with filters : {}
21:16:30,973 DEBUG AnnotationConfiguration:235 - Execute first pass mapping processing
21:16:31,013 DEBUG AnnotationConfiguration:435 - Process hbm files
21:16:31,013 DEBUG AnnotationConfiguration:443 - Process annotated classes
21:16:31,016 INFO AnnotationBinder:387 - Binding entity from annotated class: org.jtheque.tests.techno.jpa.TestEntity
21:16:31,032 DEBUG Ejb3Column:161 - Binding column DTYPE unique false
21:16:31,045 DEBUG EntityBinder:212 - Import with entity name=TestEntity
21:16:31,050 INFO EntityBinder:340 - Bind entity org.jtheque.tests.techno.jpa.TestEntity on table TEST
21:16:31,055 DEBUG AnnotationBinder:940 - Processing org.jtheque.tests.techno.jpa.TestEntity property annotation
21:16:31,074 DEBUG AnnotationBinder:1032 - Processing annotations of org.jtheque.tests.techno.jpa.TestEntity.id
21:16:31,076 DEBUG Ejb3Column:161 - Binding column ID unique false
21:16:31,076 DEBUG AnnotationBinder:1153 - id is an id
21:16:31,080 DEBUG SimpleValueBinder:220 - building SimpleValue for id
21:16:31,083 DEBUG PropertyBinder:122 - Building property id
21:16:31,087 DEBUG PropertyBinder:155 - Cascading id with null
21:16:31,087 DEBUG AnnotationBinder:1186 - Bind @Id on id
21:16:31,087 DEBUG AnnotationBinder:1032 - Processing annotations of org.jtheque.tests.techno.jpa.TestEntity.title
21:16:31,087 DEBUG Ejb3Column:161 - Binding column TITLE unique true
21:16:31,088 DEBUG PropertyBinder:102 - binding property title with lazy=false
21:16:31,088 DEBUG SimpleValueBinder:220 - building SimpleValue for title
21:16:31,088 DEBUG PropertyBinder:122 - Building property title
21:16:31,088 DEBUG PropertyBinder:155 - Cascading title with null
21:16:31,088 DEBUG AnnotationBinder:1032 - Processing annotations of org.jtheque.tests.techno.jpa.TestEntity.version
21:16:31,088 DEBUG Ejb3Column:161 - Binding column VERSION unique false
21:16:31,088 DEBUG AnnotationBinder:1203 - version is a version property
21:16:31,088 DEBUG PropertyBinder:102 - binding property version with lazy=false
21:16:31,089 DEBUG SimpleValueBinder:220 - building SimpleValue for version
21:16:31,089 DEBUG PropertyBinder:122 - Building property version
21:16:31,089 DEBUG PropertyBinder:155 - Cascading version with null
21:16:31,089 DEBUG AnnotationBinder:1223 - Version name: version, unsavedValue: undefined
21:16:31,091 DEBUG AnnotationConfiguration:329 - processing manytoone fk mappings
21:16:31,094 DEBUG Configuration:1044 - processing extends queue
21:16:31,094 DEBUG Configuration:1048 - processing collection mappings
21:16:31,094 DEBUG Configuration:1059 - processing native query and ResultSetMapping mappings
21:16:31,094 DEBUG Configuration:1067 - processing association property references
21:16:31,094 DEBUG Configuration:1089 - processing foreign key constraints
21:16:31,105 TRACE ClassValidator:160 - ResourceBundle ValidatorMessages not found in thread context classloader
21:16:31,105 DEBUG ClassValidator:170 - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
21:16:31,171 INFO DriverManagerConnectionProvider:41 - Using Hibernate built-in connection pool (not for production use!)
21:16:31,171 INFO DriverManagerConnectionProvider:42 - Hibernate connection pool size: 20
21:16:31,171 INFO DriverManagerConnectionProvider:45 - autocommit mode: false
21:16:31,173 INFO DriverManagerConnectionProvider:80 - using driver: org.hsqldb.jdbcDriver at URL: jdbc:hsqldb:hsql://localhost/dvd
21:16:31,174 INFO DriverManagerConnectionProvider:83 - connection properties: {user=sa, password=}
21:16:31,174 TRACE DriverManagerConnectionProvider:93 - total checked-out connections: 0
21:16:31,174 DEBUG DriverManagerConnectionProvider:109 - opening new JDBC connection
21:16:31,207 DEBUG DriverManagerConnectionProvider:115 - created connection to: jdbc:hsqldb:hsql://localhost/dvd, Isolation Level: 2
21:16:31,228 INFO SettingsFactory:81 - RDBMS: HSQL Database Engine, version: 1.8.0
21:16:31,228 INFO SettingsFactory:82 - JDBC driver: HSQL Database Engine Driver, version: 1.8.0
21:16:31,228 TRACE DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
21:16:31,246 INFO Dialect:141 - Using dialect: org.hibernate.dialect.HSQLDialect
21:16:31,251 INFO TransactionFactoryFactory:31 - Using default transaction strategy (direct JDBC transactions)
21:16:31,253 INFO TransactionManagerLookupFactory:33 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
21:16:31,253 INFO SettingsFactory:134 - Automatic flush during beforeCompletion(): disabled
21:16:31,253 INFO SettingsFactory:138 - Automatic session close at end of transaction: disabled
21:16:31,253 INFO SettingsFactory:145 - JDBC batch size: 15
21:16:31,253 INFO SettingsFactory:148 - JDBC batch updates for versioned data: disabled
21:16:31,254 INFO SettingsFactory:153 - Scrollable result sets: enabled
21:16:31,254 DEBUG SettingsFactory:157 - Wrap result sets: disabled
21:16:31,254 INFO SettingsFactory:161 - JDBC3 getGeneratedKeys(): disabled
21:16:31,255 INFO SettingsFactory:169 - Connection release mode: auto
21:16:31,255 INFO SettingsFactory:196 - Default batch fetch size: 1
21:16:31,255 INFO SettingsFactory:200 - Generate SQL with comments: disabled
21:16:31,255 INFO SettingsFactory:204 - Order SQL updates by primary key: disabled
21:16:31,256 INFO SettingsFactory:369 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
21:16:31,258 INFO ASTQueryTranslatorFactory:24 - Using ASTQueryTranslatorFactory
21:16:31,258 INFO SettingsFactory:212 - Query language substitutions: {}
21:16:31,258 INFO SettingsFactory:217 - JPA-QL strict compliance: disabled
21:16:31,258 INFO SettingsFactory:222 - Second-level cache: enabled
21:16:31,259 INFO SettingsFactory:226 - Query cache: disabled
21:16:31,259 INFO SettingsFactory:356 - Cache provider: org.hibernate.cache.NoCacheProvider
21:16:31,259 INFO SettingsFactory:241 - Optimize cache for minimal puts: disabled
21:16:31,259 INFO SettingsFactory:250 - Structured second-level cache entries: disabled
21:16:31,261 TRACE SQLExceptionConverterFactory:52 - Using dialect defined converter
21:16:31,265 INFO SettingsFactory:270 - Echoing all SQL to stdout
21:16:31,266 INFO SettingsFactory:277 - Statistics: disabled
21:16:31,266 INFO SettingsFactory:281 - Deleted entity synthetic identifier rollback: disabled
21:16:31,266 INFO SettingsFactory:296 - Default entity-mode: pojo
21:16:31,287 TRACE ClassValidator:160 - ResourceBundle ValidatorMessages not found in thread context classloader
21:16:31,287 DEBUG ClassValidator:170 - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
21:16:31,296 INFO SessionFactoryImpl:161 - building session factory
21:16:31,297 DEBUG SessionFactoryImpl:173 - Session factory constructed with filter configurations : {}
21:16:31,298 DEBUG SessionFactoryImpl:177 - instantiating session factory with properties: {...}
21:16:31,495 DEBUG AbstractEntityPersister:2673 - Static SQL for entity: org.jtheque.tests.techno.jpa.TestEntity
21:16:31,496 DEBUG AbstractEntityPersister:2678 - Version select: select VERSION from TEST where ID =?
21:16:31,496 DEBUG AbstractEntityPersister:2681 - Snapshot select: select testentity_.ID, testentity_.TITLE as TITLE0_, testentity_.VERSION as VERSION0_ from TEST testentity_ where testentity_.ID=?
21:16:31,496 DEBUG AbstractEntityPersister:2684 - Insert 0: insert into TEST (TITLE, VERSION, ID) values (?, ?, ?)
21:16:31,496 DEBUG AbstractEntityPersister:2685 - Update 0: update TEST set TITLE=?, VERSION=? where ID=? and VERSION=?
21:16:31,496 DEBUG AbstractEntityPersister:2686 - Delete 0: delete from TEST where ID=? and VERSION=?
21:16:31,496 DEBUG AbstractEntityPersister:2690 - Identity insert: insert into TEST (ID, TITLE, VERSION) values (null, ?, ?)
21:16:31,514 DEBUG EntityLoader:79 - Static select for entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,514 DEBUG EntityLoader:79 - Static select for entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,514 DEBUG EntityLoader:79 - Static select for entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,515 DEBUG EntityLoader:79 - Static select for entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,515 DEBUG EntityLoader:79 - Static select for entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,523 DEBUG EntityLoader:34 - Static select for action ACTION_MERGE on entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,523 DEBUG EntityLoader:34 - Static select for action ACTION_REFRESH on entity org.jtheque.tests.techno.jpa.TestEntity: select testentity0_.ID as ID0_0_, testentity0_.TITLE as TITLE0_0_, testentity0_.VERSION as VERSION0_0_ from TEST testentity0_ where testentity0_.ID=?
21:16:31,525 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory
21:16:31,526 DEBUG SessionFactoryObjectFactory:76 - registered: 4028818519de90070119de9008a30000 (unnamed)
21:16:31,527 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
21:16:31,527 DEBUG SessionFactoryImpl:308 - instantiated session factory
21:16:31,530 INFO SchemaUpdate:115 - Running hbm2ddl schema update
21:16:31,530 INFO SchemaUpdate:126 - fetching database metadata
21:16:31,530 TRACE DriverManagerConnectionProvider:93 - total checked-out connections: 0
21:16:31,530 TRACE DriverManagerConnectionProvider:99 - using pooled JDBC connection, pool size: 0
21:16:31,540 INFO SchemaUpdate:138 - updating schema
21:16:31,540 DEBUG AnnotationConfiguration:235 - Execute first pass mapping processing
21:16:31,540 DEBUG AnnotationConfiguration:435 - Process hbm files
21:16:31,540 DEBUG AnnotationConfiguration:443 - Process annotated classes
21:16:31,540 DEBUG AnnotationConfiguration:329 - processing manytoone fk mappings
21:16:31,541 DEBUG Configuration:1044 - processing extends queue
21:16:31,541 DEBUG Configuration:1048 - processing collection mappings
21:16:31,542 DEBUG Configuration:1059 - processing native query and ResultSetMapping mappings
21:16:31,542 DEBUG Configuration:1067 - processing association property references
21:16:31,542 DEBUG Configuration:1089 - processing foreign key constraints
21:16:31,542 TRACE ClassValidator:160 - ResourceBundle ValidatorMessages not found in thread context classloader
21:16:31,542 DEBUG ClassValidator:170 - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
21:16:31,567 INFO TableMetadata:40 - table found: PUBLIC.TEST
21:16:31,567 INFO TableMetadata:41 - columns: [id, title, version]
21:16:31,567 INFO TableMetadata:43 - foreign keys: []
21:16:31,567 INFO TableMetadata:44 - indexes: [sys_idx_sys_ct_71_74, sys_idx_72]
21:16:31,567 INFO SchemaUpdate:160 - schema update complete
21:16:31,574 TRACE DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
21:16:31,574 DEBUG SessionFactoryImpl:390 - Checking 0 named HQL queries
21:16:31,574 DEBUG SessionFactoryImpl:410 - Checking 0 named SQL queries
21:16:31,620 DEBUG SessionImpl:220 - opened session at timestamp: 12106197915
21:16:31,623 TRACE VersionValue:44 - version unsaved-value strategy UNDEFINED
21:16:31,623 TRACE IdentifierValue:104 - id unsaved-value: 0
21:16:31,623 TRACE AbstractSaveEventListener:489 - transient instance of: org.jtheque.tests.techno.jpa.TestEntity
21:16:31,623 TRACE DefaultPersistEventListener:124 - saving transient instance
21:16:31,626 TRACE AbstractSaveEventListener:152 - saving [org.jtheque.tests.techno.jpa.TestEntity#<null>]
java.lang.NullPointerException
at org.hibernate.ejb.event.EJB3PersistEventListener.invokeSaveLifecycle(EJB3PersistEventListener.java:31)
at org.hibernate.event.def.AbstractSaveEventListener.performSave(AbstractSaveEventListener.java:176)
at org.hibernate.event.def.AbstractSaveEventListener.saveWithGeneratedId(AbstractSaveEventListener.java:108)
at org.hibernate.event.def.DefaultPersistEventListener.entityIsTransient(DefaultPersistEventListener.java:131)
at org.hibernate.event.def.DefaultPersistEventListener.onPersist(DefaultPersistEventListener.java:87)
at org.hibernate.event.def.DefaultPersistEventListener.onPersist(DefaultPersistEventListener.java:38)
at org.hibernate.impl.SessionImpl.firePersist(SessionImpl.java:618)
at org.hibernate.impl.SessionImpl.persist(SessionImpl.java:592)
at org.hibernate.impl.SessionImpl.persist(SessionImpl.java:596)
at org.hibernate.ejb.AbstractEntityManagerImpl.persist(AbstractEntityManagerImpl.java:212)
at org.jtheque.tests.techno.jpa.JPATest.main(JPATest.java:59)
21:16:31,629 TRACE SessionImpl:273 - closing session
21:16:31,629 TRACE ConnectionManager:369 - connection already null in cleanup : no action
21:16:31,629 INFO SessionFactoryImpl:767 - closing
21:16:31,630 INFO DriverManagerConnectionProvider:147 - cleaning up connection pool: jdbc:hsqldb:hsql://localhost/dvd
Je pense que ça doit venir de la configuration de JPA/Hibernate, mais je ne voiis pas ce que j'ai fait faux :(