Hibernate version: 2.1.7

Mapping documents:



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


Full stack trace of any exception that occurs:
[code]net.sf.hibernate.HibernateException: SQL insert, update or delete failed (row not found)
at net.sf.hibernate.impl.NonBatchingBatcher.addToBatch(NonBatchingBatcher.java:25)
at net.sf.hibernate.persister.EntityPersister.update(EntityPersister.java:684)
at net.sf.hibernate.persister.EntityPersister.update(EntityPersister.java:642)
at net.sf.hibernate.impl.ScheduledUpdate.execute(ScheduledUpdate.java:52)
at net.sf.hibernate.impl.SessionImpl.executeAll(SessionImpl.java:2418)
at net.sf.hibernate.impl.SessionImpl.execute(SessionImpl.java:2372)
at net.sf.hibernate.impl.SessionImpl.flush(SessionImpl.java:2240)
at net.sf.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:61)
at com.wachovia.evergreen.autorecon.bo.dao.AccountDAO.test(AccountDAO.java:153)
at com.wachovia.evergreen.autorecon.action.admin.AccountAction.save(AccountAction.java:34)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at com.wachovia.kryptonite.event.MethodAction.execute(MethodAction.java:68)
at com.wachovia.kryptonite.servlet.Controller.performAction(Controller.java:926)
at com.wachovia.kryptonite.servlet.Controller.service(Controller.java:242)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:237)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:75)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:186)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:214)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:198)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:152)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:66)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:153)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:54)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929)
at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:799)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:705)
at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:577)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683)
at java.lang.Thread.run(Thread.java:534)

[b]Name and version of the database you are using:[/b]
MS SQL Server 2000

[b]The generated SQL (show_sql=true):[/b]
[code]update custl_acct set portia_status_indicator=?, active_custl_acct_flag=?, acct_sec_mo_end_report_flag=?, acct_name=?, note=?, last_mod_date=?, acct_nbr=?, tax_lot_level_flag=?, prim_acct_src_id=?, scndry_acct_src_id=?, eqy_pm_id=?, fixed_income_pm_id=?, analyst_id=?, last_mod_user_oid=?, prim_pm_id=? where custl_acct_oid=?[/code]

[b]Debug level Hibernate log excerpt:[/b]
13:06:38,875 INFO [STDOUT] 13:06:38 DEBUG [impl.SessionFactoryObjectFactory] JNDI lookup: AutoReconHibernateFactory
13:06:38,875 INFO [STDOUT] 13:06:38 DEBUG [impl.SessionFactoryObjectFactory] lookup: uid=d883b0cb016d402301016d4027600000
13:06:38,875 INFO [STDOUT] 13:06:38 DEBUG [impl.SessionImpl] opened session
13:06:41,281 INFO [STDOUT] 13:06:41 DEBUG [transaction.JDBCTransaction] begin
13:06:41,406 INFO [STDOUT] 13:06:41 DEBUG [transaction.JDBCTransaction] current autocommit status:true
13:06:41,406 INFO [STDOUT] 13:06:41 DEBUG [transaction.JDBCTransaction] disabling autocommit
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [cache.ReadWriteCache] Cache lookup: 40671066
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [cache.ReadWriteCache] Cache miss: 40671066
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] object not resolved in any cache [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [persister.EntityPersister] Materializing entity: [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:43,421 INFO [STDOUT] 13:06:43 DEBUG [impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
13:06:43,437 INFO [STDOUT] 13:06:43 DEBUG [hibernate.SQL] select account0_.custl_acct_oid as custl_ac1_7_, account0_.portia_status_indicator as portia_s2_7_, account0_.active_custl_acct_flag as active_c3_7_, account0_.acct_sec_mo_end_report_flag as acct_sec4_7_, account0_.acct_name as acct_name7_, account0_.note as note7_, account0_.last_mod_date as last_mod7_7_, account0_.acct_nbr as acct_nbr7_, account0_.tax_lot_level_flag as tax_lot_9_7_, account0_.prim_acct_src_id as prim_ac10_7_, account0_.scndry_acct_src_id as scndry_11_7_, account0_.eqy_pm_id as eqy_pm_id7_, account0_.fixed_income_pm_id as fixed_i13_7_, account0_.analyst_id as analyst_id7_, account0_.last_mod_user_oid as last_mo15_7_, account0_.prim_pm_id as prim_pm_id7_, custodian1_.holding_src_oid as holding_1_0_, custodian1_.holding_eff_date_code as holding_2_0_, custodian1_.cost_exch_rate as cost_exc3_0_, custodian1_.mkt_value_exch_rate_flag as mkt_valu4_0_, custodian1_.us_dollar_flag as us_dolla5_0_, custodian1_.note as note0_, custodian1_.batch_file_arrive_hr as batch_fi7_0_, custodian1_.active_flag as active_f8_0_, custodian1_.prim_acct_upd_code as prim_acc9_0_, custodian1_.last_mod_date as last_mo10_0_, custodian1_.tax_lot_level_flag as tax_lot11_0_, custodian1_.total_poss_qty as total_p12_0_, custodian1_.src_name as src_name0_, custodian1_.transact_flag as transac14_0_, custodian1_.batch_file_arrive_m_code as batch_f15_0_, custodian1_.batch_file_arrive_min as batch_f16_0_, custodian1_.last_mod_user_oid as last_mo17_0_, enduser2_.user_oid as user_oid1_, enduser2_.empl_id as empl_id1_, enduser2_.given_name as given_name1_, enduser2_.surname as surname1_, enduser2_.reg_email_addr as reg_emai5_1_, enduser2_.emerg_email_addr as emerg_em6_1_, enduser2_.tel_nbr as tel_nbr1_, enduser2_.last_mod_user_oid as last_mod8_1_, enduser2_.last_mod_date as last_mod9_1_, enduser2_.active_user_flag as active_10_1_, enduser3_.user_oid as user_oid2_, enduser3_.empl_id as empl_id2_, enduser3_.given_name as given_name2_, enduser3_.surname as surname2_, enduser3_.reg_email_addr as reg_emai5_2_, enduser3_.emerg_email_addr as emerg_em6_2_, enduser3_.tel_nbr as tel_nbr2_, enduser3_.last_mod_user_oid as last_mod8_2_, enduser3_.last_mod_date as last_mod9_2_, enduser3_.active_user_flag as active_10_2_, enduser4_.user_oid as user_oid3_, enduser4_.empl_id as empl_id3_, enduser4_.given_name as given_name3_, enduser4_.surname as surname3_, enduser4_.reg_email_addr as reg_emai5_3_, enduser4_.emerg_email_addr as emerg_em6_3_, enduser4_.tel_nbr as tel_nbr3_, enduser4_.last_mod_user_oid as last_mod8_3_, enduser4_.last_mod_date as last_mod9_3_, enduser4_.active_user_flag as active_10_3_, enduser5_.user_oid as user_oid4_, enduser5_.empl_id as empl_id4_, enduser5_.given_name as given_name4_, enduser5_.surname as surname4_, enduser5_.reg_email_addr as reg_emai5_4_, enduser5_.emerg_email_addr as emerg_em6_4_, enduser5_.tel_nbr as tel_nbr4_, enduser5_.last_mod_user_oid as last_mod8_4_, enduser5_.last_mod_date as last_mod9_4_, enduser5_.active_user_flag as active_10_4_, enduser6_.user_oid as user_oid5_, enduser6_.empl_id as empl_id5_, enduser6_.given_name as given_name5_, enduser6_.surname as surname5_, enduser6_.reg_email_addr as reg_emai5_5_, enduser6_.emerg_email_addr as emerg_em6_5_, enduser6_.tel_nbr as tel_nbr5_, enduser6_.last_mod_user_oid as last_mod8_5_, enduser6_.last_mod_date as last_mod9_5_, enduser6_.active_user_flag as active_10_5_, enduser7_.user_oid as user_oid6_, enduser7_.empl_id as empl_id6_, enduser7_.given_name as given_name6_, enduser7_.surname as surname6_, enduser7_.reg_email_addr as reg_emai5_6_, enduser7_.emerg_email_addr as emerg_em6_6_, enduser7_.tel_nbr as tel_nbr6_, enduser7_.last_mod_user_oid as last_mod8_6_, enduser7_.last_mod_date as last_mod9_6_, enduser7_.active_user_flag as active_10_6_ from custl_acct account0_ left outer join sec_holding_src custodian1_ on account0_.prim_acct_src_id=custodian1_.holding_src_oid left outer join end_user enduser2_ on custodian1_.last_mod_user_oid=enduser2_.user_oid left outer join end_user enduser3_ on account0_.eqy_pm_id=enduser3_.user_oid left outer join end_user enduser4_ on account0_.fixed_income_pm_id=enduser4_.user_oid left outer join end_user enduser5_ on account0_.analyst_id=enduser5_.user_oid left outer join end_user enduser6_ on account0_.last_mod_user_oid=enduser6_.user_oid left outer join end_user enduser7_ on account0_.prim_pm_id=enduser7_.user_oid where account0_.custl_acct_oid=?
13:06:43,468 INFO [STDOUT] Hibernate: select account0_.custl_acct_oid as custl_ac1_7_, account0_.portia_status_indicator as portia_s2_7_, account0_.active_custl_acct_flag as active_c3_7_, account0_.acct_sec_mo_end_report_flag as acct_sec4_7_, account0_.acct_name as acct_name7_, account0_.note as note7_, account0_.last_mod_date as last_mod7_7_, account0_.acct_nbr as acct_nbr7_, account0_.tax_lot_level_flag as tax_lot_9_7_, account0_.prim_acct_src_id as prim_ac10_7_, account0_.scndry_acct_src_id as scndry_11_7_, account0_.eqy_pm_id as eqy_pm_id7_, account0_.fixed_income_pm_id as fixed_i13_7_, account0_.analyst_id as analyst_id7_, account0_.last_mod_user_oid as last_mo15_7_, account0_.prim_pm_id as prim_pm_id7_, custodian1_.holding_src_oid as holding_1_0_, custodian1_.holding_eff_date_code as holding_2_0_, custodian1_.cost_exch_rate as cost_exc3_0_, custodian1_.mkt_value_exch_rate_flag as mkt_valu4_0_, custodian1_.us_dollar_flag as us_dolla5_0_, custodian1_.note as note0_, custodian1_.batch_file_arrive_hr as batch_fi7_0_, custodian1_.active_flag as active_f8_0_, custodian1_.prim_acct_upd_code as prim_acc9_0_, custodian1_.last_mod_date as last_mo10_0_, custodian1_.tax_lot_level_flag as tax_lot11_0_, custodian1_.total_poss_qty as total_p12_0_, custodian1_.src_name as src_name0_, custodian1_.transact_flag as transac14_0_, custodian1_.batch_file_arrive_m_code as batch_f15_0_, custodian1_.batch_file_arrive_min as batch_f16_0_, custodian1_.last_mod_user_oid as last_mo17_0_, enduser2_.user_oid as user_oid1_, enduser2_.empl_id as empl_id1_, enduser2_.given_name as given_name1_, enduser2_.surname as surname1_, enduser2_.reg_email_addr as reg_emai5_1_, enduser2_.emerg_email_addr as emerg_em6_1_, enduser2_.tel_nbr as tel_nbr1_, enduser2_.last_mod_user_oid as last_mod8_1_, enduser2_.last_mod_date as last_mod9_1_, enduser2_.active_user_flag as active_10_1_, enduser3_.user_oid as user_oid2_, enduser3_.empl_id as empl_id2_, enduser3_.given_name as given_name2_, enduser3_.surname as surname2_, enduser3_.reg_email_addr as reg_emai5_2_, enduser3_.emerg_email_addr as emerg_em6_2_, enduser3_.tel_nbr as tel_nbr2_, enduser3_.last_mod_user_oid as last_mod8_2_, enduser3_.last_mod_date as last_mod9_2_, enduser3_.active_user_flag as active_10_2_, enduser4_.user_oid as user_oid3_, enduser4_.empl_id as empl_id3_, enduser4_.given_name as given_name3_, enduser4_.surname as surname3_, enduser4_.reg_email_addr as reg_emai5_3_, enduser4_.emerg_email_addr as emerg_em6_3_, enduser4_.tel_nbr as tel_nbr3_, enduser4_.last_mod_user_oid as last_mod8_3_, enduser4_.last_mod_date as last_mod9_3_, enduser4_.active_user_flag as active_10_3_, enduser5_.user_oid as user_oid4_, enduser5_.empl_id as empl_id4_, enduser5_.given_name as given_name4_, enduser5_.surname as surname4_, enduser5_.reg_email_addr as reg_emai5_4_, enduser5_.emerg_email_addr as emerg_em6_4_, enduser5_.tel_nbr as tel_nbr4_, enduser5_.last_mod_user_oid as last_mod8_4_, enduser5_.last_mod_date as last_mod9_4_, enduser5_.active_user_flag as active_10_4_, enduser6_.user_oid as user_oid5_, enduser6_.empl_id as empl_id5_, enduser6_.given_name as given_name5_, enduser6_.surname as surname5_, enduser6_.reg_email_addr as reg_emai5_5_, enduser6_.emerg_email_addr as emerg_em6_5_, enduser6_.tel_nbr as tel_nbr5_, enduser6_.last_mod_user_oid as last_mod8_5_, enduser6_.last_mod_date as last_mod9_5_, enduser6_.active_user_flag as active_10_5_, enduser7_.user_oid as user_oid6_, enduser7_.empl_id as empl_id6_, enduser7_.given_name as given_name6_, enduser7_.surname as surname6_, enduser7_.reg_email_addr as reg_emai5_6_, enduser7_.emerg_email_addr as emerg_em6_6_, enduser7_.tel_nbr as tel_nbr6_, enduser7_.last_mod_user_oid as last_mod8_6_, enduser7_.last_mod_date as last_mod9_6_, enduser7_.active_user_flag as active_10_6_ from custl_acct account0_ left outer join sec_holding_src custodian1_ on account0_.prim_acct_src_id=custodian1_.holding_src_oid left outer join end_user enduser2_ on custodian1_.last_mod_user_oid=enduser2_.user_oid left outer join end_user enduser3_ on account0_.eqy_pm_id=enduser3_.user_oid left outer join end_user enduser4_ on account0_.fixed_income_pm_id=enduser4_.user_oid left outer join end_user enduser5_ on account0_.analyst_id=enduser5_.user_oid left outer join end_user enduser6_ on account0_.last_mod_user_oid=enduser6_.user_oid left outer join end_user enduser7_ on account0_.prim_pm_id=enduser7_.user_oid where account0_.custl_acct_oid=?
13:06:43,484 INFO [STDOUT] 13:06:43 DEBUG [impl.BatcherImpl] preparing statement
13:06:43,515 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] binding '40671066' to parameter: 1
13:06:43,609 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] processing result set
13:06:43,609 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '36769475' as column: holding_1_0_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '2096460' as column: user_oid1_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning null as column: user_oid2_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning null as column: user_oid3_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '39310308' as column: user_oid4_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning null as column: user_oid5_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '22759650' as column: user_oid6_
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] result row: 36769475, 2096460, null, null, 39310308, null, 22759650, 40671066
13:06:43,625 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Initializing object from ResultSet: 36769475
13:06:43,640 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.Custodian#36769475
13:06:43,640 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Y' as column: holding_2_0_
13:06:43,640 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Y' as column: cost_exc3_0_
13:06:43,640 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Y' as column: mkt_valu4_0_
13:06:43,640 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'false' as column: us_dolla5_0_
13:06:43,640 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning null as column: note0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.ShortType] returning null as column: batch_fi7_0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: active_f8_0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'A' as column: prim_acc9_0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.TimestampType] returning '2004-10-20 11:32:37' as column: last_mo10_0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: tax_lot11_0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.IntegerType] returning null as column: total_p12_0_
13:06:43,656 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Kaplan-T' as column: src_name0_
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: transac14_0_
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning ' ' as column: batch_f15_0_
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [type.ShortType] returning null as column: batch_f16_0_
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '2096460' as column: last_mo17_0_
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Initializing object from ResultSet: 2096460
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.EndUser#2096460
13:06:43,671 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'A320814' as column: empl_id1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Christine' as column: given_name1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Sweet' as column: surname1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'christine.sweet@wachovia.com' as column: reg_emai5_1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '' as column: emerg_em6_1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '610-648-1853' as column: tel_nbr1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '12061018' as column: last_mod8_1_
13:06:43,687 INFO [STDOUT] 13:06:43 DEBUG [type.TimestampType] returning '2004-10-21 08:32:23' as column: last_mod9_1_
13:06:43,703 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: active_10_1_
13:06:43,703 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Initializing object from ResultSet: 39310308
13:06:43,703 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.EndUser#39310308
13:06:43,703 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'A228405' as column: empl_id4_
13:06:43,703 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Edward' as column: given_name4_
13:06:43,703 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Myers' as column: surname4_
13:06:43,734 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'mr@test.com' as column: reg_emai5_4_
13:06:43,734 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'mr@test.com' as column: emerg_em6_4_
13:06:43,734 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '555-555-1212' as column: tel_nbr4_
13:06:43,734 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '2096460' as column: last_mod8_4_
13:06:43,750 INFO [STDOUT] 13:06:43 DEBUG [type.TimestampType] returning '2004-08-27 16:16:51' as column: last_mod9_4_
13:06:43,750 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: active_10_4_
13:06:43,750 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Initializing object from ResultSet: 22759650
13:06:43,750 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.EndUser#22759650
13:06:43,750 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'A000000001' as column: empl_id6_
13:06:43,750 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'Unknown' as column: given_name6_
13:06:43,765 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning 'PM' as column: surname6_
13:06:43,765 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '.' as column: reg_emai5_6_
13:06:43,765 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '' as column: emerg_em6_6_
13:06:43,765 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '' as column: tel_nbr6_
13:06:43,765 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '3574' as column: last_mod8_6_
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [type.TimestampType] returning '2003-06-25 16:04:49' as column: last_mod9_6_
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: active_10_6_
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Initializing object from ResultSet: 40671066
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.Account#40671066
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '1' as column: portia_s2_7_
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'true' as column: active_c3_7_
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'false' as column: acct_sec4_7_
13:06:43,781 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '"Auburn Gear, Inc. Hourly Pension Plan"' as column: acct_name7_
13:06:43,796 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning null as column: note7_
13:06:43,796 INFO [STDOUT] 13:06:43 DEBUG [type.TimestampType] returning '2004-12-31 10:45:07' as column: last_mod7_7_
13:06:43,796 INFO [STDOUT] 13:06:43 DEBUG [type.StringType] returning '2600161103' as column: acct_nbr7_
13:06:43,796 INFO [STDOUT] 13:06:43 DEBUG [type.YesNoType] returning 'false' as column: tax_lot_9_7_
13:06:43,796 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '36769475' as column: prim_ac10_7_
13:06:43,796 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '2096853' as column: scndry_11_7_
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning null as column: eqy_pm_id7_
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning null as column: fixed_i13_7_
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '39310308' as column: analyst_id7_
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning null as column: last_mo15_7_
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [type.LongType] returning '22759650' as column: prim_pm_id7_
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] done processing result set (1 rows)
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [impl.BatcherImpl] closing statement
13:06:43,812 INFO [STDOUT] 13:06:43 DEBUG [loader.Loader] total objects hydrated: 5
13:06:43,828 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.Custodian#36769475]
13:06:43,828 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.EndUser#2096460]
13:06:43,828 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.EndUser#2096460]
13:06:43,828 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] resolved object in session cache [com.wachovia.evergreen.autorecon.bo.EndUser#2096460]
13:06:43,828 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.Custodian#36769475]
13:06:43,828 INFO [STDOUT] 13:06:43 DEBUG [cache.ReadOnlyCache] Caching: 36769475
13:06:43,843 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.Custodian#36769475]
13:06:43,843 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.EndUser#2096460]
13:06:43,843 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] creating collection wrapper:[com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#2096460]
13:06:44,156 INFO [STDOUT] 13:06:43 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.EndUser#2096460]
13:06:44,156 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadOnlyCache] Caching: 2096460
13:06:44,156 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.EndUser#2096460]
13:06:44,156 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.EndUser#39310308]
13:06:44,156 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] creating collection wrapper:[com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#39310308]
13:06:44,500 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.EndUser#39310308]
13:06:44,500 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadOnlyCache] Caching: 39310308
13:06:44,500 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.EndUser#39310308]
13:06:44,515 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.EndUser#22759650]
13:06:44,515 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] creating collection wrapper:[com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#22759650]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.EndUser#22759650]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadOnlyCache] Caching: 22759650
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.EndUser#22759650]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.Custodian#36769475]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.Custodian#36769475]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolved object in session cache [com.wachovia.evergreen.autorecon.bo.Custodian#36769475]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,531 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,546 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] object not resolved in any cache [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,546 INFO [STDOUT] 13:06:44 DEBUG [persister.EntityPersister] Materializing entity: [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,546 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
13:06:44,546 INFO [STDOUT] 13:06:44 DEBUG [hibernate.SQL] select custodian0_.holding_src_oid as holding_1_1_, custodian0_.holding_eff_date_code as holding_2_1_, custodian0_.cost_exch_rate as cost_exc3_1_, custodian0_.mkt_value_exch_rate_flag as mkt_valu4_1_, custodian0_.us_dollar_flag as us_dolla5_1_, custodian0_.note as note1_, custodian0_.batch_file_arrive_hr as batch_fi7_1_, custodian0_.active_flag as active_f8_1_, custodian0_.prim_acct_upd_code as prim_acc9_1_, custodian0_.last_mod_date as last_mo10_1_, custodian0_.tax_lot_level_flag as tax_lot11_1_, custodian0_.total_poss_qty as total_p12_1_, custodian0_.src_name as src_name1_, custodian0_.transact_flag as transac14_1_, custodian0_.batch_file_arrive_m_code as batch_f15_1_, custodian0_.batch_file_arrive_min as batch_f16_1_, custodian0_.last_mod_user_oid as last_mo17_1_, enduser1_.user_oid as user_oid0_, enduser1_.empl_id as empl_id0_, enduser1_.given_name as given_name0_, enduser1_.surname as surname0_, enduser1_.reg_email_addr as reg_emai5_0_, enduser1_.emerg_email_addr as emerg_em6_0_, enduser1_.tel_nbr as tel_nbr0_, enduser1_.last_mod_user_oid as last_mod8_0_, enduser1_.last_mod_date as last_mod9_0_, enduser1_.active_user_flag as active_10_0_ from sec_holding_src custodian0_ left outer join end_user enduser1_ on custodian0_.last_mod_user_oid=enduser1_.user_oid where custodian0_.holding_src_oid=?
13:06:44,562 INFO [STDOUT] Hibernate: select custodian0_.holding_src_oid as holding_1_1_, custodian0_.holding_eff_date_code as holding_2_1_, custodian0_.cost_exch_rate as cost_exc3_1_, custodian0_.mkt_value_exch_rate_flag as mkt_valu4_1_, custodian0_.us_dollar_flag as us_dolla5_1_, custodian0_.note as note1_, custodian0_.batch_file_arrive_hr as batch_fi7_1_, custodian0_.active_flag as active_f8_1_, custodian0_.prim_acct_upd_code as prim_acc9_1_, custodian0_.last_mod_date as last_mo10_1_, custodian0_.tax_lot_level_flag as tax_lot11_1_, custodian0_.total_poss_qty as total_p12_1_, custodian0_.src_name as src_name1_, custodian0_.transact_flag as transac14_1_, custodian0_.batch_file_arrive_m_code as batch_f15_1_, custodian0_.batch_file_arrive_min as batch_f16_1_, custodian0_.last_mod_user_oid as last_mo17_1_, enduser1_.user_oid as user_oid0_, enduser1_.empl_id as empl_id0_, enduser1_.given_name as given_name0_, enduser1_.surname as surname0_, enduser1_.reg_email_addr as reg_emai5_0_, enduser1_.emerg_email_addr as emerg_em6_0_, enduser1_.tel_nbr as tel_nbr0_, enduser1_.last_mod_user_oid as last_mod8_0_, enduser1_.last_mod_date as last_mod9_0_, enduser1_.active_user_flag as active_10_0_ from sec_holding_src custodian0_ left outer join end_user enduser1_ on custodian0_.last_mod_user_oid=enduser1_.user_oid where custodian0_.holding_src_oid=?
13:06:44,562 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] preparing statement
13:06:44,562 INFO [STDOUT] 13:06:44 DEBUG [type.LongType] binding '2096853' to parameter: 1
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] processing result set
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [type.LongType] returning '3569075' as column: user_oid0_
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] result row: 3569075, 2096853
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] Initializing object from ResultSet: 3569075
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.EndUser#3569075
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'A338154' as column: empl_id0_
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'Damon ' as column: given_name0_
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'Wioskowski' as column: surname0_
13:06:44,640 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning '.' as column: reg_emai5_0_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning '' as column: emerg_em6_0_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning '610-251-5927' as column: tel_nbr0_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.LongType] returning '2096460' as column: last_mod8_0_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.TimestampType] returning '2004-06-22 11:39:08' as column: last_mod9_0_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.YesNoType] returning 'true' as column: active_10_0_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] Initializing object from ResultSet: 2096853
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] Hydrating entity: com.wachovia.evergreen.autorecon.bo.Custodian#2096853
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'Y' as column: holding_2_1_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'N' as column: cost_exc3_1_
13:06:44,656 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'N' as column: mkt_valu4_1_
13:06:44,671 INFO [STDOUT] 13:06:44 DEBUG [type.YesNoType] returning 'false' as column: us_dolla5_1_
13:06:44,671 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning null as column: note1_
13:06:44,671 INFO [STDOUT] 13:06:44 DEBUG [type.ShortType] returning null as column: batch_fi7_1_
13:06:44,671 INFO [STDOUT] 13:06:44 DEBUG [type.YesNoType] returning 'true' as column: active_f8_1_
13:06:44,671 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'A' as column: prim_acc9_1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.TimestampType] returning '2004-10-21 14:03:50' as column: last_mo10_1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.YesNoType] returning 'false' as column: tax_lot11_1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.IntegerType] returning '412' as column: total_p12_1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning 'BankOne-T' as column: src_name1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.YesNoType] returning 'false' as column: transac14_1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.StringType] returning ' ' as column: batch_f15_1_
13:06:44,687 INFO [STDOUT] 13:06:44 DEBUG [type.ShortType] returning null as column: batch_f16_1_
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [type.LongType] returning '3569075' as column: last_mo17_1_
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] done processing result set (1 rows)
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] closing statement
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] total objects hydrated: 2
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.EndUser#3569075]
13:06:44,703 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] creating collection wrapper:[com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#3569075]
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.EndUser#3569075]
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadOnlyCache] Caching: 3569075
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.EndUser#3569075]
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolving associations for [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.EndUser#3569075]
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.EndUser#3569075]
13:06:44,718 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolved object in session cache [com.wachovia.evergreen.autorecon.bo.EndUser#3569075]
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadOnlyCache] Caching: 2096853
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.Custodian#2096853]
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.EndUser#39310308]
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.EndUser#39310308]
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolved object in session cache [com.wachovia.evergreen.autorecon.bo.EndUser#39310308]
13:06:44,734 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] loading [com.wachovia.evergreen.autorecon.bo.EndUser#22759650]
13:06:44,750 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] attempting to resolve [com.wachovia.evergreen.autorecon.bo.EndUser#22759650]
13:06:44,750 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] resolved object in session cache [com.wachovia.evergreen.autorecon.bo.EndUser#22759650]
13:06:44,765 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] adding entity to second-level cache [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:44,765 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadWriteCache] Caching: 40671066
13:06:44,796 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadWriteCache] Cached: 40671066
13:06:44,796 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] done materializing entity [com.wachovia.evergreen.autorecon.bo.Account#40671066]
13:06:44,796 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] initializing non-lazy collections
13:06:44,796 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] initializing collection [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#3569075]
13:06:44,812 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] checking second-level cache
13:06:44,812 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] collection not cached
13:06:44,812 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
13:06:44,812 INFO [STDOUT] 13:06:44 DEBUG [hibernate.SQL] select resolution0_.user_oid as user_oid__, resolution0_.resoln_group_oid as resoln_g2___, resolution1_.resoln_group_oid as resoln_g1_0_, resolution1_.active_flag as active_f2_0_, resolution1_.last_mod_date as last_mod3_0_, resolution1_.group_name as group_name0_, resolution1_.holding_src_oid as holding_5_0_, resolution1_.last_mod_user_oid as last_mod6_0_, custodian2_.holding_src_oid as holding_1_1_, custodian2_.holding_eff_date_code as holding_2_1_, custodian2_.cost_exch_rate as cost_exc3_1_, custodian2_.mkt_value_exch_rate_flag as mkt_valu4_1_, custodian2_.us_dollar_flag as us_dolla5_1_, custodian2_.note as note1_, custodian2_.batch_file_arrive_hr as batch_fi7_1_, custodian2_.active_flag as active_f8_1_, custodian2_.prim_acct_upd_code as prim_acc9_1_, custodian2_.last_mod_date as last_mo10_1_, custodian2_.tax_lot_level_flag as tax_lot11_1_, custodian2_.total_poss_qty as total_p12_1_, custodian2_.src_name as src_name1_, custodian2_.transact_flag as transac14_1_, custodian2_.batch_file_arrive_m_code as batch_f15_1_, custodian2_.batch_file_arrive_min as batch_f16_1_, custodian2_.last_mod_user_oid as last_mo17_1_, enduser3_.user_oid as user_oid2_, enduser3_.empl_id as empl_id2_, enduser3_.given_name as given_name2_, enduser3_.surname as surname2_, enduser3_.reg_email_addr as reg_emai5_2_, enduser3_.emerg_email_addr as emerg_em6_2_, enduser3_.tel_nbr as tel_nbr2_, enduser3_.last_mod_user_oid as last_mod8_2_, enduser3_.last_mod_date as last_mod9_2_, enduser3_.active_user_flag as active_10_2_, enduser4_.user_oid as user_oid3_, enduser4_.empl_id as empl_id3_, enduser4_.given_name as given_name3_, enduser4_.surname as surname3_, enduser4_.reg_email_addr as reg_emai5_3_, enduser4_.emerg_email_addr as emerg_em6_3_, enduser4_.tel_nbr as tel_nbr3_, enduser4_.last_mod_user_oid as last_mod8_3_, enduser4_.last_mod_date as last_mod9_3_, enduser4_.active_user_flag as active_10_3_ from resoln_group_membership resolution0_ inner join resoln_group resolution1_ on resolution0_.resoln_group_oid=resolution1_.resoln_group_oid left outer join sec_holding_src custodian2_ on resolution1_.holding_src_oid=custodian2_.holding_src_oid left outer join end_user enduser3_ on custodian2_.last_mod_user_oid=enduser3_.user_oid left outer join end_user enduser4_ on resolution1_.last_mod_user_oid=enduser4_.user_oid where resolution0_.user_oid=?
13:06:44,828 INFO [STDOUT] Hibernate: select resolution0_.user_oid as user_oid__, resolution0_.resoln_group_oid as resoln_g2___, resolution1_.resoln_group_oid as resoln_g1_0_, resolution1_.active_flag as active_f2_0_, resolution1_.last_mod_date as last_mod3_0_, resolution1_.group_name as group_name0_, resolution1_.holding_src_oid as holding_5_0_, resolution1_.last_mod_user_oid as last_mod6_0_, custodian2_.holding_src_oid as holding_1_1_, custodian2_.holding_eff_date_code as holding_2_1_, custodian2_.cost_exch_rate as cost_exc3_1_, custodian2_.mkt_value_exch_rate_flag as mkt_valu4_1_, custodian2_.us_dollar_flag as us_dolla5_1_, custodian2_.note as note1_, custodian2_.batch_file_arrive_hr as batch_fi7_1_, custodian2_.active_flag as active_f8_1_, custodian2_.prim_acct_upd_code as prim_acc9_1_, custodian2_.last_mod_date as last_mo10_1_, custodian2_.tax_lot_level_flag as tax_lot11_1_, custodian2_.total_poss_qty as total_p12_1_, custodian2_.src_name as src_name1_, custodian2_.transact_flag as transac14_1_, custodian2_.batch_file_arrive_m_code as batch_f15_1_, custodian2_.batch_file_arrive_min as batch_f16_1_, custodian2_.last_mod_user_oid as last_mo17_1_, enduser3_.user_oid as user_oid2_, enduser3_.empl_id as empl_id2_, enduser3_.given_name as given_name2_, enduser3_.surname as surname2_, enduser3_.reg_email_addr as reg_emai5_2_, enduser3_.emerg_email_addr as emerg_em6_2_, enduser3_.tel_nbr as tel_nbr2_, enduser3_.last_mod_user_oid as last_mod8_2_, enduser3_.last_mod_date as last_mod9_2_, enduser3_.active_user_flag as active_10_2_, enduser4_.user_oid as user_oid3_, enduser4_.empl_id as empl_id3_, enduser4_.given_name as given_name3_, enduser4_.surname as surname3_, enduser4_.reg_email_addr as reg_emai5_3_, enduser4_.emerg_email_addr as emerg_em6_3_, enduser4_.tel_nbr as tel_nbr3_, enduser4_.last_mod_user_oid as last_mod8_3_, enduser4_.last_mod_date as last_mod9_3_, enduser4_.active_user_flag as active_10_3_ from resoln_group_membership resolution0_ inner join resoln_group resolution1_ on resolution0_.resoln_group_oid=resolution1_.resoln_group_oid left outer join sec_holding_src custodian2_ on resolution1_.holding_src_oid=custodian2_.holding_src_oid left outer join end_user enduser3_ on custodian2_.last_mod_user_oid=enduser3_.user_oid left outer join end_user enduser4_ on resolution1_.last_mod_user_oid=enduser4_.user_oid where resolution0_.user_oid=?
13:06:44,843 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] preparing statement
13:06:44,843 INFO [STDOUT] 13:06:44 DEBUG [type.LongType] binding '3569075' to parameter: 1
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] result set contains (possibly empty) collection: [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#3569075]
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] uninitialized collection: initializing
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] processing result set
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] done processing result set (0 rows)
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] closing statement
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [loader.Loader] total objects hydrated: 0
13:06:44,937 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] 1 collections were found in result set
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] Caching collection: [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#3569075]
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [cache.ReadOnlyCache] Caching: 3569075
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] collection fully initialized: [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#3569075]
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] 1 collections initialized
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] collection initialized
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] initializing collection [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#22759650]
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] checking second-level cache
13:06:44,953 INFO [STDOUT] 13:06:44 DEBUG [impl.SessionImpl] collection not cached
13:06:44,968 INFO [STDOUT] 13:06:44 DEBUG [impl.BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
13:06:44,968 INFO [STDOUT] 13:06:44 DEBUG [hibernate.SQL] select resolution0_.user_oid as user_oid__, resolution0_.resoln_group_oid as resoln_g2___, resolution1_.resoln_group_oid as resoln_g1_0_, resolution1_.active_flag as active_f2_0_, resolution1_.last_mod_date as last_mod3_0_, resolution1_.group_name as group_name0_, resolution1_.holding_src_oid as holding_5_0_, resolution1_.last_mod_user_oid as last_mod6_0_, custodian2_.holding_src_oid as holding_1_1_, custodian2_.holding_eff_date_code as holding_2_1_, custodian2_.cost_exch_rate as cost_exc3_1_, custodian2_.mkt_value_exch_rate_flag as mkt_valu4_1_, custodian2_.us_dollar_flag as us_dolla5_1_, custodian2_.note as note1_, custodian2_.batch_file_arrive_hr as batch_fi7_1_, custodian2_.active_flag as active_f8_1_, custodian2_.prim_acct_upd_code as prim_acc9_1_, custodian2_.last_mod_date as last_mo10_1_, custodian2_.tax_lot_level_flag as tax_lot11_1_, custodian2_.total_poss_qty as total_p12_1_, custodian2_.src_name as src_name1_, custodian2_.transact_flag as transac14_1_, custodian2_.batch_file_arrive_m_code as batch_f15_1_, custodian2_.batch_file_arrive_min as batch_f16_1_, custodian2_.last_mod_user_oid as last_mo17_1_, enduser3_.user_oid as user_oid2_, enduser3_.empl_id as empl_id2_, enduser3_.given_name as given_name2_, enduser3_.surname as surname2_, enduser3_.reg_email_addr as reg_emai5_2_, enduser3_.emerg_email_addr as emerg_em6_2_, enduser3_.tel_nbr as tel_nbr2_, enduser3_.last_mod_user_oid as last_mod8_2_, enduser3_.last_mod_date as last_mod9_2_, enduser3_.active_user_flag as active_10_2_, enduser4_.user_oid as user_oid3_, enduser4_.empl_id as empl_id3_, enduser4_.given_name as given_name3_, enduser4_.surname as surname3_, enduser4_.reg_email_addr as reg_emai5_3_, enduser4_.emerg_email_addr as emerg_em6_3_, enduser4_.tel_nbr as tel_nbr3_, enduser4_.last_mod_user_oid as last_mod8_3_, enduser4_.last_mod_date as last_mod9_3_, enduser4_.active_user_flag as active_10_3_ from resoln_group_membership resolution0_ inner join resoln_group resolution1_ on resolution0_.resoln_group_oid=resolution1_.resoln_group_oid left outer join sec_holding_src custodian2_ on resolution1_.holding_src_oid=custodian2_.holding_src_oid left outer join end_user enduser3_ on custodian2_.last_mod_user_oid=enduser3_.user_oid left outer join end_user enduser4_ on resolution1_.last_mod_user_oid=enduser4_.user_oid where resolution0_.user_oid=?
13:06:44,984 INFO [STDOUT] Hibernate: select resolution0_.user_oid as user_oid__, resolution0_.resoln_group_oid as resoln_g2___, resolution1_.resoln_group_oid as resoln_g1_0_, resolution1_.active_flag as active_f2_0_, resolution1_.last_mod_date as last_mod3_0_, resolution1_.group_name as group_name0_, resolution1_.holding_src_oid as holding_5_0_, resolution1_.last_mod_user_oid as last_mod6_0_, custodian2_.holding_src_oid as holding_1_1_, custodian2_.holding_eff_date_code as holding_2_1_, custodian2_.cost_exch_rate as cost_exc3_1_, custodian2_.mkt_value_exch_rate_flag as mkt_valu4_1_, custodian2_.us_dollar_flag as us_dolla5_1_, custodian2_.note as note1_, custodian2_.batch_file_arrive_hr as batch_fi7_1_, custodian2_.active_flag as active_f8_1_, custodian2_.prim_acct_upd_code as prim_acc9_1_, custodian2_.last_mod_date as last_mo10_1_, custodian2_.tax_lot_level_flag as tax_lot11_1_, custodian2_.total_poss_qty as total_p12_1_, custodian2_.src_name as src_name1_, custodian2_.transact_flag as transac14_1_, custodian2_.batch_file_arrive_m_code as batch_f15_1_, custodian2_.batch_file_arrive_min as batch_f16_1_, custodian2_.last_mod_user_oid as last_mo17_1_, enduser3_.user_oid as user_oid2_, enduser3_.empl_id as empl_id2_, enduser3_.given_name as given_name2_, enduser3_.surname as surname2_, enduser3_.reg_email_addr as reg_emai5_2_, enduser3_.emerg_email_addr as emerg_em6_2_, enduser3_.tel_nbr as tel_nbr2_, enduser3_.last_mod_user_oid as last_mod8_2_, enduser3_.last_mod_date as last_mod9_2_, enduser3_.active_user_flag as active_10_2_, enduser4_.user_oid as user_oid3_, enduser4_.empl_id as empl_id3_, enduser4_.given_name as given_name3_, enduser4_.surname as surname3_, enduser4_.reg_email_addr as reg_emai5_3_, enduser4_.emerg_email_addr as emerg_em6_3_, enduser4_.tel_nbr as tel_nbr3_, enduser4_.last_mod_user_oid as last_mod8_3_, enduser4_.last_mod_date as last_mod9_3_, enduser4_.active_user_flag as active_10_3_ from resoln_group_membership resolution0_ inner join resoln_group resolution1_ on resolution0_.resoln_group_oid=resolution1_.resoln_group_oid left outer join sec_holding_src custodian2_ on resolution1_.holding_src_oid=custodian2_.holding_src_oid left outer join end_user enduser3_ on custodian2_.last_mod_user_oid=enduser3_.user_oid left outer join end_user enduser4_ on resolution1_.last_mod_user_oid=enduser4_.user_oid where resolution0_.user_oid=?
13:06:45,000 INFO [STDOUT] 13:06:45 DEBUG [impl.BatcherImpl] preparing statement
13:06:45,000 INFO [STDOUT] 13:06:45 DEBUG [type.LongType] binding '22759650' to parameter: 1
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [loader.Loader] result set contains (possibly empty) collection: [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#22759650]
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [impl.SessionImpl] uninitialized collection: initializing
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [loader.Loader] processing result set
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [loader.Loader] done processing result set (0 rows)
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [impl.BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [impl.BatcherImpl] closing statement
13:06:45,031 INFO [STDOUT] 13:06:45 DEBUG [loader.Loader] total objects hydrated: 0
13:06:45,046 INFO [STDOUT] 13:06:45 DEBUG [impl.SessionImpl] 1 collections were found in result set
13:06:45,046 INFO [STDOUT] 13:06:45 DEBUG [impl.SessionImpl] Caching collection: [com.wachovia.evergreen.autorecon.bo.EndUser.ResolutionGroups#22759650]
13:06:45,046 INFO

I've had this happen quite a bit to me. I'm also using SQL Server and it's famous for screwing up your ID's on the return trip back from the database, which Hibernate uses for all kinds of things.

Ok. First thing is to check with your Database Driver. If you're using jTDS (please don't tell me you're using Microsoft's murky driver!) there are some settings that can govern the returning of row counts and id's from the database. Check the FAQ, documentation, and mailing list for jTDS for more information. Now that you understand how your database driver interacts with SQL, on to step 2.

Second, check to see if you're using a Trigger. I get that error most often when a trigger fires that inserts new records into the database. What happens is that SQL returns the ID of the row created IN THE TRIGGER rather than the ID of the row you just updated. Again, this behavior can be modified by using settings on the JDBC driver. It's not really a bug in anyone's code, it's just how SQL works when returning ID's during an update.

To get your head wrapped around this check the @@IDENTITY, SCOPE_IDENTITY, and IDENT_CURRENT in your SQL documentation. Then check your JDBC driver documentation on how it handles multiple rows and rowcounts in an update involving triggers.

Thank you so much! That resolved my problem... with JTDS, lastUpdateCount=true.