Hibernate version:3.1

My problem is i want to update the association table when ever a new intrument is associated to the portfolio but hibernate is first deleting the old records from the relation table the inserting the new records. any help plz
Mapping documents:

<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<hibernate-mapping package="DTO">
<class name="MainClass" table="UIBS_INSTRUMENT_PORTFOLIO">
<id name="portid" column="port_id" type="integer">
<generator class="assigned"></generator>
</id>
<property name="longname" column="longname"></property>
<property name="isactive" column="isactive" type="boolean"></property>
<property name="shortname" column="shortname"></property>
<property name="userid" column="userid"></property>
<property name="isshared" column="isshared" type="boolean"></property>


<set name="intrument" table="UIBS_INSTRUMENT_PORTFOLIO_ITEM">
<key update="true">
<column name="PORTFOLIOID" />
</key>
<composite-element class="inst">
<property name="id" column="instrumentid"></property>
</composite-element>
</set>
</class>
</hibernate-mapping>



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

MainClass mainc = new MainClass();
inst ins=new inst();
Set setins=new HashSet();

ins.setId("8000");

setins.add(ins);

mainc.setIntrument(setins);
mainc.setIsactive(true);
mainc.setIsshared(true);
mainc.setLongname("milky");
mainc.setPortid(10000);
mainc.setShortname("devvv");
mainc.setUserid("Don");

session.saveOrUpdate(mainc);
//session.flush();
tx1.commit();
session.close();



Full stack trace of any exception that occurs:

Name and version of the database you are using:
oracle 9i


The generated SQL (show_sql=true):

Hibernate: select mainclass_.port_id, mainclass_.longname as longname0_, mainclass_.isactive as isactive0_, mainclass_.shortname as shortname0_, mainclass_.userid as userid0_, mainclass_.isshared as isshared0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass_ where mainclass_.port_id=?
Hibernate: delete from UIBS_INSTRUMENT_PORTFOLIO_ITEM where PORTFOLIOID=?
Hibernate: insert into UIBS_INSTRUMENT_PORTFOLIO_ITEM (PORTFOLIOID, instrumentid) values (?, ?)




Debug level Hibernate log excerpt:

11:22:30,352 main INFO Environment:479 - Hibernate 3.1.2
11:22:30,368 main INFO Environment:509 - hibernate.properties not found
11:22:30,368 main INFO Environment:525 - using CGLIB reflection optimizer
11:22:30,368 main INFO Environment:555 - using JDK 1.4 java.sql.Timestamp handling
11:22:30,462 main INFO Configuration:1308 - configuring from resource: /hibernate.cfg.xml
11:22:30,462 main INFO Configuration:1285 - Configuration resource: /hibernate.cfg.xml
11:22:30,790 main DEBUG DTDEntityResolver:42 - trying to locate http://hibernate.sourceforge.net/hibern ... on-3.0.dtd in classpath under org/hibernate/
11:22:30,790 main DEBUG DTDEntityResolver:56 - found http://hibernate.sourceforge.net/hibern ... on-3.0.dtd in classpath
11:22:30,868 main DEBUG Configuration:1269 - hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver
11:22:30,868 main DEBUG Configuration:1269 - hibernate.connection.url=jdbc:oracle:thin:@//128.8.14.126:1521/DGEN01.UK.HIBM.HSBC
11:22:30,868 main DEBUG Configuration:1269 - hibernate.connection.username=RDS
11:22:30,868 main DEBUG Configuration:1269 - hibernate.connection.password=rds9900
11:22:30,868 main DEBUG Configuration:1269 - hibernate.dialect=org.hibernate.dialect.Oracle9Dialect
11:22:30,868 main DEBUG Configuration:1269 - hibernate.connection.pool_size=10
11:22:30,868 main DEBUG Configuration:1269 - hibernate.jdbc.batch_size=20
11:22:30,868 main DEBUG Configuration:1269 - show_sql=true
11:22:30,868 main DEBUG Configuration:1464 - null<-org.dom4j.tree.DefaultAttribute@1c0e45a [Attribute: name resource value "MainClass.hbm.xml"]
11:22:30,868 main INFO Configuration:469 - Reading mappings from resource: MainClass.hbm.xml
11:22:30,868 main DEBUG DTDEntityResolver:42 - trying to locate http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath under org/hibernate/
11:22:30,884 main DEBUG DTDEntityResolver:56 - found http://hibernate.sourceforge.net/hibern ... ng-3.0.dtd in classpath
11:22:31,055 main INFO HbmBinder:309 - Mapping class: DTO.MainClass -> UIBS_INSTRUMENT_PORTFOLIO
11:22:31,071 main DEBUG HbmBinder:1261 - Mapped property: portid -> port_id
11:22:31,087 main DEBUG HbmBinder:1261 - Mapped property: longname -> longname
11:22:31,087 main DEBUG HbmBinder:1261 - Mapped property: isactive -> isactive
11:22:31,102 main DEBUG HbmBinder:1261 - Mapped property: shortname -> shortname
11:22:31,102 main DEBUG HbmBinder:1261 - Mapped property: userid -> userid
11:22:31,102 main DEBUG HbmBinder:1261 - Mapped property: isshared -> isshared
11:22:31,102 main INFO HbmBinder:1397 - Mapping collection: DTO.MainClass.intrument -> UIBS_INSTRUMENT_PORTFOLIO_ITEM
11:22:31,102 main DEBUG HbmBinder:1261 - Mapped property: intrument
11:22:31,102 main INFO Configuration:1419 - Configured SessionFactory: null
11:22:31,118 main DEBUG Configuration:1420 - properties: {hibernate.connection.password=rds9900, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\j2sdk1.4.2_08\jre\bin, java.vm.version=1.4.2_08-b03, hibernate.connection.username=RDS, 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:\newWorksapace2\test, java.runtime.version=1.4.2_08-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\j2sdk1.4.2_08\jre\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\43231399\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:\j2sdk1.4.2_08\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files\IBM\WebSphere MQ\Java\lib;C:\j2sdk1.4.2_08\bin;C:\Program Files\Oracle\jre\1.3.1\bin;C:\oracle\ora92\bin;C:\Program Files\Oracle\jre\1.1.8\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;c:\WINDOWS\CWD_Build\Scramble;c:\WINDOWS\CWD_Build\Components\Dlls;C:\j2sdk1.4.2_08\lib\log4j-1.2.13.jar;C:\Program Files\IBM\WebSphere MQ\bin;C:\Program Files\Rational\common;C:\Program Files\Rational\ClearCase\bin;C:\Program Files\1E\SMSNomad\;C:\PROGRA~1\F-Secure\Ssh;C:\Fiorano\FioranoSOA2006\antscripts\bin;C:\j2sdk1.4.2_08\, java.specification.name=Java Platform API Specification, java.class.version=48.0, hibernate.connection.pool_size=10, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=5.1, user.home=C:\Documents and Settings\43231399, user.timezone=Asia/Calcutta, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, show_sql=true, user.name=vivek.d, java.class.path=C:\newWorksapace2\test\resource;C:\newWorksapace2\test;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-antlr-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-junit-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-launcher-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\antlr-2.7.6rc1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-swing-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\asm.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\asm-attrs.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\c3p0-0.9.0.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\cglib-2.1.3.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\classes12.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\cleanimports.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\commons-collections-2.1.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\commons-logging-1.0.4.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\concurrent-1.3.2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\connector.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\dom4j-1.6.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ehcache-1.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\hibernate3.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jaas.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jacc-1_0-fr.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jaxen-1.1-beta-7.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-cache.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-common.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-jmx.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-system.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jdbc2_0-stdext.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jgroups-2.2.8.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jta.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\junit-3.8.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\log4j-1.2.11.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ojdbc14.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\oscache-2.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\proxool-0.8.3.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\swarmcache-1.0rc2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\syndiag2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\versioncheck.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\xerces-2.6.2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\xml-apis.jar;C:\newWorksapace2\test\resource, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\j2sdk1.4.2_08\jre, sun.arch.data.model=32, hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, hibernate.connection.url=jdbc:oracle:thin:@//128.8.14.126:1521/DGEN01.UK.HIBM.HSBC, 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_08, java.ext.dirs=C:\j2sdk1.4.2_08\jre\lib\ext, sun.boot.class.path=C:\j2sdk1.4.2_08\jre\lib\rt.jar;C:\j2sdk1.4.2_08\jre\lib\i18n.jar;C:\j2sdk1.4.2_08\jre\lib\sunrsasign.jar;C:\j2sdk1.4.2_08\jre\lib\jsse.jar;C:\j2sdk1.4.2_08\jre\lib\jce.jar;C:\j2sdk1.4.2_08\jre\lib\charsets.jar;C:\j2sdk1.4.2_08\jre\classes, java.vendor=Sun Microsystems Inc., hibernate.jdbc.batch_size=20, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, sun.cpu.isalist=pentium i486 i386}
11:22:31,118 main DEBUG Configuration:1167 - Preparing to build session factory with filters : {}
11:22:31,118 main DEBUG Configuration:1002 - processing extends queue
11:22:31,118 main DEBUG Configuration:1006 - processing collection mappings
11:22:31,118 main DEBUG CollectionSecondPass:33 - Second pass for collection: DTO.MainClass.intrument
11:22:31,118 main DEBUG HbmBinder:1261 - Mapped property: id -> instrumentid
11:22:31,134 main DEBUG CollectionSecondPass:49 - Mapped collection key: PORTFOLIOID, element: instrumentid
11:22:31,134 main DEBUG Configuration:1017 - processing native query and ResultSetMapping mappings
11:22:31,134 main DEBUG Configuration:1025 - processing association property references
11:22:31,134 main DEBUG Configuration:1047 - processing foreign key constraints
11:22:31,134 main DEBUG Configuration:1130 - resolving reference to class: DTO.MainClass
11:22:31,462 main INFO DriverManagerConnectionProvider:41 - Using Hibernate built-in connection pool (not for production use!)
11:22:31,462 main INFO DriverManagerConnectionProvider:42 - Hibernate connection pool size: 10
11:22:31,462 main INFO DriverManagerConnectionProvider:45 - autocommit mode: false
11:22:31,524 main INFO DriverManagerConnectionProvider:80 - using driver: oracle.jdbc.driver.OracleDriver at URL: jdbc:oracle:thin:@//128.8.14.126:1521/DGEN01.UK.HIBM.HSBC
11:22:31,524 main INFO DriverManagerConnectionProvider:83 - connection properties: {user=RDS, password=rds9900}
11:22:31,524 main DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
11:22:31,524 main DEBUG DriverManagerConnectionProvider:109 - opening new JDBC connection
11:22:38,806 main DEBUG DriverManagerConnectionProvider:115 - created connection to: jdbc:oracle:thin:@//128.8.14.126:1521/DGEN01.UK.HIBM.HSBC, Isolation Level: 2
11:22:38,806 main DEBUG SettingsFactory:299 - could not get database version from JDBC metadata
11:22:38,806 main INFO SettingsFactory:77 - RDBMS: Oracle, version: Oracle9i Enterprise Edition Release 9.2.0.6.0 - 64bit Production
With the Partitioning, OLAP and Oracle Data Mining options
JServer Release 9.2.0.6.0 - Production
11:22:38,806 main INFO SettingsFactory:78 - JDBC driver: Oracle JDBC driver, version: 9.2.0.1.0
11:22:38,806 main DEBUG DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
11:22:38,837 main INFO Dialect:103 - Using dialect: org.hibernate.dialect.Oracle9Dialect
11:22:38,837 main INFO TransactionFactoryFactory:31 - Using default transaction strategy (direct JDBC transactions)
11:22:38,837 main INFO TransactionManagerLookupFactory:33 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
11:22:38,837 main INFO SettingsFactory:125 - Automatic flush during beforeCompletion(): disabled
11:22:38,837 main INFO SettingsFactory:129 - Automatic session close at end of transaction: disabled
11:22:38,837 main INFO SettingsFactory:136 - JDBC batch size: 20
11:22:38,837 main INFO SettingsFactory:139 - JDBC batch updates for versioned data: disabled
11:22:38,837 main INFO SettingsFactory:144 - Scrollable result sets: enabled
11:22:38,853 main DEBUG SettingsFactory:148 - Wrap result sets: disabled
11:22:38,853 main INFO SettingsFactory:152 - JDBC3 getGeneratedKeys(): disabled
11:22:38,853 main INFO SettingsFactory:160 - Connection release mode: auto
11:22:38,853 main INFO SettingsFactory:187 - Default batch fetch size: 1
11:22:38,853 main INFO SettingsFactory:191 - Generate SQL with comments: disabled
11:22:38,853 main INFO SettingsFactory:195 - Order SQL updates by primary key: disabled
11:22:38,853 main INFO SettingsFactory:338 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
11:22:38,853 main INFO ASTQueryTranslatorFactory:24 - Using ASTQueryTranslatorFactory
11:22:38,853 main INFO SettingsFactory:203 - Query language substitutions: {}
11:22:38,853 main INFO SettingsFactory:209 - Second-level cache: enabled
11:22:38,853 main INFO SettingsFactory:213 - Query cache: disabled
11:22:38,853 main INFO SettingsFactory:325 - Cache provider: org.hibernate.cache.EhCacheProvider
11:22:38,853 main INFO SettingsFactory:228 - Optimize cache for minimal puts: disabled
11:22:38,868 main INFO SettingsFactory:237 - Structured second-level cache entries: disabled
11:22:38,868 main DEBUG SQLExceptionConverterFactory:52 - Using dialect defined converter
11:22:38,868 main INFO SettingsFactory:257 - Echoing all SQL to stdout
11:22:38,868 main INFO SettingsFactory:264 - Statistics: disabled
11:22:38,868 main INFO SettingsFactory:268 - Deleted entity synthetic identifier rollback: disabled
11:22:38,868 main INFO SettingsFactory:283 - Default entity-mode: pojo
11:22:38,915 main INFO SessionFactoryImpl:153 - building session factory
11:22:38,931 main DEBUG SessionFactoryImpl:164 - Session factory constructed with filter configurations : {}
11:22:38,931 main DEBUG SessionFactoryImpl:167 - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=rds9900, sun.boot.library.path=C:\j2sdk1.4.2_08\jre\bin, java.vm.version=1.4.2_08-b03, hibernate.connection.username=RDS, 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:\newWorksapace2\test, java.runtime.version=1.4.2_08-b03, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\j2sdk1.4.2_08\jre\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\43231399\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:\j2sdk1.4.2_08\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\Program Files\IBM\WebSphere MQ\Java\lib;C:\j2sdk1.4.2_08\bin;C:\Program Files\Oracle\jre\1.3.1\bin;C:\oracle\ora92\bin;C:\Program Files\Oracle\jre\1.1.8\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;c:\WINDOWS\CWD_Build\Scramble;c:\WINDOWS\CWD_Build\Components\Dlls;C:\j2sdk1.4.2_08\lib\log4j-1.2.13.jar;C:\Program Files\IBM\WebSphere MQ\bin;C:\Program Files\Rational\common;C:\Program Files\Rational\ClearCase\bin;C:\Program Files\1E\SMSNomad\;C:\PROGRA~1\F-Secure\Ssh;C:\Fiorano\FioranoSOA2006\antscripts\bin;C:\j2sdk1.4.2_08\, java.specification.name=Java Platform API Specification, java.class.version=48.0, hibernate.connection.pool_size=10, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=5.1, user.home=C:\Documents and Settings\43231399, user.timezone=Asia/Calcutta, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, show_sql=true, java.class.path=C:\newWorksapace2\test\resource;C:\newWorksapace2\test;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-antlr-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-junit-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-launcher-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\antlr-2.7.6rc1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ant-swing-1.6.5.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\asm.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\asm-attrs.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\c3p0-0.9.0.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\cglib-2.1.3.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\classes12.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\cleanimports.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\commons-collections-2.1.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\commons-logging-1.0.4.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\concurrent-1.3.2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\connector.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\dom4j-1.6.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ehcache-1.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\hibernate3.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jaas.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jacc-1_0-fr.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jaxen-1.1-beta-7.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-cache.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-common.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-jmx.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jboss-system.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jdbc2_0-stdext.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jgroups-2.2.8.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\jta.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\junit-3.8.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\log4j-1.2.11.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\ojdbc14.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\oscache-2.1.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\proxool-0.8.3.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\swarmcache-1.0rc2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\syndiag2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\versioncheck.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\xerces-2.6.2.jar;C:\newWorksapace2\HibernateBatchUpdate\lib\xml-apis.jar;C:\newWorksapace2\test\resource, user.name=vivek.d, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=C:\j2sdk1.4.2_08\jre, hibernate.connection.url=jdbc:oracle:thin:@//128.8.14.126:1521/DGEN01.UK.HIBM.HSBC, hibernate.dialect=org.hibernate.dialect.Oracle9Dialect, java.specification.vendor=Sun Microsystems Inc., user.language=en, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, hibernate.cglib.use_reflection_optimizer=true, java.version=1.4.2_08, java.ext.dirs=C:\j2sdk1.4.2_08\jre\lib\ext, sun.boot.class.path=C:\j2sdk1.4.2_08\jre\lib\rt.jar;C:\j2sdk1.4.2_08\jre\lib\i18n.jar;C:\j2sdk1.4.2_08\jre\lib\sunrsasign.jar;C:\j2sdk1.4.2_08\jre\lib\jsse.jar;C:\j2sdk1.4.2_08\jre\lib\jce.jar;C:\j2sdk1.4.2_08\jre\lib\charsets.jar;C:\j2sdk1.4.2_08\jre\classes, java.vendor=Sun Microsystems Inc., hibernate.jdbc.batch_size=20, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.isalist=pentium i486 i386}
11:22:38,931 main DEBUG CacheManager:191 - Creating new CacheManager with default config
11:22:38,931 main DEBUG CacheManager:164 - Configuring ehcache from classpath.
11:22:38,931 main WARN Configurator:126 - No configuration found. Configuring ehcache from ehcache-failsafe.xml found in the classpath: jar:file:/C:/newWorksapace2/HibernateBatchUpdate/lib/ehcache-1.1.jar!/ehcache-failsafe.xml
11:22:38,946 main DEBUG Configuration$DiskStore:185 - Disk Store Path: C:\DOCUME~1\43231399\LOCALS~1\Temp\
11:22:39,243 main DEBUG AbstractEntityPersister:2447 - Static SQL for entity: DTO.MainClass
11:22:39,259 main DEBUG AbstractEntityPersister:2449 - Version select: select port_id from UIBS_INSTRUMENT_PORTFOLIO where port_id =?
11:22:39,259 main DEBUG AbstractEntityPersister:2450 - Snapshot select: select mainclass_.port_id, mainclass_.longname as longname0_, mainclass_.isactive as isactive0_, mainclass_.shortname as shortname0_, mainclass_.userid as userid0_, mainclass_.isshared as isshared0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass_ where mainclass_.port_id=?
11:22:39,259 main DEBUG AbstractEntityPersister:2452 - Insert 0: insert into UIBS_INSTRUMENT_PORTFOLIO (longname, isactive, shortname, userid, isshared, port_id) values (?, ?, ?, ?, ?, ?)
11:22:39,259 main DEBUG AbstractEntityPersister:2453 - Update 0: update UIBS_INSTRUMENT_PORTFOLIO set longname=?, isactive=?, shortname=?, userid=?, isshared=? where port_id=?
11:22:39,259 main DEBUG AbstractEntityPersister:2454 - Delete 0: delete from UIBS_INSTRUMENT_PORTFOLIO where port_id=?
11:22:39,259 main DEBUG AbstractCollectionPersister:511 - Static SQL for collection: DTO.MainClass.intrument
11:22:39,275 main DEBUG AbstractCollectionPersister:512 - Row insert: insert into UIBS_INSTRUMENT_PORTFOLIO_ITEM (PORTFOLIOID, instrumentid) values (?, ?)
11:22:39,275 main DEBUG AbstractCollectionPersister:513 - Row update: update UIBS_INSTRUMENT_PORTFOLIO_ITEM set instrumentid=? where PORTFOLIOID=? and instrumentid=?
11:22:39,275 main DEBUG AbstractCollectionPersister:514 - Row delete: delete from UIBS_INSTRUMENT_PORTFOLIO_ITEM where PORTFOLIOID=? and instrumentid=?
11:22:39,275 main DEBUG AbstractCollectionPersister:515 - One-shot delete: delete from UIBS_INSTRUMENT_PORTFOLIO_ITEM where PORTFOLIOID=?
11:22:39,306 main DEBUG EntityLoader:79 - Static select for entity DTO.MainClass: select mainclass0_.port_id as port1_0_0_, mainclass0_.longname as longname0_0_, mainclass0_.isactive as isactive0_0_, mainclass0_.shortname as shortname0_0_, mainclass0_.userid as userid0_0_, mainclass0_.isshared as isshared0_0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass0_ where mainclass0_.port_id=?
11:22:39,306 main DEBUG EntityLoader:79 - Static select for entity DTO.MainClass: select mainclass0_.port_id as port1_0_0_, mainclass0_.longname as longname0_0_, mainclass0_.isactive as isactive0_0_, mainclass0_.shortname as shortname0_0_, mainclass0_.userid as userid0_0_, mainclass0_.isshared as isshared0_0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass0_ where mainclass0_.port_id=?
11:22:39,306 main DEBUG EntityLoader:79 - Static select for entity DTO.MainClass: select mainclass0_.port_id as port1_0_0_, mainclass0_.longname as longname0_0_, mainclass0_.isactive as isactive0_0_, mainclass0_.shortname as shortname0_0_, mainclass0_.userid as userid0_0_, mainclass0_.isshared as isshared0_0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass0_ where mainclass0_.port_id=? for update
11:22:39,306 main DEBUG EntityLoader:79 - Static select for entity DTO.MainClass: select mainclass0_.port_id as port1_0_0_, mainclass0_.longname as longname0_0_, mainclass0_.isactive as isactive0_0_, mainclass0_.shortname as shortname0_0_, mainclass0_.userid as userid0_0_, mainclass0_.isshared as isshared0_0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass0_ where mainclass0_.port_id=? for update nowait
11:22:39,322 main DEBUG EntityLoader:67 - Static select for action ACTION_MERGE on entity DTO.MainClass: select mainclass0_.port_id as port1_0_0_, mainclass0_.longname as longname0_0_, mainclass0_.isactive as isactive0_0_, mainclass0_.shortname as shortname0_0_, mainclass0_.userid as userid0_0_, mainclass0_.isshared as isshared0_0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass0_ where mainclass0_.port_id=?
11:22:39,322 main DEBUG EntityLoader:67 - Static select for action ACTION_REFRESH on entity DTO.MainClass: select mainclass0_.port_id as port1_0_0_, mainclass0_.longname as longname0_0_, mainclass0_.isactive as isactive0_0_, mainclass0_.shortname as shortname0_0_, mainclass0_.userid as userid0_0_, mainclass0_.isshared as isshared0_0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass0_ where mainclass0_.port_id=?
11:22:39,337 main DEBUG BasicCollectionLoader:64 - Static select for collection DTO.MainClass.intrument: select intrument0_.PORTFOLIOID as PORTFOLI1_0_, intrument0_.instrumentid as instrume2_0_ from UIBS_INSTRUMENT_PORTFOLIO_ITEM intrument0_ where intrument0_.PORTFOLIOID=?
11:22:39,337 main DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory
11:22:39,337 main DEBUG SessionFactoryObjectFactory:76 - registered: 08b2e8be0cf6a3b3010cf6a3b5a90000 (unnamed)
11:22:39,337 main INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
11:22:39,337 main DEBUG SessionFactoryImpl:292 - instantiated session factory
11:22:39,337 main DEBUG SessionFactoryImpl:353 - Checking 0 named HQL queries
11:22:39,337 main DEBUG SessionFactoryImpl:373 - Checking 0 named SQL queries
this is after creating session factory object
11:22:39,400 main DEBUG SessionImpl:273 - opened session at timestamp: 4731654796709888
this is after opening session
11:22:39,400 main DEBUG JDBCTransaction:54 - begin
11:22:39,400 main DEBUG ConnectionManager:358 - opening JDBC connection
11:22:39,400 main DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
11:22:39,400 main DEBUG DriverManagerConnectionProvider:99 - using pooled JDBC connection, pool size: 0
11:22:39,400 main DEBUG JDBCTransaction:59 - current autocommit status: false
11:22:39,400 main DEBUG JDBCContext:190 - after transaction begin
this is after begin transaction
11:22:39,400 main DEBUG IdentifierValue:153 - id unsaved-value strategy UNDEFINED
11:22:39,400 main DEBUG AbstractEntityPersister:975 - Getting current persistent state for: [DTO.MainClass#10000]
11:22:39,415 main DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
11:22:39,415 main DEBUG SQL:346 - select mainclass_.port_id, mainclass_.longname as longname0_, mainclass_.isactive as isactive0_, mainclass_.shortname as shortname0_, mainclass_.userid as userid0_, mainclass_.isshared as isshared0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass_ where mainclass_.port_id=?
Hibernate: select mainclass_.port_id, mainclass_.longname as longname0_, mainclass_.isactive as isactive0_, mainclass_.shortname as shortname0_, mainclass_.userid as userid0_, mainclass_.isshared as isshared0_ from UIBS_INSTRUMENT_PORTFOLIO mainclass_ where mainclass_.port_id=?
11:22:39,415 main DEBUG AbstractBatcher:424 - preparing statement
11:22:39,712 main DEBUG IntegerType:79 - binding '10000' to parameter: 1
11:22:40,540 main DEBUG StringType:123 - returning 'milky' as column: longname0_
11:22:40,540 main DEBUG BooleanType:123 - returning 'false' as column: isactive0_
11:22:40,556 main DEBUG StringType:123 - returning 'devvv' as column: shortname0_
11:22:40,556 main DEBUG StringType:123 - returning 'Don' as column: userid0_
11:22:40,556 main DEBUG BooleanType:123 - returning 'true' as column: isshared0_
11:22:41,681 main DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
11:22:41,681 main DEBUG AbstractBatcher:470 - closing statement
11:22:41,962 main DEBUG AbstractSaveEventListener:471 - detached instance of: DTO.MainClass
11:22:41,962 main DEBUG DefaultSaveOrUpdateEventListener:202 - updating detached instance
11:22:41,962 main DEBUG DefaultSaveOrUpdateEventListener:248 - updating [DTO.MainClass#10000]
11:22:41,962 main DEBUG ReattachVisitor:60 - collection dereferenced while transient [DTO.MainClass.intrument#10000]
11:22:41,978 main DEBUG DefaultSaveOrUpdateEventListener:297 - updating [DTO.MainClass#10000]
11:22:41,978 main DEBUG JDBCTransaction:103 - commit
11:22:41,978 main DEBUG SessionImpl:355 - automatically flushing session
11:22:41,978 main DEBUG AbstractFlushingEventListener:58 - flushing session
11:22:41,978 main DEBUG AbstractFlushingEventListener:111 - processing flush-time cascades
11:22:41,978 main DEBUG AbstractFlushingEventListener:153 - dirty checking collections
11:22:41,978 main DEBUG AbstractFlushingEventListener:170 - Flushing entities and processing referenced collections
11:22:41,994 main DEBUG WrapVisitor:87 - Wrapped collection in role: DTO.MainClass.intrument
11:22:41,994 main DEBUG Collections:176 - Collection found: [DTO.MainClass.intrument#10000], was: [<unreferenced>] (initialized)
11:22:41,994 main DEBUG AbstractFlushingEventListener:209 - Processing unreferenced collections
11:22:41,994 main DEBUG AbstractFlushingEventListener:223 - Scheduling collection removes/(re)creates/updates
11:22:41,994 main DEBUG AbstractFlushingEventListener:85 - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
11:22:41,994 main DEBUG AbstractFlushingEventListener:91 - Flushed: 1 (re)creations, 0 updates, 1 removals to 1 collections
11:22:41,994 main DEBUG Printer:83 - listing entities:
11:22:42,009 main DEBUG Printer:90 - DTO.MainClass{isshared=true, userid=Don, intrument=[component[id]{id=8000}], longname=milky, portid=10000, isactive=false, shortname=devvv}
11:22:42,009 main DEBUG AbstractFlushingEventListener:289 - executing flush
11:22:42,009 main DEBUG AbstractCollectionPersister:947 - Deleting collection: [DTO.MainClass.intrument#10000]
11:22:42,009 main DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
11:22:42,009 main DEBUG SQL:346 - delete from UIBS_INSTRUMENT_PORTFOLIO_ITEM where PORTFOLIOID=?
Hibernate: delete from UIBS_INSTRUMENT_PORTFOLIO_ITEM where PORTFOLIOID=?
11:22:42,009 main DEBUG AbstractBatcher:424 - preparing statement
11:22:42,290 main DEBUG IntegerType:79 - binding '10000' to parameter: 1
11:22:42,290 main DEBUG AbstractBatcher:28 - Adding to batch
11:22:42,290 main DEBUG AbstractCollectionPersister:977 - done deleting collection
11:22:42,290 main DEBUG AbstractBatcher:55 - Executing batch size: 1
11:22:42,556 main DEBUG AbstractBatcher:85 - success of batch update unknown: 0
11:22:42,556 main DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
11:22:42,556 main DEBUG AbstractBatcher:470 - closing statement
11:22:42,759 main DEBUG AbstractCollectionPersister:999 - Inserting collection: [DTO.MainClass.intrument#10000]
11:22:42,759 main DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
11:22:42,775 main DEBUG SQL:346 - insert into UIBS_INSTRUMENT_PORTFOLIO_ITEM (PORTFOLIOID, instrumentid) values (?, ?)
Hibernate: insert into UIBS_INSTRUMENT_PORTFOLIO_ITEM (PORTFOLIOID, instrumentid) values (?, ?)
11:22:42,775 main DEBUG AbstractBatcher:424 - preparing statement
11:22:42,962 main DEBUG IntegerType:79 - binding '10000' to parameter: 1
11:22:42,962 main DEBUG StringType:79 - binding '8000' to parameter: 2
11:22:42,962 main DEBUG AbstractBatcher:28 - Adding to batch
11:22:42,962 main DEBUG AbstractCollectionPersister:1045 - done inserting collection: 1 rows inserted
11:22:42,962 main DEBUG AbstractBatcher:55 - Executing batch size: 1
11:22:43,150 main DEBUG AbstractBatcher:85 - success of batch update unknown: 0
11:22:43,150 main DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
11:22:43,150 main DEBUG AbstractBatcher:470 - closing statement
11:22:43,353 main DEBUG AbstractFlushingEventListener:316 - post flush
11:22:43,353 main DEBUG JDBCContext:181 - before transaction completion
11:22:43,353 main DEBUG SessionImpl:411 - before transaction completion
11:22:43,587 main DEBUG JDBCTransaction:116 - committed JDBC Connection
11:22:43,587 main DEBUG JDBCContext:195 - after transaction completion
11:22:43,587 main DEBUG ConnectionManager:341 - aggressively releasing JDBC connection
11:22:43,587 main DEBUG ConnectionManager:378 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
11:22:43,587 main DEBUG DriverManagerConnectionProvider:129 - returning connection to pool, pool size: 1
11:22:43,587 main DEBUG SessionImpl:442 - after transaction completion
11:22:43,587 main DEBUG SessionImpl:291 - closing session
11:22:43,587 main DEBUG ConnectionManager:312 - connection already null in cleanup : no action