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

Hibernate JIRA | G. Wayne Kidd | 1 decade ago
  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.

    Not finding the right solution?
    Take a tour to get the most out of Samebug.

    Tired of useless tips?

    Automated exception search integrated into your IDE

    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