Read the rules before posting!
http://www.hibernate.org/ForumMailingli ... AskForHelp

Hibernate version:
2.1.7c

Mapping documents:
<?xml version="1.0" encoding="utf-8" ?>
<!DOCTYPE hibernate-configuration (View Source for full doctype...)>
- <hibernate-configuration>
- <session-factory>
- <!-- local connection properties
-->
<property name="hibernate.connection.url">jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev</property>
<property name="hibernate.connection.driver_class">com.sybase.jdbc2.jdbc.SybDriver</property>
<property name="dialect">net.sf.hibernate.dialect.SybaseDialect</property>
<property name="hibernate.connection.username">ersdbo</property>
<property name="hibernate.connection.password">ecl1pse</property>
- <!-- property name="hibernate.connection.pool_size"></property
-->
- <!-- dialect for Sybase
-->
<mapping resource="edu/ucop/ers/domain/CustOrder.hbm" />
<mapping resource="edu/ucop/ers/domain/Customer.hbm" />
</session-factory>
</hibernate-configuration>

<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd" >

<hibernate-mapping package="edu.ucop.ers.domain">
<class name="CustOrder" table="WBCustOrder">
<id
column="id"
name="id"
type="integer"
>
<generator class="hilo">
<param name="table">WBcustorder_hi</param>
<param name="column">next_value</param>
<param name="max_lo">10</param>
</generator>
</id>
<property
column="order_number"
length="10"
name="orderNumber"
not-null="true"
type="integer"
/>
<property
column="product_name"
length="30"
name="productName"
not-null="true"
type="string"
/>
<property
column="quantity"
length="5"
name="quantity"
not-null="true"
type="big_decimal"
/>

<many-to-one
class="Customer"
name="cust"
not-null="true"
>
<column name="cust_id" />
</many-to-one>
</class>
</hibernate-mapping>

<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd" >

<hibernate-mapping package="edu.ucop.ers.domain">
<class name="Customer" table="WBCustomer">
<id
column="id"
name="id"
type="java.lang.Integer"
>
<generator class="hilo">
<param name="table">WBcustomer_hi</param>
<param name="column">next_value</param>
<param name="max_lo">10</param>
</generator>
</id>
<property
column="customer_zip"
length="5"
name="customerZip"
not-null="true"
type="string"
/>
<property
column="customer_number"
length="10"
name="customerNumber"
not-null="true"
type="integer"
/>
<property
column="customer_name"
length="30"
name="customerName"
not-null="true"
type="string"
/>

<set inverse="true" name="custOrders"
table="WBcustorder"
sort="natural"
order-by="order_number asc"
cascade="all-delete-orphan">
<key column="cust_id" />
<one-to-many class="CustOrder" />
</set>
</class>
</hibernate-mapping>


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

Full stack trace of any exception that occurs:
09:36:59,534 INFO Environment:478 - Hibernate 2.1.7
09:36:59,544 INFO Environment:507 - hibernate.properties not found
09:36:59,544 INFO Environment:538 - using CGLIB reflection optimizer
09:36:59,544 INFO Environment:567 - using JDK 1.4 java.sql.Timestamp handling
09:36:59,554 INFO Configuration:900 - configuring from resource: /hibernate.cfg.xml
09:36:59,554 INFO Configuration:872 - Configuration resource: /hibernate.cfg.xml
09:36:59,835 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/
09:36:59,835 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath
09:36:59,875 DEBUG Configuration:858 - hibernate.connection.url=jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev
09:36:59,875 DEBUG Configuration:858 - hibernate.connection.driver_class=com.sybase.jdbc2.jdbc.SybDriver
09:36:59,875 DEBUG Configuration:858 - dialect=net.sf.hibernate.dialect.SybaseDialect
09:36:59,875 DEBUG Configuration:858 - hibernate.connection.username=ersdbo
09:36:59,875 DEBUG Configuration:858 - hibernate.connection.password=ecl1pse
09:36:59,885 DEBUG Configuration:1017 - null<-org.dom4j.tree.DefaultAttribute@1df5a8f [Attribute: name resource value "edu/ucop/ers/domain/CustOrder.hbm"]
09:36:59,885 INFO Configuration:331 - Mapping resource: edu/ucop/ers/domain/CustOrder.hbm
09:36:59,885 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
09:36:59,895 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
09:36:59,985 INFO Binder:230 - Mapping class: edu.ucop.ers.domain.CustOrder -> WBCustOrder
09:37:00,035 DEBUG Binder:487 - Mapped property: id -> id, type: integer
09:37:00,045 DEBUG Binder:487 - Mapped property: orderNumber -> order_number, type: integer
09:37:00,045 DEBUG Binder:487 - Mapped property: productName -> product_name, type: string
09:37:00,045 DEBUG Binder:487 - Mapped property: quantity -> quantity, type: big_decimal
09:37:00,055 DEBUG Binder:487 - Mapped property: cust -> cust_id, type: edu.ucop.ers.domain.Customer
09:37:00,055 DEBUG Configuration:1017 - null<-org.dom4j.tree.DefaultAttribute@15fadcf [Attribute: name resource value "edu/ucop/ers/domain/Customer.hbm"]
09:37:00,075 INFO Configuration:331 - Mapping resource: edu/ucop/ers/domain/Customer.hbm
09:37:00,075 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
09:37:00,075 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
09:37:00,105 INFO Binder:230 - Mapping class: edu.ucop.ers.domain.Customer -> WBCustomer
09:37:00,105 DEBUG Binder:487 - Mapped property: id -> id, type: integer
09:37:00,105 DEBUG Binder:487 - Mapped property: customerZip -> customer_zip, type: string
09:37:00,105 DEBUG Binder:487 - Mapped property: customerNumber -> customer_number, type: integer
09:37:00,105 DEBUG Binder:487 - Mapped property: customerName -> customer_name, type: string
09:37:00,115 DEBUG Binder:487 - Mapped property: custOrders, type: java.util.SortedSet
09:37:00,115 INFO Configuration:1058 - Configured SessionFactory: null
09:37:00,125 DEBUG Configuration:1059 - properties: {hibernate.connection.password=ecl1pse, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\Program Files\Java\j2re1.4.2_06\bin, java.vm.version=1.4.2_06-b03, hibernate.connection.username=ersdbo, 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.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\eclipse\workspace\TestSync3, java.runtime.version=1.4.2_06-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Program Files\Java\j2re1.4.2_06\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\wkidd\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.java2d.fontpath=, java.library.path=C:\Program Files\Java\j2re1.4.2_06\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Common Files\Adaptec Shared\System, java.specification.name=Java Platform API Specification, java.class.version=48.0, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=5.1, user.home=C:\Documents and Settings\wkidd, user.timezone=America/Los_Angeles, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=com.sybase.jdbc2.jdbc.SybDriver, user.name=wkidd, java.class.path=/c:/eclipse/plugins/org.eclipse.jdt.junit_3.0.1/junitsupport.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.0.0/junitruntime.jar;C:\eclipse\workspace\TestSync3\bin;C:\eclipse\plugins\org.junit_3.8.1\junit.jar;C:\eclipse\workspace\TestSync3\lib\ant-1.5.3.jar;C:\eclipse\workspace\TestSync3\lib\ant-optional-1.5.3.jar;C:\eclipse\workspace\TestSync3\lib\c3p0-0.8.4.5.jar;C:\eclipse\workspace\TestSync3\lib\cglib-full-2.0.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-collections-2.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-dbcp-1.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-lang-1.0.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-logging-1.0.3.jar;C:\eclipse\workspace\TestSync3\lib\commons-pool-1.1.jar;C:\eclipse\workspace\TestSync3\lib\concurrent-1.3.2.jar;C:\eclipse\workspace\TestSync3\lib\connector.jar;C:\eclipse\workspace\TestSync3\lib\dom4j-1.4.jar;C:\eclipse\workspace\TestSync3\lib\ehcache-0.7.jar;C:\eclipse\workspace\TestSync3\lib\jaas.jar;C:\eclipse\workspace\TestSync3\lib\jboss-cache.jar;C:\eclipse\workspace\TestSync3\lib\jboss-common.jar;C:\eclipse\workspace\TestSync3\lib\jboss-jmx.jar;C:\eclipse\workspace\TestSync3\lib\jboss-system.jar;C:\eclipse\workspace\TestSync3\lib\jconn2.jar;C:\eclipse\workspace\TestSync3\lib\jcs-1.0-dev.jar;C:\eclipse\workspace\TestSync3\lib\jdbc2_0-stdext.jar;C:\eclipse\workspace\TestSync3\lib\jgroups-2.2.1.jar;C:\eclipse\workspace\TestSync3\lib\jta.jar;C:\eclipse\workspace\TestSync3\lib\junit-3.8.1.jar;C:\eclipse\workspace\TestSync3\lib\log4j-1.2.8.jar;C:\eclipse\workspace\TestSync3\lib\odmg-3.0.jar;C:\eclipse\workspace\TestSync3\lib\oscache-2.0.jar;C:\eclipse\workspace\TestSync3\lib\proxool-0.8.3.jar;C:\eclipse\workspace\TestSync3\lib\swarmcache-1.0rc2.jar;C:\eclipse\workspace\TestSync3\lib\xalan-2.4.0.jar;C:\eclipse\workspace\TestSync3\lib\xerces-2.4.0.jar;C:\eclipse\workspace\TestSync3\lib\xml-apis.jar;C:\eclipse\workspace\TestSync3\lib\hibernate2.jar;C:\eclipse\workspace\TestSync3\lib\commons-chain-1.0.jar, java.vm.specification.version=1.0, java.home=C:\Program Files\Java\j2re1.4.2_06, sun.arch.data.model=32, hibernate.dialect=net.sf.hibernate.dialect.SybaseDialect, hibernate.connection.url=jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, java.version=1.4.2_06, java.ext.dirs=C:\Program Files\Java\j2re1.4.2_06\lib\ext, sun.boot.class.path=C:\Program Files\Java\j2re1.4.2_06\lib\rt.jar;C:\Program Files\Java\j2re1.4.2_06\lib\i18n.jar;C:\Program Files\Java\j2re1.4.2_06\lib\sunrsasign.jar;C:\Program Files\Java\j2re1.4.2_06\lib\jsse.jar;C:\Program Files\Java\j2re1.4.2_06\lib\jce.jar;C:\Program Files\Java\j2re1.4.2_06\lib\charsets.jar;C:\Program Files\Java\j2re1.4.2_06\classes;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\activation.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\javax.servlet.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\javax.servlet.jsp.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-j2ee.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-jaxrpc.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-jsr77.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-saaj.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\mail.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\namespace.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\xml-apis.jar, java.vendor=Sun Microsystems Inc., file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, dialect=net.sf.hibernate.dialect.SybaseDialect, sun.cpu.isalist=pentium i486 i386}
09:37:00,125 INFO Configuration:632 - processing one-to-many association mappings
09:37:00,125 DEBUG Binder:1354 - Second pass for collection: edu.ucop.ers.domain.Customer.custOrders
09:37:00,125 INFO Binder:1182 - Mapping collection: edu.ucop.ers.domain.Customer.custOrders -> WBCustOrder
09:37:00,125 DEBUG Binder:1369 - Mapped collection key: cust_id, one-to-many: edu.ucop.ers.domain.CustOrder
09:37:00,125 INFO Configuration:641 - processing one-to-one association property references
09:37:00,125 INFO Configuration:666 - processing foreign key constraints
09:37:00,125 DEBUG Configuration:683 - resolving reference to class: edu.ucop.ers.domain.Customer
09:37:00,145 INFO Dialect:86 - Using dialect: net.sf.hibernate.dialect.SybaseDialect
09:37:00,155 DEBUG SQLExceptionConverterFactory:49 - Using dialect defined converter
09:37:00,155 INFO SettingsFactory:74 - Use outer join fetching: true
09:37:00,165 INFO DriverManagerConnectionProvider:42 - Using Hibernate built-in connection pool (not for production use!)
09:37:00,165 INFO DriverManagerConnectionProvider:43 - Hibernate connection pool size: 20
09:37:00,185 INFO DriverManagerConnectionProvider:77 - using driver: com.sybase.jdbc2.jdbc.SybDriver at URL: jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev
09:37:00,185 INFO DriverManagerConnectionProvider:78 - connection properties: {user=ersdbo, password=ecl1pse}
09:37:00,195 INFO TransactionManagerLookupFactory:33 - No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended)
09:37:00,195 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:00,195 DEBUG DriverManagerConnectionProvider:100 - opening new JDBC connection
09:37:00,506 DEBUG DriverManagerConnectionProvider:106 - created connection to: jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev, Isolation Level: 2
09:37:00,516 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 1
09:37:00,516 INFO SettingsFactory:114 - Use scrollable result sets: true
09:37:00,516 INFO SettingsFactory:117 - Use JDBC3 getGeneratedKeys(): false
09:37:00,516 INFO SettingsFactory:120 - Optimize cache for minimal puts: false
09:37:00,516 INFO SettingsFactory:129 - Query language substitutions: {}
09:37:00,516 INFO SettingsFactory:140 - cache provider: net.sf.hibernate.cache.EhCacheProvider
09:37:00,516 INFO Configuration:1121 - instantiating and configuring caches
09:37:00,606 INFO SessionFactoryImpl:119 - building session factory
09:37:00,606 DEBUG SessionFactoryImpl:125 - instantiating session factory with properties: {hibernate.connection.password=ecl1pse, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\Program Files\Java\j2re1.4.2_06\bin, java.vm.version=1.4.2_06-b03, hibernate.connection.username=ersdbo, 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.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\eclipse\workspace\TestSync3, java.runtime.version=1.4.2_06-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\Program Files\Java\j2re1.4.2_06\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\wkidd\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.java2d.fontpath=, java.library.path=C:\Program Files\Java\j2re1.4.2_06\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Common Files\Adaptec Shared\System, java.specification.name=Java Platform API Specification, java.class.version=48.0, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=5.1, user.home=C:\Documents and Settings\wkidd, user.timezone=America/Los_Angeles, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=com.sybase.jdbc2.jdbc.SybDriver, user.name=wkidd, java.class.path=/c:/eclipse/plugins/org.eclipse.jdt.junit_3.0.1/junitsupport.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.0.0/junitruntime.jar;C:\eclipse\workspace\TestSync3\bin;C:\eclipse\plugins\org.junit_3.8.1\junit.jar;C:\eclipse\workspace\TestSync3\lib\ant-1.5.3.jar;C:\eclipse\workspace\TestSync3\lib\ant-optional-1.5.3.jar;C:\eclipse\workspace\TestSync3\lib\c3p0-0.8.4.5.jar;C:\eclipse\workspace\TestSync3\lib\cglib-full-2.0.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-collections-2.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-dbcp-1.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-lang-1.0.1.jar;C:\eclipse\workspace\TestSync3\lib\commons-logging-1.0.3.jar;C:\eclipse\workspace\TestSync3\lib\commons-pool-1.1.jar;C:\eclipse\workspace\TestSync3\lib\concurrent-1.3.2.jar;C:\eclipse\workspace\TestSync3\lib\connector.jar;C:\eclipse\workspace\TestSync3\lib\dom4j-1.4.jar;C:\eclipse\workspace\TestSync3\lib\ehcache-0.7.jar;C:\eclipse\workspace\TestSync3\lib\jaas.jar;C:\eclipse\workspace\TestSync3\lib\jboss-cache.jar;C:\eclipse\workspace\TestSync3\lib\jboss-common.jar;C:\eclipse\workspace\TestSync3\lib\jboss-jmx.jar;C:\eclipse\workspace\TestSync3\lib\jboss-system.jar;C:\eclipse\workspace\TestSync3\lib\jconn2.jar;C:\eclipse\workspace\TestSync3\lib\jcs-1.0-dev.jar;C:\eclipse\workspace\TestSync3\lib\jdbc2_0-stdext.jar;C:\eclipse\workspace\TestSync3\lib\jgroups-2.2.1.jar;C:\eclipse\workspace\TestSync3\lib\jta.jar;C:\eclipse\workspace\TestSync3\lib\junit-3.8.1.jar;C:\eclipse\workspace\TestSync3\lib\log4j-1.2.8.jar;C:\eclipse\workspace\TestSync3\lib\odmg-3.0.jar;C:\eclipse\workspace\TestSync3\lib\oscache-2.0.jar;C:\eclipse\workspace\TestSync3\lib\proxool-0.8.3.jar;C:\eclipse\workspace\TestSync3\lib\swarmcache-1.0rc2.jar;C:\eclipse\workspace\TestSync3\lib\xalan-2.4.0.jar;C:\eclipse\workspace\TestSync3\lib\xerces-2.4.0.jar;C:\eclipse\workspace\TestSync3\lib\xml-apis.jar;C:\eclipse\workspace\TestSync3\lib\hibernate2.jar;C:\eclipse\workspace\TestSync3\lib\commons-chain-1.0.jar, java.vm.specification.version=1.0, java.home=C:\Program Files\Java\j2re1.4.2_06, sun.arch.data.model=32, hibernate.dialect=net.sf.hibernate.dialect.SybaseDialect, hibernate.connection.url=jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, java.version=1.4.2_06, java.ext.dirs=C:\Program Files\Java\j2re1.4.2_06\lib\ext, sun.boot.class.path=C:\Program Files\Java\j2re1.4.2_06\lib\rt.jar;C:\Program Files\Java\j2re1.4.2_06\lib\i18n.jar;C:\Program Files\Java\j2re1.4.2_06\lib\sunrsasign.jar;C:\Program Files\Java\j2re1.4.2_06\lib\jsse.jar;C:\Program Files\Java\j2re1.4.2_06\lib\jce.jar;C:\Program Files\Java\j2re1.4.2_06\lib\charsets.jar;C:\Program Files\Java\j2re1.4.2_06\classes;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\activation.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\javax.servlet.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\javax.servlet.jsp.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-j2ee.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-jaxrpc.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-jsr77.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\jboss-saaj.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\mail.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\namespace.jar;C:\MyEclipse\eclipse\plugins\com.genuitec.eclipse.j2eedt.core_3.8.3\data\libraryset\1.4\xml-apis.jar, java.vendor=Sun Microsystems Inc., file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, dialect=net.sf.hibernate.dialect.SybaseDialect, sun.cpu.isalist=pentium i486 i386}
09:37:00,906 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory
09:37:00,906 DEBUG SessionFactoryObjectFactory:76 - registered: 00b0b5bb01ba65ac0101ba65adea0000 (unnamed)
09:37:00,906 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
09:37:00,906 DEBUG SessionFactoryImpl:196 - instantiated session factory
09:37:00,957 DEBUG SessionImpl:558 - opened session
09:37:00,967 DEBUG SessionImpl:1536 - find: from Customer where customer_number=6
09:37:00,977 DEBUG QueryTranslator:147 - compiling query
09:37:00,987 DEBUG SessionImpl:2266 - flushing session
09:37:00,987 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:00,987 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:00,987 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:00,997 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:00,997 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:00,997 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:00,997 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number=6
09:37:00,997 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:00,997 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:00,997 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:00,997 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 0
09:37:00,997 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,017 DEBUG BatcherImpl:252 - preparing statement
09:37:01,017 DEBUG Loader:277 - processing result set
09:37:01,017 DEBUG Loader:294 - done processing result set (0 rows)
09:37:01,017 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,017 DEBUG BatcherImpl:272 - closing statement
09:37:01,017 DEBUG Loader:314 - total objects hydrated: 0
09:37:01,017 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,017 DEBUG SessionImpl:576 - closing session
09:37:01,017 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,027 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 1
09:37:01,027 DEBUG SessionImpl:594 - transaction completion
09:37:01,027 DEBUG SessionImpl:558 - opened session
09:37:01,027 DEBUG SessionImpl:1536 - find: from Customer where customer_number=6
09:37:01,027 DEBUG SessionImpl:2266 - flushing session
09:37:01,027 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:01,027 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:01,037 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:01,037 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:01,037 DEBUG SessionImpl:3423 - running Session.finalize()
09:37:01,037 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:01,037 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:01,037 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number=6
09:37:01,037 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,037 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,037 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,037 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 0
09:37:01,037 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,037 DEBUG BatcherImpl:252 - preparing statement
09:37:01,047 DEBUG Loader:277 - processing result set
09:37:01,047 DEBUG Loader:294 - done processing result set (0 rows)
09:37:01,047 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,047 DEBUG BatcherImpl:272 - closing statement
09:37:01,047 DEBUG Loader:314 - total objects hydrated: 0
09:37:01,047 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,047 DEBUG SessionImpl:576 - closing session
09:37:01,047 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,047 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 1
09:37:01,047 DEBUG SessionImpl:594 - transaction completion
09:37:01,057 DEBUG SessionImpl:558 - opened session
09:37:01,057 DEBUG SessionImpl:1536 - find: from Customer where customer_number=6
09:37:01,057 DEBUG SessionImpl:2266 - flushing session
09:37:01,057 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:01,057 DEBUG SessionImpl:3423 - running Session.finalize()
09:37:01,057 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:01,057 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:01,057 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:01,057 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:01,057 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:01,057 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number=6
09:37:01,057 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,057 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,057 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,057 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 0
09:37:01,067 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,067 DEBUG BatcherImpl:252 - preparing statement
09:37:01,067 DEBUG Loader:277 - processing result set
09:37:01,067 DEBUG Loader:294 - done processing result set (0 rows)
09:37:01,067 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,077 DEBUG BatcherImpl:272 - closing statement
09:37:01,077 DEBUG Loader:314 - total objects hydrated: 0
09:37:01,077 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,077 DEBUG SessionImpl:576 - closing session
09:37:01,077 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,087 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 1
09:37:01,087 DEBUG SessionImpl:594 - transaction completion
09:37:01,087 DEBUG SessionImpl:3423 - running Session.finalize()
09:37:01,097 DEBUG SessionImpl:558 - opened session
09:37:01,097 DEBUG SessionImpl:1536 - find: from Customer where customer_number = 4
09:37:01,097 DEBUG QueryTranslator:147 - compiling query
09:37:01,097 DEBUG SessionImpl:2266 - flushing session
09:37:01,097 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:01,097 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:01,107 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:01,107 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:01,107 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:01,107 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:01,107 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number = 4
09:37:01,107 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=4 )
09:37:01,107 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,107 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,107 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 0
09:37:01,107 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=4 )
09:37:01,117 DEBUG BatcherImpl:252 - preparing statement
09:37:01,127 DEBUG Loader:277 - processing result set
09:37:01,127 DEBUG Loader:480 - result row: 1973
09:37:01,127 DEBUG Loader:611 - Initializing object from ResultSet: 1973
09:37:01,127 DEBUG Loader:680 - Hydrating entity: edu.ucop.ers.domain.Customer#1973
09:37:01,137 DEBUG Loader:294 - done processing result set (1 rows)
09:37:01,137 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,137 DEBUG BatcherImpl:272 - closing statement
09:37:01,137 DEBUG Loader:314 - total objects hydrated: 1
09:37:01,137 DEBUG SessionImpl:2215 - resolving associations for [edu.ucop.ers.domain.Customer#1973]
09:37:01,137 DEBUG SessionImpl:3982 - creating collection wrapper:[edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,147 DEBUG SessionImpl:2246 - done materializing entity [edu.ucop.ers.domain.Customer#1973]
09:37:01,147 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,147 DEBUG SessionImpl:3295 - initializing collection [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,147 DEBUG SessionImpl:3296 - checking second-level cache
09:37:01,147 DEBUG SessionImpl:3302 - collection not cached
09:37:01,147 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,147 DEBUG SQL:229 - select custorders0_.cust_id as cust_id__, custorders0_.id as id__, custorders0_.id as id0_, custorders0_.order_number as order_nu2_0_, custorders0_.product_name as product_3_0_, custorders0_.quantity as quantity0_, custorders0_.cust_id as cust_id0_ from WBCustOrder custorders0_ where custorders0_.cust_id=? order by custorders0_.order_number asc
09:37:01,147 DEBUG BatcherImpl:252 - preparing statement
09:37:01,157 DEBUG Loader:402 - result set contains (possibly empty) collection: [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,157 DEBUG SessionImpl:3038 - uninitialized collection: initializing
09:37:01,157 DEBUG Loader:277 - processing result set
09:37:01,157 DEBUG Loader:480 - result row: 1365
09:37:01,157 DEBUG Loader:611 - Initializing object from ResultSet: 1365
09:37:01,157 DEBUG Loader:680 - Hydrating entity: edu.ucop.ers.domain.CustOrder#1365
09:37:01,167 DEBUG Loader:367 - found row of collection: [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,167 DEBUG SessionImpl:3061 - reading row
09:37:01,167 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.CustOrder#1365]
09:37:01,167 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.CustOrder#1365]
09:37:01,167 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.CustOrder#1365]
09:37:01,167 DEBUG Loader:480 - result row: 1366
09:37:01,167 DEBUG Loader:611 - Initializing object from ResultSet: 1366
09:37:01,167 DEBUG Loader:680 - Hydrating entity: edu.ucop.ers.domain.CustOrder#1366
09:37:01,167 DEBUG Loader:367 - found row of collection: [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,167 DEBUG SessionImpl:3061 - reading row
09:37:01,167 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.CustOrder#1366]
09:37:01,167 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.CustOrder#1366]
09:37:01,167 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.CustOrder#1366]
09:37:01,177 DEBUG Loader:480 - result row: 1376
09:37:01,177 DEBUG Loader:611 - Initializing object from ResultSet: 1376
09:37:01,177 DEBUG Loader:680 - Hydrating entity: edu.ucop.ers.domain.CustOrder#1376
09:37:01,177 DEBUG Loader:367 - found row of collection: [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,177 DEBUG SessionImpl:3061 - reading row
09:37:01,177 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.CustOrder#1376]
09:37:01,177 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.CustOrder#1376]
09:37:01,177 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.CustOrder#1376]
09:37:01,177 DEBUG Loader:480 - result row: 1377
09:37:01,177 DEBUG Loader:611 - Initializing object from ResultSet: 1377
09:37:01,177 DEBUG Loader:680 - Hydrating entity: edu.ucop.ers.domain.CustOrder#1377
09:37:01,177 DEBUG Loader:367 - found row of collection: [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,177 DEBUG SessionImpl:3061 - reading row
09:37:01,177 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.CustOrder#1377]
09:37:01,177 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.CustOrder#1377]
09:37:01,177 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.CustOrder#1377]
09:37:01,177 DEBUG Loader:294 - done processing result set (4 rows)
09:37:01,177 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,177 DEBUG BatcherImpl:272 - closing statement
09:37:01,177 DEBUG Loader:314 - total objects hydrated: 4
09:37:01,187 DEBUG SessionImpl:2215 - resolving associations for [edu.ucop.ers.domain.CustOrder#1365]
09:37:01,187 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2246 - done materializing entity [edu.ucop.ers.domain.CustOrder#1365]
09:37:01,187 DEBUG SessionImpl:2215 - resolving associations for [edu.ucop.ers.domain.CustOrder#1366]
09:37:01,187 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2246 - done materializing entity [edu.ucop.ers.domain.CustOrder#1366]
09:37:01,187 DEBUG SessionImpl:2215 - resolving associations for [edu.ucop.ers.domain.CustOrder#1376]
09:37:01,187 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2246 - done materializing entity [edu.ucop.ers.domain.CustOrder#1376]
09:37:01,187 DEBUG SessionImpl:2215 - resolving associations for [edu.ucop.ers.domain.CustOrder#1377]
09:37:01,187 DEBUG SessionImpl:1995 - loading [edu.ucop.ers.domain.Customer#1973]
09:37:01,187 DEBUG SessionImpl:2093 - attempting to resolve [edu.ucop.ers.domain.Customer#1973]
09:37:01,197 DEBUG SessionImpl:2109 - resolved object in session cache [edu.ucop.ers.domain.Customer#1973]
09:37:01,197 DEBUG SessionImpl:2246 - done materializing entity [edu.ucop.ers.domain.CustOrder#1377]
09:37:01,197 DEBUG SessionImpl:3097 - 1 collections were found in result set
09:37:01,197 DEBUG SessionImpl:3128 - collection fully initialized: [edu.ucop.ers.domain.Customer.custOrders#1973]
09:37:01,197 DEBUG SessionImpl:3131 - 1 collections initialized
09:37:01,197 DEBUG SessionImpl:3304 - collection initialized
09:37:01,197 DEBUG SessionImpl:576 - closing session
09:37:01,197 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,257 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 1
09:37:01,257 DEBUG SessionImpl:594 - transaction completion
09:37:01,257 DEBUG SessionImpl:558 - opened session
09:37:01,267 DEBUG JDBCTransaction:37 - begin
09:37:01,267 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,267 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 0
09:37:01,277 DEBUG JDBCTransaction:41 - current autocommit status:false
09:37:01,277 DEBUG Cascades:341 - id unsaved-value strategy NULL
09:37:01,277 DEBUG SessionImpl:1401 - saveOrUpdate() previously saved instance with id: 1973
09:37:01,277 DEBUG SessionImpl:1449 - updating [edu.ucop.ers.domain.Customer#1973]
09:37:01,287 DEBUG Cascades:497 - processing cascades for: edu.ucop.ers.domain.Customer
09:37:01,287 DEBUG Cascades:524 - cascading to collection: edu.ucop.ers.domain.Customer.custOrders
09:37:01,287 DEBUG Cascades:113 - cascading to saveOrUpdate()
09:37:01,287 DEBUG Cascades:341 - id unsaved-value strategy NULL
09:37:01,287 DEBUG SessionImpl:1401 - saveOrUpdate() previously saved instance with id: 1365
09:37:01,287 DEBUG SessionImpl:1449 - updating [edu.ucop.ers.domain.CustOrder#1365]
09:37:01,287 DEBUG Cascades:113 - cascading to saveOrUpdate()
09:37:01,287 DEBUG Cascades:341 - id unsaved-value strategy NULL
09:37:01,287 DEBUG SessionImpl:1401 - saveOrUpdate() previously saved instance with id: 1366
09:37:01,287 DEBUG SessionImpl:1449 - updating [edu.ucop.ers.domain.CustOrder#1366]
09:37:01,287 DEBUG Cascades:113 - cascading to saveOrUpdate()
09:37:01,287 DEBUG Cascades:341 - id unsaved-value strategy NULL
09:37:01,297 DEBUG SessionImpl:1401 - saveOrUpdate() previously saved instance with id: 1376
09:37:01,297 DEBUG SessionImpl:1449 - updating [edu.ucop.ers.domain.CustOrder#1376]
09:37:01,297 DEBUG Cascades:113 - cascading to saveOrUpdate()
09:37:01,297 DEBUG Cascades:341 - id unsaved-value strategy NULL
09:37:01,297 DEBUG SessionImpl:1401 - saveOrUpdate() previously saved instance with id: 1377
09:37:01,297 DEBUG SessionImpl:1449 - updating [edu.ucop.ers.domain.CustOrder#1377]
09:37:01,297 DEBUG Cascades:113 - cascading to saveOrUpdate()
09:37:01,297 DEBUG SessionImpl:1396 - saveOrUpdate() unsaved instance
09:37:01,297 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 1
09:37:01,297 DEBUG DriverManagerConnectionProvider:100 - opening new JDBC connection
09:37:01,507 DEBUG DriverManagerConnectionProvider:106 - created connection to: jdbc:sybase:Tds:sybserv4.ucop.edu:2125/ersdev, Isolation Level: 2
09:37:01,517 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 1
09:37:01,517 DEBUG JDBCExceptionReporter:49 - Could not save object
com.sybase.jdbc2.jdbc.SybSQLException: SET CHAINED command not allowed within multi-statement transaction.

at com.sybase.jdbc2.tds.Tds.processEed(Tds.java:2636)
at com.sybase.jdbc2.tds.Tds.nextResult(Tds.java:1996)
at com.sybase.jdbc2.jdbc.ResultGetter.nextResult(ResultGetter.java:69)
at com.sybase.jdbc2.jdbc.SybStatement.nextResult(SybStatement.java:204)
at com.sybase.jdbc2.jdbc.SybStatement.nextResult(SybStatement.java:187)
at com.sybase.jdbc2.jdbc.SybStatement.updateLoop(SybStatement.java:1615)
at com.sybase.jdbc2.jdbc.SybStatement.executeUpdate(SybStatement.java:1598)
at com.sybase.jdbc2.jdbc.SybPreparedStatement.executeUpdate(SybPreparedStatement.java:89)
at com.sybase.jdbc2.tds.Tds.setOption(Tds.java:1111)
at com.sybase.jdbc2.jdbc.SybConnection.setAutoCommit(SybConnection.java:634)
at net.sf.hibernate.id.TableGenerator.generate(TableGenerator.java:85)
at net.sf.hibernate.id.TableHiLoGenerator.generate(TableHiLoGenerator.java:59)
at net.sf.hibernate.impl.SessionImpl.saveWithGeneratedIdentifier(SessionImpl.java:774)
at net.sf.hibernate.impl.SessionImpl.save(SessionImpl.java:747)
at net.sf.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:1397)
at net.sf.hibernate.engine.Cascades$4.cascade(Cascades.java:114)
at net.sf.hibernate.engine.Cascades.cascade(Cascades.java:436)
at net.sf.hibernate.engine.Cascades.cascadeCollection(Cascades.java:526)
at net.sf.hibernate.engine.Cascades.cascade(Cascades.java:452)
at net.sf.hibernate.engine.Cascades.cascade(Cascades.java:503)
at net.sf.hibernate.engine.Cascades.cascade(Cascades.java:482)
at net.sf.hibernate.impl.SessionImpl.doUpdate(SessionImpl.java:1484)
at net.sf.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:1402)
at edu.ucop.ers.domain.base._BaseRootDAO.saveOrUpdate(_BaseRootDAO.java:656)
at edu.ucop.ers.domain.base._BaseRootDAO.saveOrUpdate(_BaseRootDAO.java:638)
at edu.ucop.ers.domain.base.BaseCustomerDAO.saveOrUpdate(BaseCustomerDAO.java:84)
at edu.ucop.erstest.chain.AddOrderCommand.execute(AddOrderCommand.java:63)
at org.apache.commons.chain.impl.ChainBase.execute(ChainBase.java:166)
at edu.ucop.ers.erstest.HibernateCommandTest.testAddOrders(HibernateCommandTest.java:92)
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:154)
at junit.framework.TestCase.runBare(TestCase.java:127)
at junit.framework.TestResult$1.protect(TestResult.java:106)
at junit.framework.TestResult.runProtected(TestResult.java:124)
at junit.framework.TestResult.run(TestResult.java:109)
at junit.framework.TestCase.run(TestCase.java:118)
at junit.framework.TestSuite.runTest(TestSuite.java:208)
at junit.framework.TestSuite.run(TestSuite.java:203)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:421)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:305)
at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:186)
09:37:01,527 WARN JDBCExceptionReporter:57 - SQL Error: 226, SQLState: ZZZZZ
09:37:01,527 ERROR JDBCExceptionReporter:58 - SET CHAINED command not allowed within multi-statement transaction.

09:37:01,527 DEBUG JDBCTransaction:82 - rollback
09:37:01,527 DEBUG SessionImpl:594 - transaction completion
09:37:01,527 DEBUG SessionImpl:576 - closing session
09:37:01,527 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,527 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 2
09:37:01,527 DEBUG SessionImpl:594 - transaction completion
09:37:01,547 DEBUG SessionImpl:558 - opened session
09:37:01,547 DEBUG SessionImpl:1536 - find: from Customer where customer_number=6
09:37:01,547 DEBUG SessionImpl:2266 - flushing session
09:37:01,547 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:01,547 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:01,547 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:01,547 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:01,547 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:01,547 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:01,547 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number=6
09:37:01,547 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,547 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,547 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,547 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 1
09:37:01,557 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,557 DEBUG BatcherImpl:252 - preparing statement
09:37:01,557 DEBUG Loader:277 - processing result set
09:37:01,557 DEBUG Loader:294 - done processing result set (0 rows)
09:37:01,557 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,557 DEBUG BatcherImpl:272 - closing statement
09:37:01,557 DEBUG Loader:314 - total objects hydrated: 0
09:37:01,557 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,557 DEBUG SessionImpl:576 - closing session
09:37:01,557 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,557 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 2
09:37:01,557 DEBUG SessionImpl:594 - transaction completion
09:37:01,627 DEBUG SessionImpl:558 - opened session
09:37:01,637 DEBUG SessionImpl:1536 - find: from Customer where customer_number=6
09:37:01,637 DEBUG SessionImpl:2266 - flushing session
09:37:01,637 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:01,637 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:01,637 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:01,637 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:01,637 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:01,637 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:01,637 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number=6
09:37:01,637 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,637 DEBUG SessionImpl:3423 - running Session.finalize()
09:37:01,637 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,637 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,637 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 1
09:37:01,637 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,637 DEBUG BatcherImpl:252 - preparing statement
09:37:01,648 DEBUG Loader:277 - processing result set
09:37:01,648 DEBUG Loader:294 - done processing result set (0 rows)
09:37:01,648 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,648 DEBUG BatcherImpl:272 - closing statement
09:37:01,648 DEBUG Loader:314 - total objects hydrated: 0
09:37:01,648 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,648 DEBUG SessionImpl:576 - closing session
09:37:01,648 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,648 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 2
09:37:01,648 DEBUG SessionImpl:594 - transaction completion
09:37:01,658 DEBUG SessionImpl:558 - opened session
09:37:01,658 DEBUG SessionImpl:1536 - find: from Customer where customer_number=6
09:37:01,658 DEBUG SessionImpl:2266 - flushing session
09:37:01,658 DEBUG SessionImpl:2459 - Flushing entities and processing referenced collections
09:37:01,658 DEBUG SessionImpl:2800 - Processing unreferenced collections
09:37:01,658 DEBUG SessionImpl:2814 - Scheduling collection removes/(re)creates/updates
09:37:01,658 DEBUG SessionImpl:2290 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
09:37:01,658 DEBUG SessionImpl:2295 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
09:37:01,658 DEBUG SessionImpl:3423 - running Session.finalize()
09:37:01,658 DEBUG SessionImpl:1827 - Dont need to execute flush
09:37:01,658 DEBUG QueryTranslator:207 - HQL: from edu.ucop.ers.domain.Customer where customer_number=6
09:37:01,658 DEBUG QueryTranslator:208 - SQL: select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,658 DEBUG BatcherImpl:203 - about to open: 0 open PreparedStatements, 0 open ResultSets
09:37:01,658 DEBUG DriverManagerConnectionProvider:84 - total checked-out connections: 0
09:37:01,658 DEBUG DriverManagerConnectionProvider:90 - using pooled JDBC connection, pool size: 1
09:37:01,658 DEBUG SQL:229 - select customer0_.id as id, customer0_.customer_zip as customer2_, customer0_.customer_number as customer3_, customer0_.customer_name as customer4_ from WBCustomer customer0_ where (customer_number=6 )
09:37:01,668 DEBUG BatcherImpl:252 - preparing statement
09:37:01,668 DEBUG Loader:277 - processing result set
09:37:01,668 DEBUG Loader:294 - done processing result set (0 rows)
09:37:01,668 DEBUG BatcherImpl:210 - done closing: 0 open PreparedStatements, 0 open ResultSets
09:37:01,668 DEBUG BatcherImpl:272 - closing statement
09:37:01,668 DEBUG Loader:314 - total objects hydrated: 0
09:37:01,668 DEBUG SessionImpl:3149 - initializing non-lazy collections
09:37:01,668 DEBUG SessionImpl:576 - closing session
09:37:01,668 DEBUG SessionImpl:3371 - disconnecting session
09:37:01,668 DEBUG DriverManagerConnectionProvider:120 - returning connection to pool, pool size: 2
09:37:01,668 DEBUG SessionImpl:594 - transaction completion

Name and version of the database you are using:
Sybase 12.5
The generated SQL (show_sql=true):

Debug level Hibernate log excerpt:
debug