com.sybase.jdbc2.jdbc.SybSQLException: SET CHAINED command not allowed within multi-statement transaction.

Hibernate JIRA | G. Wayne Kidd | 1 decade ago
tip
Click on the to mark the solution that helps you, Samebug will learn from it.
As a community member, you’ll be rewarded for you help.
  1. 0

    When the debug from below is executed in 2.1.3, the code completes normally. When executed with the 2.1.7c Hibernate, the failure shown here 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/hibernate-configuration-2.0.dtd in classpath under net/sf/hibernate/ 09:36:59,835 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-configuration-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/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 09:36:59,895 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-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/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 09:37:00,075 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-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

    Hibernate JIRA | 1 decade ago | G. Wayne Kidd
    com.sybase.jdbc2.jdbc.SybSQLException: SET CHAINED command not allowed within multi-statement transaction.
  2. 0

    When the debug from below is executed in 2.1.3, the code completes normally. When executed with the 2.1.7c Hibernate, the failure shown here 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/hibernate-configuration-2.0.dtd in classpath under net/sf/hibernate/ 09:36:59,835 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-configuration-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/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 09:36:59,895 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-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/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 09:37:00,075 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-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

    Hibernate JIRA | 1 decade ago | G. Wayne Kidd
    com.sybase.jdbc2.jdbc.SybSQLException: SET CHAINED command not allowed within multi-statement transaction.
  3. 0

    Assertion error.

    Oracle Community | 1 decade ago | 3004
    com.sybase.jdbc2.jdbc.SybSQLException: SET CHAINED command not allowed within multi-statement transaction.
  4. Speed up your debug routine!

    Automated exception search integrated into your IDE

  5. 0

    Error configuring AutoCommit

    GitHub | 2 years ago | GoogleCodeExporter
    com.sybase.jdbc2.jdbc.SybSQLException: SET CHAINED command not allowed within multi-statement transaction.

    Root Cause Analysis

    1. com.sybase.jdbc2.jdbc.SybSQLException

      SET CHAINED command not allowed within multi-statement transaction.

      at com.sybase.jdbc2.tds.Tds.processEed()
    2. com.sybase.jdbc2
      SybConnection.setAutoCommit
      1. com.sybase.jdbc2.tds.Tds.processEed(Tds.java:2636)
      2. com.sybase.jdbc2.tds.Tds.nextResult(Tds.java:1996)
      3. com.sybase.jdbc2.jdbc.ResultGetter.nextResult(ResultGetter.java:69)
      4. com.sybase.jdbc2.jdbc.SybStatement.nextResult(SybStatement.java:204)
      5. com.sybase.jdbc2.jdbc.SybStatement.nextResult(SybStatement.java:187)
      6. com.sybase.jdbc2.jdbc.SybStatement.updateLoop(SybStatement.java:1615)
      7. com.sybase.jdbc2.jdbc.SybStatement.executeUpdate(SybStatement.java:1598)
      8. com.sybase.jdbc2.jdbc.SybPreparedStatement.executeUpdate(SybPreparedStatement.java:89)
      9. com.sybase.jdbc2.tds.Tds.setOption(Tds.java:1111)
      10. com.sybase.jdbc2.jdbc.SybConnection.setAutoCommit(SybConnection.java:634)
      10 frames
    3. net.sf.hibernate
      SessionImpl.saveOrUpdate
      1. net.sf.hibernate.id.TableGenerator.generate(TableGenerator.java:85)
      2. net.sf.hibernate.id.TableHiLoGenerator.generate(TableHiLoGenerator.java:59)
      3. net.sf.hibernate.impl.SessionImpl.saveWithGeneratedIdentifier(SessionImpl.java:774)
      4. net.sf.hibernate.impl.SessionImpl.save(SessionImpl.java:747)
      5. net.sf.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:1397)
      6. net.sf.hibernate.engine.Cascades$4.cascade(Cascades.java:114)
      7. net.sf.hibernate.engine.Cascades.cascade(Cascades.java:436)
      8. net.sf.hibernate.engine.Cascades.cascadeCollection(Cascades.java:526)
      9. net.sf.hibernate.engine.Cascades.cascade(Cascades.java:452)
      10. net.sf.hibernate.engine.Cascades.cascade(Cascades.java:503)
      11. net.sf.hibernate.engine.Cascades.cascade(Cascades.java:482)
      12. net.sf.hibernate.impl.SessionImpl.doUpdate(SessionImpl.java:1484)
      13. net.sf.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:1402)
      13 frames
    4. edu.ucop.ers
      BaseCustomerDAO.saveOrUpdate
      1. edu.ucop.ers.domain.base._BaseRootDAO.saveOrUpdate(_BaseRootDAO.java:656)
      2. edu.ucop.ers.domain.base._BaseRootDAO.saveOrUpdate(_BaseRootDAO.java:638)
      3. edu.ucop.ers.domain.base.BaseCustomerDAO.saveOrUpdate(BaseCustomerDAO.java:84)
      3 frames
    5. edu.ucop.erstest
      AddOrderCommand.execute
      1. edu.ucop.erstest.chain.AddOrderCommand.execute(AddOrderCommand.java:63)
      1 frame
    6. Commons Chain
      ChainBase.execute
      1. org.apache.commons.chain.impl.ChainBase.execute(ChainBase.java:166)
      1 frame
    7. edu.ucop.ers
      HibernateCommandTest.testAddOrders
      1. edu.ucop.ers.erstest.HibernateCommandTest.testAddOrders(HibernateCommandTest.java:92)
      1 frame
    8. Java RT
      Method.invoke
      1. sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      2. sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
      3. sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
      4. java.lang.reflect.Method.invoke(Unknown Source)
      4 frames
    9. JUnit
      TestSuite.run
      1. junit.framework.TestCase.runTest(TestCase.java:154)
      2. junit.framework.TestCase.runBare(TestCase.java:127)
      3. junit.framework.TestResult$1.protect(TestResult.java:106)
      4. junit.framework.TestResult.runProtected(TestResult.java:124)
      5. junit.framework.TestResult.run(TestResult.java:109)
      6. junit.framework.TestCase.run(TestCase.java:118)
      7. junit.framework.TestSuite.runTest(TestSuite.java:208)
      8. junit.framework.TestSuite.run(TestSuite.java:203)
      8 frames
    10. JUnit3 Runner
      RemoteTestRunner.main
      1. org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:421)
      2. org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:305)
      3. org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:186)
      3 frames