This is the debug
Code:
log4j: Threshold ="null".
log4j: Retreiving an instance of org.apache.log4j.Logger.
log4j: Setting [net.sf] additivity to [false].
log4j: Level value for net.sf is [error].
log4j: net.sf level set to ERROR
log4j: Class name: [org.apache.log4j.ConsoleAppender]
log4j: Parsing layout of class: "org.apache.log4j.PatternLayout"
log4j: Setting property [conversionPattern] to [%-4r %-5p [%t] %37c %3x - %m%n].
log4j: Adding appender named [STDOUT] to category [net.sf].
log4j: Retreiving an instance of org.apache.log4j.Logger.
log4j: Setting [eg] additivity to [false].
log4j: Level value for eg is [debug].
log4j: eg level set to DEBUG
log4j: Adding appender named [STDOUT] to category [eg].
log4j: Level value for root is [debug].
log4j: root level set to DEBUG
log4j: Adding appender named [STDOUT] to category [root].
0 DEBUG [main] org.dao.NamesDAO - saving Names instance
31 INFO [main] org.hibernate.cfg.annotations.Version - Hibernate Annotations 3.3.0.GA
31 INFO [main] org.hibernate.cfg.Environment - Hibernate 3.2.5
46 INFO [main] org.hibernate.cfg.Environment - hibernate.properties not found
46 INFO [main] org.hibernate.cfg.Environment - Bytecode provider name : cglib
46 INFO [main] org.hibernate.cfg.Environment - using JDK 1.4 java.sql.Timestamp handling
140 INFO [main] org.hibernate.cfg.Configuration - configuring from resource: /hibernate.cfg.xml
140 INFO [main] org.hibernate.cfg.Configuration - Configuration resource: /hibernate.cfg.xml
203 DEBUG [main] org.hibernate.util.DTDEntityResolver - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]
203 DEBUG [main] org.hibernate.util.DTDEntityResolver - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
203 DEBUG [main] org.hibernate.util.DTDEntityResolver - located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath
234 DEBUG [main] org.hibernate.cfg.Configuration - connection.username=sa
234 DEBUG [main] org.hibernate.cfg.Configuration - connection.url=jdbc:hsqldb:hsql://localhost:1666
234 DEBUG [main] org.hibernate.cfg.Configuration - dialect=org.hibernate.dialect.HSQLDialect
234 DEBUG [main] org.hibernate.cfg.Configuration - show_sql=true
234 DEBUG [main] org.hibernate.cfg.Configuration - use_outer_join=false
234 DEBUG [main] org.hibernate.cfg.Configuration - myeclipse.connection.profile=HsqlServer
234 DEBUG [main] org.hibernate.cfg.Configuration - connection.driver_class=org.hsqldb.jdbcDriver
234 DEBUG [main] org.hibernate.cfg.Configuration - mappingLocations=classpath*:org/dao/hib/xml/**/*.hbm.xml
234 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - null<-org.dom4j.tree.DefaultAttribute@26dbec [Attribute: name class value "org.domain.Names"]
250 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - null<-org.dom4j.tree.DefaultAttribute@2200d5 [Attribute: name class value "org.domain.OrderItems"]
250 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - null<-org.dom4j.tree.DefaultAttribute@5dd582 [Attribute: name class value "org.domain.Orders"]
250 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - null<-org.dom4j.tree.DefaultAttribute@153c375 [Attribute: name resource value "org/dao/hib/xml/Names.hbm.xml"]
250 INFO [main] org.hibernate.cfg.Configuration - Reading mappings from resource : org/dao/hib/xml/Names.hbm.xml
453 DEBUG [main] org.hibernate.util.DTDEntityResolver - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
453 DEBUG [main] org.hibernate.util.DTDEntityResolver - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
453 DEBUG [main] org.hibernate.util.DTDEntityResolver - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
500 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - null<-org.dom4j.tree.DefaultAttribute@1a001ff [Attribute: name resource value "org/dao/hib/xml/OrderItems.hbm.xml"]
500 INFO [main] org.hibernate.cfg.Configuration - Reading mappings from resource : org/dao/hib/xml/OrderItems.hbm.xml
500 DEBUG [main] org.hibernate.util.DTDEntityResolver - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
500 DEBUG [main] org.hibernate.util.DTDEntityResolver - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
500 DEBUG [main] org.hibernate.util.DTDEntityResolver - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
531 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - null<-org.dom4j.tree.DefaultAttribute@90c06f [Attribute: name resource value "org/dao/hib/xml/Orders.hbm.xml"]
531 INFO [main] org.hibernate.cfg.Configuration - Reading mappings from resource : org/dao/hib/xml/Orders.hbm.xml
531 DEBUG [main] org.hibernate.util.DTDEntityResolver - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd]
531 DEBUG [main] org.hibernate.util.DTDEntityResolver - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
531 DEBUG [main] org.hibernate.util.DTDEntityResolver - located [http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd] in classpath
609 INFO [main] org.hibernate.cfg.Configuration - Configured SessionFactory: null
609 DEBUG [main] org.hibernate.cfg.Configuration - properties: {mappingLocations=classpath*:org/dao/hib/xml/**/*.hbm.xml, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\bin, java.vm.version=1.5.0_11-b03, hibernate.connection.username=sa, 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, use_outer_join=false, user.country=US, sun.java.launcher=SUN_STANDARD, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\openxava_311\workspace\DataModel, java.runtime.version=1.5.0_11-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\DG03118\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, hibernate.mappingLocations=classpath*:org/dao/hib/xml/**/*.hbm.xml, sun.jnu.encoding=Cp1252, java.library.path=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:/Program Files/Genuitec/Common/binary/com.sun.java.jre.win32.x86_1.5.0.011/bin/client;C:/Program Files/Genuitec/Common/binary/com.sun.java.jre.win32.x86_1.5.0.011/bin;C:\Program Files\Compuware\File-AID_CS\Dme;C:\Program Files\Compuware\File-AID_CS;C:\Program Files\Java\jdk1.5.0_15\bin;C:\AGILE\ANT\bin;C:\oracle\product\10.2.0\db_1\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Novell\ZENworks\;C:\WINDOWS\system32\nls;C:\WINDOWS\system32\nls\english;C:\Program Files\Attachmate\E!E2K\;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\Pointsec\Pointsec Media Encryption\Program\;C:\Program Files\Common Files\Compuware;C:\Program Files\Compuware\TestPartner\;C:\Program Files\Compuware\TestPartner\AppExtDlls;c:\grails\bin;C:\Program Files\Apache Software Foundation\apache-maven-2.0.9\bin;C:\Program\Files\JBoss\jboss-4.2.2\bin;C:\Program Files\Apache Software Foundation\apache-maven-2.0.9\bin;C:\Program Files\Groovy\Groovy-1.5.6\bin, hibernate.myeclipse.connection.profile=HsqlServer, java.specification.name=Java Platform API Specification, java.class.version=49.0, sun.management.compiler=HotSpot Client Compiler, os.version=5.1, user.home=C:\Documents and Settings\DG03118, user.timezone=, connection.username=sa, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.5, hibernate.connection.driver_class=org.hsqldb.jdbcDriver, myeclipse.connection.profile=HsqlServer, show_sql=true, user.name=DG03118, java.class.path=C:\openxava_311\workspace\DataModel\bin;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-annotations.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-commons-annotations.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-entitymanager.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-validator.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\ejb3-persistence.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\jboss-archive-browsing.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\antlr-2.7.6.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\asm.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\asm-attrs.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\cglib-2.1.3.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\commons-collections-2.1.1.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\commons-logging-1.0.4.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\dom4j-1.6.1.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\ehcache-1.2.3.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\hibernate3.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\jaas.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\jaxen-1.1-beta-7.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\javassist.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\jdbc2_0-stdext.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\jta.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\log4j-1.2.11.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\xerces-2.6.2.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\xml-apis.jar;C:\openxava_311\workspace\DataModel\lib\hsqldb.jar;C:\Program Files\Genuitec\Common\plugins\org.junit_3.8.2.v20080602-1318\junit.jar;/C:/Program Files/Genuitec/MyEclipse 7.0/configuration/org.eclipse.osgi/bundles/466/1/.cp/;/C:/Program Files/Genuitec/MyEclipse 7.0/configuration/org.eclipse.osgi/bundles/467/1/.cp/, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.HSQLDialect, hibernate.connection.url=jdbc:hsqldb:hsql://localhost:1666, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, java.version=1.5.0_11, java.ext.dirs=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\ext, sun.boot.class.path=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\rt.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\i18n.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\sunrsasign.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\jsse.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\jce.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\charsets.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\classes, java.vendor=Sun Microsystems Inc., connection.driver_class=org.hsqldb.jdbcDriver, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, hibernate.use_outer_join=false, sun.desktop=windows, connection.url=jdbc:hsqldb:hsql://localhost:1666, dialect=org.hibernate.dialect.HSQLDialect, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86}
609 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - Search not present in classpath, ignoring event listener registration
609 DEBUG [main] org.hibernate.cfg.Configuration - Preparing to build session factory with filters : {}
609 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - Execute first pass mapping processing
687 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - Process hbm files
734 INFO [main] org.hibernate.cfg.HbmBinder - Mapping class: org.domain.Names -> NAMES
750 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: nameId -> NAME_ID
750 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: name -> NAME
765 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: orderses
765 INFO [main] org.hibernate.cfg.HbmBinder - Mapping class: org.domain.OrderItems -> ORDER_ITEMS
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: orders -> ORDER_NUMBER
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: itemNumber -> ITEM_NUMBER
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: id -> ORDER_NUMBER, ITEM_NUMBER
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: quantity -> QUANTITY
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: price -> PRICE
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: description -> DESCRIPTION
843 INFO [main] org.hibernate.cfg.HbmBinder - Mapping class: org.domain.Orders -> ORDERS
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: orderNumber -> ORDER_NUMBER
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: names -> NAME_ID
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: orderTotal -> ORDER_TOTAL
843 DEBUG [main] org.hibernate.cfg.HbmBinder - Mapped property: orderItemses
843 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - Process annotated classes
843 DEBUG [main] org.hibernate.cfg.AnnotationConfiguration - processing manytoone fk mappings
843 DEBUG [main] org.hibernate.cfg.Configuration - processing extends queue
843 DEBUG [main] org.hibernate.cfg.Configuration - processing collection mappings
843 DEBUG [main] org.hibernate.cfg.CollectionSecondPass - Second pass for collection: org.domain.Names.orderses
843 INFO [main] org.hibernate.cfg.HbmBinder - Mapping collection: org.domain.Names.orderses -> ORDERS
843 DEBUG [main] org.hibernate.cfg.CollectionSecondPass - Mapped collection key: NAME_ID, one-to-many: org.domain.Orders
843 DEBUG [main] org.hibernate.cfg.CollectionSecondPass - Second pass for collection: org.domain.Orders.orderItemses
843 INFO [main] org.hibernate.cfg.HbmBinder - Mapping collection: org.domain.Orders.orderItemses -> ORDER_ITEMS
843 DEBUG [main] org.hibernate.cfg.CollectionSecondPass - Mapped collection key: ORDER_NUMBER, one-to-many: org.domain.OrderItems
843 DEBUG [main] org.hibernate.cfg.Configuration - processing native query and ResultSetMapping mappings
843 DEBUG [main] org.hibernate.cfg.Configuration - processing association property references
843 DEBUG [main] org.hibernate.cfg.Configuration - processing foreign key constraints
843 DEBUG [main] org.hibernate.cfg.Configuration - resolving reference to class: org.domain.Names
843 DEBUG [main] org.hibernate.cfg.Configuration - resolving reference to class: org.domain.Orders
859 INFO [main] org.hibernate.validator.Version - Hibernate Validator 3.0.0.GA
859 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
859 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
890 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
890 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
890 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
890 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
937 INFO [main] org.hibernate.connection.DriverManagerConnectionProvider - Using Hibernate built-in connection pool (not for production use!)
937 INFO [main] org.hibernate.connection.DriverManagerConnectionProvider - Hibernate connection pool size: 20
937 INFO [main] org.hibernate.connection.DriverManagerConnectionProvider - autocommit mode: false
937 INFO [main] org.hibernate.connection.DriverManagerConnectionProvider - using driver: org.hsqldb.jdbcDriver at URL: jdbc:hsqldb:hsql://localhost:1666
937 INFO [main] org.hibernate.connection.DriverManagerConnectionProvider - connection properties: {user=sa}
937 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
937 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - opening new JDBC connection
1046 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - created connection to: jdbc:hsqldb:hsql://localhost:1666, Isolation Level: 0
1062 INFO [main] org.hibernate.cfg.SettingsFactory - RDBMS: HSQL Database Engine, version: 1.8.0
1062 INFO [main] org.hibernate.cfg.SettingsFactory - JDBC driver: HSQL Database Engine Driver, version: 1.8.0
1062 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - returning connection to pool, pool size: 1
1093 INFO [main] org.hibernate.dialect.Dialect - Using dialect: org.hibernate.dialect.HSQLDialect
1109 INFO [main] org.hibernate.transaction.TransactionFactoryFactory - Using default transaction strategy (direct JDBC transactions)
1109 INFO [main] org.hibernate.transaction.TransactionManagerLookupFactory - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Automatic flush during beforeCompletion(): disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Automatic session close at end of transaction: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - JDBC batch size: 15
1109 INFO [main] org.hibernate.cfg.SettingsFactory - JDBC batch updates for versioned data: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Scrollable result sets: enabled
1109 DEBUG [main] org.hibernate.cfg.SettingsFactory - Wrap result sets: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - JDBC3 getGeneratedKeys(): disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Connection release mode: auto
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Default batch fetch size: 1
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Generate SQL with comments: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Order SQL updates by primary key: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Order SQL inserts for batching: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
1109 INFO [main] org.hibernate.hql.ast.ASTQueryTranslatorFactory - Using ASTQueryTranslatorFactory
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Query language substitutions: {}
1109 INFO [main] org.hibernate.cfg.SettingsFactory - JPA-QL strict compliance: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Second-level cache: enabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Query cache: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Cache provider: org.hibernate.cache.NoCacheProvider
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: disabled
1109 INFO [main] org.hibernate.cfg.SettingsFactory - Structured second-level cache entries: disabled
1109 DEBUG [main] org.hibernate.exception.SQLExceptionConverterFactory - Using dialect defined converter
1125 INFO [main] org.hibernate.cfg.SettingsFactory - Echoing all SQL to stdout
1125 INFO [main] org.hibernate.cfg.SettingsFactory - Statistics: disabled
1125 INFO [main] org.hibernate.cfg.SettingsFactory - Deleted entity synthetic identifier rollback: disabled
1125 INFO [main] org.hibernate.cfg.SettingsFactory - Default entity-mode: pojo
1125 INFO [main] org.hibernate.cfg.SettingsFactory - Named query checking : enabled
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in thread context classloader
1140 DEBUG [main] org.hibernate.validator.ClassValidator - ResourceBundle ValidatorMessages not found in Validator classloader. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
1156 INFO [main] org.hibernate.impl.SessionFactoryImpl - building session factory
1156 DEBUG [main] org.hibernate.impl.SessionFactoryImpl - Session factory constructed with filter configurations : {}
1156 DEBUG [main] org.hibernate.impl.SessionFactoryImpl - instantiating session factory with properties: {mappingLocations=classpath*:org/dao/hib/xml/**/*.hbm.xml, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\bin, java.vm.version=1.5.0_11-b03, hibernate.connection.username=sa, 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=US, sun.java.launcher=SUN_STANDARD, sun.os.patch.level=Service Pack 2, use_outer_join=false, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\openxava_311\workspace\DataModel, java.runtime.version=1.5.0_11-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\DG03118\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, hibernate.mappingLocations=classpath*:org/dao/hib/xml/**/*.hbm.xml, sun.jnu.encoding=Cp1252, java.library.path=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:/Program Files/Genuitec/Common/binary/com.sun.java.jre.win32.x86_1.5.0.011/bin/client;C:/Program Files/Genuitec/Common/binary/com.sun.java.jre.win32.x86_1.5.0.011/bin;C:\Program Files\Compuware\File-AID_CS\Dme;C:\Program Files\Compuware\File-AID_CS;C:\Program Files\Java\jdk1.5.0_15\bin;C:\AGILE\ANT\bin;C:\oracle\product\10.2.0\db_1\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Novell\ZENworks\;C:\WINDOWS\system32\nls;C:\WINDOWS\system32\nls\english;C:\Program Files\Attachmate\E!E2K\;C:\Program Files\Microsoft SQL Server\80\Tools\Binn\;C:\Program Files\MySQL\MySQL Server 5.0\bin;C:\Program Files\Pointsec\Pointsec Media Encryption\Program\;C:\Program Files\Common Files\Compuware;C:\Program Files\Compuware\TestPartner\;C:\Program Files\Compuware\TestPartner\AppExtDlls;c:\grails\bin;C:\Program Files\Apache Software Foundation\apache-maven-2.0.9\bin;C:\Program\Files\JBoss\jboss-4.2.2\bin;C:\Program Files\Apache Software Foundation\apache-maven-2.0.9\bin;C:\Program Files\Groovy\Groovy-1.5.6\bin, hibernate.myeclipse.connection.profile=HsqlServer, java.specification.name=Java Platform API Specification, java.class.version=49.0, sun.management.compiler=HotSpot Client Compiler, os.version=5.1, user.home=C:\Documents and Settings\DG03118, user.timezone=, java.awt.printerjob=sun.awt.windows.WPrinterJob, connection.username=sa, java.specification.version=1.5, file.encoding=Cp1252, myeclipse.connection.profile=HsqlServer, hibernate.connection.driver_class=org.hsqldb.jdbcDriver, show_sql=true, java.class.path=C:\openxava_311\workspace\DataModel\bin;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-annotations.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-commons-annotations.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-entitymanager.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\hibernate-validator.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\ejb3-persistence.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\entitymanager\jboss-archive-browsing.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\antlr-2.7.6.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\asm.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\asm-attrs.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\cglib-2.1.3.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\commons-collections-2.1.1.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\commons-logging-1.0.4.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\dom4j-1.6.1.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\ehcache-1.2.3.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\hibernate3.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\jaas.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\jaxen-1.1-beta-7.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.2\lib\javassist.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\jdbc2_0-stdext.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\jta.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.1\lib\log4j-1.2.11.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\xerces-2.6.2.jar;C:\Program Files\Genuitec\Common\plugins\com.genuitec.org.hibernate.eclipse_3.2.4.CR1-zmyeclipse7102009030516\myeclipse-data\3.0\lib\xml-apis.jar;C:\openxava_311\workspace\DataModel\lib\hsqldb.jar;C:\Program Files\Genuitec\Common\plugins\org.junit_3.8.2.v20080602-1318\junit.jar;/C:/Program Files/Genuitec/MyEclipse 7.0/configuration/org.eclipse.osgi/bundles/466/1/.cp/;/C:/Program Files/Genuitec/MyEclipse 7.0/configuration/org.eclipse.osgi/bundles/467/1/.cp/, user.name=DG03118, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011, hibernate.connection.url=jdbc:hsqldb:hsql://localhost:1666, hibernate.dialect=org.hibernate.dialect.HSQLDialect, java.specification.vendor=Sun Microsystems Inc., user.language=en, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, java.version=1.5.0_11, java.ext.dirs=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\ext, sun.boot.class.path=C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\rt.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\i18n.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\sunrsasign.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\jsse.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\jce.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\lib\charsets.jar;C:\Program Files\Genuitec\Common\binary\com.sun.java.jre.win32.x86_1.5.0.011\classes, java.vendor=Sun Microsystems Inc., file.separator=\, connection.driver_class=org.hsqldb.jdbcDriver, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, hibernate.use_outer_join=false, sun.desktop=windows, connection.url=jdbc:hsqldb:hsql://localhost:1666, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86, dialect=org.hibernate.dialect.HSQLDialect}
1468 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: org.domain.Orders
1468 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Version select: select ORDER_NUMBER from PUBLIC.ORDERS where ORDER_NUMBER =?
1468 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select orders_.ORDER_NUMBER, orders_.NAME_ID as NAME2_2_, orders_.ORDER_TOTAL as ORDER3_2_ from PUBLIC.ORDERS orders_ where orders_.ORDER_NUMBER=?
1468 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into PUBLIC.ORDERS (NAME_ID, ORDER_TOTAL, ORDER_NUMBER) values (?, ?, ?)
1468 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update PUBLIC.ORDERS set NAME_ID=?, ORDER_TOTAL=? where ORDER_NUMBER=?
1468 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from PUBLIC.ORDERS where ORDER_NUMBER=?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: org.domain.Names
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Version select: select NAME_ID from PUBLIC.NAMES where NAME_ID =?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select names_.NAME_ID, names_.NAME as NAME0_ from PUBLIC.NAMES names_ where names_.NAME_ID=?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into PUBLIC.NAMES (NAME, NAME_ID) values (?, ?)
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update PUBLIC.NAMES set NAME=? where NAME_ID=?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from PUBLIC.NAMES where NAME_ID=?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Static SQL for entity: org.domain.OrderItems
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Version select: select ORDER_NUMBER, ITEM_NUMBER from PUBLIC.ORDER_ITEMS where ORDER_NUMBER =? and ITEM_NUMBER =?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Snapshot select: select orderitems_.ORDER_NUMBER, orderitems_.ITEM_NUMBER, orderitems_.QUANTITY as QUANTITY1_, orderitems_.PRICE as PRICE1_, orderitems_.DESCRIPTION as DESCRIPT5_1_ from PUBLIC.ORDER_ITEMS orderitems_ where orderitems_.ORDER_NUMBER=? and orderitems_.ITEM_NUMBER=?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Insert 0: insert into PUBLIC.ORDER_ITEMS (QUANTITY, PRICE, DESCRIPTION, ORDER_NUMBER, ITEM_NUMBER) values (?, ?, ?, ?, ?)
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Update 0: update PUBLIC.ORDER_ITEMS set QUANTITY=?, PRICE=?, DESCRIPTION=? where ORDER_NUMBER=? and ITEM_NUMBER=?
1484 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Delete 0: delete from PUBLIC.ORDER_ITEMS where ORDER_NUMBER=? and ITEM_NUMBER=?
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: org.domain.Orders.orderItemses
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: update PUBLIC.ORDER_ITEMS set ORDER_NUMBER=? where ORDER_NUMBER=? and ITEM_NUMBER=?
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: update PUBLIC.ORDER_ITEMS set ORDER_NUMBER=null where ORDER_NUMBER=? and ORDER_NUMBER=? and ITEM_NUMBER=?
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: update PUBLIC.ORDER_ITEMS set ORDER_NUMBER=null where ORDER_NUMBER=?
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - Static SQL for collection: org.domain.Names.orderses
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - Row insert: update PUBLIC.ORDERS set NAME_ID=? where ORDER_NUMBER=?
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - Row delete: update PUBLIC.ORDERS set NAME_ID=null where NAME_ID=? and ORDER_NUMBER=?
1500 DEBUG [main] org.hibernate.persister.collection.AbstractCollectionPersister - One-shot delete: update PUBLIC.ORDERS set NAME_ID=null where NAME_ID=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity org.domain.Orders: select orders0_.ORDER_NUMBER as ORDER1_2_0_, orders0_.NAME_ID as NAME2_2_0_, orders0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orders0_ where orders0_.ORDER_NUMBER=?
1531 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity org.domain.Names: select names0_.NAME_ID as NAME1_0_0_, names0_.NAME as NAME0_0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_MERGE on entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.entity.EntityLoader - Static select for action ACTION_REFRESH on entity org.domain.OrderItems: select orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=? and orderitems0_.ITEM_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.collection.OneToManyLoader - Static select for one-to-many org.domain.Orders.orderItemses: select orderitems0_.ORDER_NUMBER as ORDER1_1_, orderitems0_.ITEM_NUMBER as ITEM2_1_, orderitems0_.ORDER_NUMBER as ORDER1_1_0_, orderitems0_.ITEM_NUMBER as ITEM2_1_0_, orderitems0_.QUANTITY as QUANTITY1_0_, orderitems0_.PRICE as PRICE1_0_, orderitems0_.DESCRIPTION as DESCRIPT5_1_0_ from PUBLIC.ORDER_ITEMS orderitems0_ where orderitems0_.ORDER_NUMBER=?
1547 DEBUG [main] org.hibernate.loader.collection.OneToManyLoader - Static select for one-to-many org.domain.Names.orderses: select orderses0_.NAME_ID as NAME2_1_, orderses0_.ORDER_NUMBER as ORDER1_1_, orderses0_.ORDER_NUMBER as ORDER1_2_0_, orderses0_.NAME_ID as NAME2_2_0_, orderses0_.ORDER_TOTAL as ORDER3_2_0_ from PUBLIC.ORDERS orderses0_ where orderses0_.NAME_ID=?
1547 DEBUG [main] org.hibernate.impl.SessionFactoryObjectFactory - initializing class SessionFactoryObjectFactory
1562 DEBUG [main] org.hibernate.impl.SessionFactoryObjectFactory - registered: 8a2b96c8218dc7c901218dc7cb390000 (unnamed)
1562 INFO [main] org.hibernate.impl.SessionFactoryObjectFactory - Not binding factory to JNDI, no JNDI name configured
1562 DEBUG [main] org.hibernate.impl.SessionFactoryImpl - instantiated session factory
1562 DEBUG [main] org.hibernate.impl.SessionFactoryImpl - Checking 0 named HQL queries
1562 DEBUG [main] org.hibernate.impl.SessionFactoryImpl - Checking 0 named SQL queries
1609 DEBUG [main] org.hibernate.impl.SessionImpl - opened session at timestamp: 12436242297
1625 DEBUG [main] org.hibernate.event.def.DefaultSaveOrUpdateEventListener - saving transient instance
1625 DEBUG [main] org.hibernate.event.def.AbstractSaveEventListener - generated identifier: TEST, using strategy: org.hibernate.id.Assigned
1625 DEBUG [main] org.hibernate.event.def.AbstractSaveEventListener - saving [org.domain.Names#TEST]
1640 DEBUG [main] org.hibernate.event.def.WrapVisitor - Wrapped collection in role: org.domain.Names.orderses
1640 DEBUG [main] org.dao.NamesDAO - save successful
1640 DEBUG [main] org.dao.NamesDAO - deleting Names instance
1656 DEBUG [main] org.hibernate.event.def.DefaultDeleteEventListener - entity was not persistent in delete processing
1656 DEBUG [main] org.hibernate.engine.IdentifierValue - id unsaved-value strategy UNDEFINED
1656 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Getting current persistent state for: [org.domain.Names#TEST]
1656 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
1656 DEBUG [main] org.hibernate.jdbc.ConnectionManager - opening JDBC connection
1656 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
1656 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - using pooled JDBC connection, pool size: 0
1656 DEBUG [main] org.hibernate.SQL - select names_.NAME_ID, names_.NAME as NAME0_ from PUBLIC.NAMES names_ where names_.NAME_ID=?
Hibernate: select names_.NAME_ID, names_.NAME as NAME0_ from PUBLIC.NAMES names_ where names_.NAME_ID=?
1656 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - preparing statement
1656 DEBUG [main] org.hibernate.type.StringType - binding 'TEST' to parameter: 1
1656 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
1656 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - closing statement
1656 INFO [main] org.hibernate.event.def.DefaultDeleteEventListener - handling transient entity in delete processing
1656 DEBUG [main] org.hibernate.impl.SessionImpl - setting cache mode to: GET
1656 DEBUG [main] org.hibernate.impl.SessionImpl - setting cache mode to: NORMAL
1656 DEBUG [main] org.hibernate.impl.SessionImpl - setting cache mode to: GET
1656 DEBUG [main] org.hibernate.impl.SessionImpl - setting cache mode to: NORMAL
1656 DEBUG [main] org.dao.NamesDAO - delete successful
1656 DEBUG [main] org.dao.NamesDAO - getting Names instance with id: JACKI
1656 DEBUG [main] org.hibernate.jdbc.JDBCContext - after autocommit
1656 DEBUG [main] org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
1656 DEBUG [main] org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
1656 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - returning connection to pool, pool size: 1
1672 ERROR [main] org.dao.NamesDAO - get failed
org.hibernate.MappingException: Unknown entity: Names
at org.hibernate.impl.SessionFactoryImpl.getEntityPersister(SessionFactoryImpl.java:550)
at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:68)
at org.hibernate.impl.SessionImpl.fireLoad(SessionImpl.java:878)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:815)
at org.dao.NamesDAO.findById(NamesDAO.java:53)
at org.test.NamesDAOTest.testFindById(NamesDAOTest.java:51)
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 junit.framework.TestCase.runTest(TestCase.java:164)
at junit.framework.TestCase.runBare(TestCase.java:130)
at junit.framework.TestResult$1.protect(TestResult.java:106)
at junit.framework.TestResult.runProtected(TestResult.java:124)
at junit.framework.TestResult.run(TestResult.java:109)
at junit.framework.TestCase.run(TestCase.java:120)
at junit.framework.TestSuite.runTest(TestSuite.java:230)
at junit.framework.TestSuite.run(TestSuite.java:225)
at org.eclipse.jdt.internal.junit.runner.junit3.JUnit3TestReference.run(JUnit3TestReference.java:130)
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)
1687 DEBUG [main] org.dao.NamesDAO - finding Names instance with property: nameId, value: JACKI
1703 DEBUG [main] org.hibernate.engine.query.QueryPlanCache - unable to locate HQL query plan in cache; generating (from Names as model where model.nameId= ?)
1781 DEBUG [main] org.hibernate.hql.ast.QueryTranslatorImpl - parse() - HQL: from org.domain.Names as model where model.nameId= ?
1797 DEBUG [main] org.hibernate.hql.ast.AST - --- HQL AST ---
\-[QUERY] 'query'
+-[SELECT_FROM] 'SELECT_FROM'
| \-[FROM] 'from'
| \-[RANGE] 'RANGE'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[IDENT] 'org'
| | | \-[IDENT] 'domain'
| | \-[IDENT] 'Names'
| \-[ALIAS] 'model'
\-[WHERE] 'where'
\-[EQ] '='
+-[DOT] '.'
| +-[IDENT] 'model'
| \-[IDENT] 'nameId'
\-[PARAM] '?'
1797 DEBUG [main] org.hibernate.hql.ast.ErrorCounter - throwQueryException() : no errors
1843 DEBUG [main] org.hibernate.hql.antlr.HqlSqlBaseWalker - select << begin [level=1, statement=select]
1875 DEBUG [main] org.hibernate.hql.ast.tree.FromElement - FromClause{level=1} : org.domain.Names (model) -> names0_
1875 DEBUG [main] org.hibernate.hql.ast.tree.FromReferenceNode - Resolved : model -> names0_.NAME_ID
1875 DEBUG [main] org.hibernate.hql.ast.tree.FromElement - handling property dereference [org.domain.Names (model) -> nameId (class)]
1875 DEBUG [main] org.hibernate.hql.ast.tree.DotNode - getDataType() : nameId -> org.hibernate.type.StringType@63a721
1875 DEBUG [main] org.hibernate.hql.ast.tree.FromReferenceNode - Resolved : model.nameId -> names0_.NAME_ID
1890 DEBUG [main] org.hibernate.hql.antlr.HqlSqlBaseWalker - select : finishing up [level=1, statement=select]
1890 DEBUG [main] org.hibernate.hql.ast.HqlSqlWalker - processQuery() : ( SELECT ( FromClause{level=1} PUBLIC.NAMES names0_ ) ( where ( = ( names0_.NAME_ID names0_.NAME_ID nameId ) ? ) ) )
1890 DEBUG [main] org.hibernate.hql.ast.HqlSqlWalker - Derived SELECT clause created.
1906 DEBUG [main] org.hibernate.hql.ast.util.JoinProcessor - Using FROM fragment [PUBLIC.NAMES names0_]
1906 DEBUG [main] org.hibernate.hql.antlr.HqlSqlBaseWalker - select >> end [level=1, statement=select]
1922 DEBUG [main] org.hibernate.hql.ast.AST - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (PUBLIC.NAMES)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'names0_.NAME_ID as NAME1_0_' {FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=model,role=null,tableName=PUBLIC.NAMES,tableAlias=names0_,origin=null,colums={,className=org.domain.Names}}}
| \-[SQL_TOKEN] SqlFragment: 'names0_.NAME as NAME0_'
+-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[model], fromElementByTableAlias=[names0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
| \-[FROM_FRAGMENT] FromElement: 'PUBLIC.NAMES names0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=model,role=null,tableName=PUBLIC.NAMES,tableAlias=names0_,origin=null,colums={,className=org.domain.Names}}
\-[WHERE] SqlNode: 'where'
\-[EQ] BinaryLogicOperatorNode: '='
+-[DOT] DotNode: 'names0_.NAME_ID' {propertyName=nameId,dereferenceType=4,propertyPath=nameId,path=model.nameId,tableAlias=names0_,className=org.domain.Names,classAlias=model}
| +-[ALIAS_REF] IdentNode: 'names0_.NAME_ID' {alias=model, className=org.domain.Names, tableAlias=names0_}
| \-[IDENT] IdentNode: 'nameId' {originalText=nameId}
\-[PARAM] ParameterNode: '?' {ordinal=0, expectedType=org.hibernate.type.StringType@63a721}
1922 DEBUG [main] org.hibernate.hql.ast.ErrorCounter - throwQueryException() : no errors
1937 DEBUG [main] org.hibernate.hql.ast.QueryTranslatorImpl - HQL: from org.domain.Names as model where model.nameId= ?
1937 DEBUG [main] org.hibernate.hql.ast.QueryTranslatorImpl - SQL: select names0_.NAME_ID as NAME1_0_, names0_.NAME as NAME0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1937 DEBUG [main] org.hibernate.hql.ast.ErrorCounter - throwQueryException() : no errors
1953 DEBUG [main] org.hibernate.engine.query.HQLQueryPlan - HQL param location recognition took 16 mills (from Names as model where model.nameId= ?)
1953 DEBUG [main] org.hibernate.engine.query.QueryPlanCache - located HQL query plan in cache (from Names as model where model.nameId= ?)
1953 DEBUG [main] org.hibernate.engine.query.HQLQueryPlan - find: from Names as model where model.nameId= ?
1968 DEBUG [main] org.hibernate.engine.QueryParameters - parameters: [JACKI]
1968 DEBUG [main] org.hibernate.engine.QueryParameters - named parameters: {}
1968 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
1968 DEBUG [main] org.hibernate.jdbc.ConnectionManager - opening JDBC connection
1968 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
1968 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - using pooled JDBC connection, pool size: 0
1968 DEBUG [main] org.hibernate.SQL - select names0_.NAME_ID as NAME1_0_, names0_.NAME as NAME0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
Hibernate: select names0_.NAME_ID as NAME1_0_, names0_.NAME as NAME0_ from PUBLIC.NAMES names0_ where names0_.NAME_ID=?
1968 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - preparing statement
1968 DEBUG [main] org.hibernate.type.StringType - binding 'JACKI' to parameter: 1
1968 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
1968 DEBUG [main] org.hibernate.loader.Loader - processing result set
1968 DEBUG [main] org.hibernate.loader.Loader - result set row: 0
1968 DEBUG [main] org.hibernate.type.StringType - returning 'JACKI' as column: NAME1_0_
1968 DEBUG [main] org.hibernate.loader.Loader - result row: EntityKey[org.domain.Names#JACKI]
1968 DEBUG [main] org.hibernate.loader.Loader - Initializing object from ResultSet: [org.domain.Names#JACKI]
1968 DEBUG [main] org.hibernate.persister.entity.AbstractEntityPersister - Hydrating entity: [org.domain.Names#JACKI]
1968 DEBUG [main] org.hibernate.type.StringType - returning 'Jack Inman' as column: NAME0_
1968 DEBUG [main] org.hibernate.loader.Loader - done processing result set (1 rows)
1968 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
1968 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
1968 DEBUG [main] org.hibernate.jdbc.AbstractBatcher - closing statement
1968 DEBUG [main] org.hibernate.loader.Loader - total objects hydrated: 1
1968 DEBUG [main] org.hibernate.engine.TwoPhaseLoad - resolving associations for [org.domain.Names#JACKI]
1968 DEBUG [main] org.hibernate.engine.loading.LoadContexts - creating collection wrapper:[org.domain.Names.orderses#JACKI]
1968 DEBUG [main] org.hibernate.engine.TwoPhaseLoad - done materializing entity [org.domain.Names#JACKI]
1968 DEBUG [main] org.hibernate.engine.StatefulPersistenceContext - initializing non-lazy collections
1968 DEBUG [main] org.hibernate.jdbc.JDBCContext - after autocommit
1984 DEBUG [main] org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
1984 DEBUG [main] org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
1984 DEBUG [main] org.hibernate.connection.DriverManagerConnectionProvider - returning connection to pool, pool size: 1