-->
These old forums are deprecated now and set to read-only. We are waiting for you on our new forums!
More modern, Discourse-based and with GitHub/Google/Twitter authentication built-in.

All times are UTC - 5 hours [ DST ]



Forum locked This topic is locked, you cannot edit posts or make further replies.  [ 5 posts ] 
Author Message
 Post subject: Bloody StaleStateException
PostPosted: Sat Dec 02, 2006 2:55 pm 
Newbie

Joined: Thu Nov 30, 2006 10:44 am
Posts: 6
Location: Moscow
Hello All,

here is my pain
--------------
I have Stateful EJB with method marked 'Required' for transaction.
Hibernate is configured to use CMT and connection pool. I have my transaction rollback during flush.

-------------
10:13:53,635 ERROR AbstractFlushingEventListener:300 - Could not synchronize database state with session
org.hibernate.StaleStateException: Unexpected row count: 0 expected: 1
-------------

ExtInstrument entity has native primiry key configured and I'm not setting this field.
Hibernate is running under WebLogic 8.1.4 and uses the same connection pool as WebLogic.
The drivers are JDBC MSSQL 2000 SP3 non-TX but I've switched on emulate two-phase commit.
I have a lot of stored procedures with 'SET NOCOUNT ON' statements inside but don't think it is connected with this problem.

Working for 2 weeks to resolve the problem, please help.


Hibernate version:
3.1.3, March 20, 2006

Mapping documents:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE hibernate-configuration PUBLIC
"-//Hibernate/Hibernate Configuration DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd">
<hibernate-configuration>
<session-factory>
<property name="hibernate.bytecode.use_reflection_optimizer">false</property>
<property name="hibernate.default_schema">dbo</property>
<property name="hibernate.dialect">org.hibernate.dialect.SQLServerDialect</property>

<property name="hibernate.connection.datasource">jdbc/EquitiesDB_MSSQLTX</property>
<property name="hibernate.transaction.factory_class">org.hibernate.transaction.CMTTransactionFactory</property>
<property name="hibernate.transaction.manager_lookup_class">org.hibernate.transaction.WeblogicTransactionManagerLookup</property>

<!-- Enable Hibernate's automatic session context management -->
<property name="current_session_context_class">jta</property>

<!-- Disable the second-level cache -->
<property name="cache.provider_class">org.hibernate.cache.NoCacheProvider</property>

<!-- Echo all executed SQL to stdout -->
<property name="show_sql">true</property>
<property name="hibernate.format_sql">true</property>

<mapping resource="com/bubsw/bequest/sd/link/model/Issuer.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/RegistrationAgents.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/Instrument.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/CurrencyC.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/InstrumentNum.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/ExtInstrument.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/ExtSystemTypeC.hbm.xml" />
<mapping resource="com/bubsw/bequest/sd/link/model/ExtInstrumentNum.hbm.xml" />

</session-factory>
</hibernate-configuration>


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

EJB's setInstrument() method set to Required in deployment description and uses CMT.

1. EJB code
-------------------------------------------------------------------------------------------------------
public void setInstrument() // This is EJB method, marked as Required
{
ExtInstrument extInstr = new ExtInstrument();

// Set properties here ....
...
extInstr.setProcessedF(ICommonConstants.FALSE_FLAG.charAt(0));

...


// Arrange ext.instruments: mark old ones as deleted, insert current one
arrangeExtInsruments(extInstr); // See this method body below

// Unlock ext.instrument
extInstr.setLockedF(ICommonConstants.FALSE_FLAG.charAt(0));
}

private void arrangeExtInsruments(ExtInstrument extInstr)
{
// Mark all unprocessed and not deleted related ext instruments deleted
List staleList = hbDao.getStaleInstrument(extInstr);
for (Iterator it = staleList.iterator(); it.hasNext(); ) {
ExtInstrument stale = (ExtInstrument) it.next();
stale.setDeletedF(ICommonConstants.TRUE_FLAG.charAt(0));
}

// Update them all
hbDao.setExtInstrumentBatch(staleList);
if (extInstr.getExtInstrumentNum() != null && extInstr.getExtInstrumentNum().getExtInstrNumId() == null) {
hbDao.save(extInstr.getExtInstrumentNum());
}

// Insert ext.instrument
hbDao.setExtInstrument(extInstr);
}

2. DAO code
-------------------------------------------------------------------------------------------------------
// Get Hibernate session
protected Session getHbSession() {
return HibernateUtil.getSessionFactoryManaged().getCurrentSession();
}

public Serializable save(Object o) {
return getHbSession().save(o);
}

public void setExtInstrumentBatch(List extInstrs) {
for (Iterator it = extInstrs.iterator(); it.hasNext(); ) {
ExtInstrument item = (ExtInstrument)it.next();
getHbSession().flush();
getHbSession().clear();
}
}

// Insert or update
public void setExtInstrument(ExtInstrument extInstr)
{
// Insert or update
getHbSession().saveOrUpdate(extInstr);
}

public List getStaleInstrument(ExtInstrument extInstr)
{
StringBuffer hql = new StringBuffer("from ExtInstrument s ").
append("where s.isinNum = :isinNum ").
append("and s.processedF = :processedF ").
append("and s.deletedF = :deletedF ").
append("and s.stamp < :stamp ").
append("and s.issueNum = :issueNum ");
Query query = getHbSession().createQuery(hql.toString());
query.setString("isinNum", extInstr.getIsinNum());
query.setCharacter("processedF", ICommonConstants.FALSE_FLAG.charAt(0));
query.setCharacter("deletedF", ICommonConstants.FALSE_FLAG.charAt(0));
query.setTimestamp("stamp", extInstr.getStamp());
query.setShort("issueNum", extInstr.getIssueNum().shortValue());
return query.list();
}

Full stack trace of any exception that occurs:
org.hibernate.StaleStateException: Unexpected row count: 0 expected: 1
at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(I)V(NonBatchingBatcher.java:27)
at org.hibernate.persister.entity.AbstractEntityPersister.update(Ljava.io.Serializable;[Ljava.lang.Object;[Ljava.lang.Object;Ljava.lang.Object;[ZILjava.lang.Object;Ljava.lang.Object;Ljava.lang.String;Lorg.hibernate.engine.SessionImplementor;)Z(AbstractEntityPersister.java:2204)
at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(Ljava.io.Serializable;[Ljava.lang.Object;[Ljava.lang.Object;Ljava.lang.Object;[ZILjava.lang.Object;Ljava.lang.Object;Ljava.lang.String;Lorg.hibernate.engine.SessionImplementor;)V(AbstractEntityPersister.java:2118)
at org.hibernate.persister.entity.AbstractEntityPersister.update(Ljava.io.Serializable;[Ljava.lang.Object;[IZ[Ljava.lang.Object;Ljava.lang.Object;Ljava.lang.Object;Ljava.lang.Object;Lorg.hibernate.engine.SessionImplementor;)V(AbstractEntityPersister.java:2374)
at org.hibernate.action.EntityUpdateAction.execute()V(EntityUpdateAction.java:91)
at org.hibernate.engine.ActionQueue.execute(Lorg.hibernate.action.Executable;)V(ActionQueue.java:248)
at org.hibernate.engine.ActionQueue.executeActions(Ljava.util.List;)V(ActionQueue.java:232)
at org.hibernate.engine.ActionQueue.executeActions()V(ActionQueue.java:140)
at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(Lorg.hibernate.event.EventSource;)V(AbstractFlushingEventListener.java:297)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(Lorg.hibernate.event.FlushEvent;)V(DefaultFlushEventListener.java:27)
at org.hibernate.impl.SessionImpl.flush()V(SessionImpl.java:985)
at org.hibernate.impl.SessionImpl.managedFlush()V(SessionImpl.java:333)
at org.hibernate.transaction.CacheSynchronization.beforeCompletion()V(CacheSynchronization.java:59)
at weblogic.transaction.internal.ServerSCInfo.callBeforeCompletions(Lweblogic.transaction.internal.TransactionImpl;)V(ServerSCInfo.java:1010)
at weblogic.transaction.internal.ServerSCInfo.startPrePrepareAndChain(Lweblogic.transaction.internal.ServerTransactionImpl;I)V(ServerSCInfo.java:115)
at weblogic.transaction.internal.ServerTransactionImpl.localPrePrepareAndChain()V(ServerTransactionImpl.java:1216)
at weblogic.transaction.internal.ServerTransactionImpl.globalPrePrepare()V(ServerTransactionImpl.java:1990)
at weblogic.transaction.internal.ServerTransactionImpl.internalCommit()V(ServerTransactionImpl.java:275)
at weblogic.transaction.internal.ServerTransactionImpl.commit()V(ServerTransactionImpl.java:246)
at weblogic.ejb20.internal.BaseEJBObject.postInvoke(Lweblogic.ejb20.internal.InvocationWrapper;Ljava.lang.Throwable;)V(Optimized Method)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl.setExtInstrumentLink(Ljava.lang.String;)V(StaticDataSession_muwkuo_EOImpl.java:4265)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl_WLSkel.internalInvoke1(ILweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;Ljava.lang.Object;)Lweblogic.rmi.spi.OutboundResponse;(Unknown Source)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl_WLSkel.invoke(ILweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;Ljava.lang.Object;)Lweblogic.rmi.spi.OutboundResponse;(Unknown Source)
at weblogic.rmi.internal.activation.ActivatableServerRef.invoke(Lweblogic.rmi.extensions.server.RuntimeMethodDescriptor;Lweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;)V(Optimized Method)
at weblogic.rmi.internal.BasicServerRef$1.run()Ljava.lang.Object;(Optimized Method)
at weblogic.security.acl.internal.AuthenticatedSubject.doAs(Lweblogic.security.subject.AbstractSubject;Ljava.security.PrivilegedExceptionAction;)Ljava.lang.Object;(Optimized Method)
at weblogic.security.service.SecurityManager.runAs(Lweblogic.security.acl.internal.AuthenticatedSubject;Lweblogic.security.acl.internal.AuthenticatedSubject;Ljava.security.PrivilegedExceptionAction;)Ljava.lang.Object;(Optimized Method)
at weblogic.rmi.internal.BasicServerRef.handleRequest(Lweblogic.rmi.spi.InboundRequest;)V(Optimized Method)
at weblogic.rmi.internal.BasicExecuteRequest.execute(Lweblogic.kernel.ExecuteThread;)V(Optimized Method)
at weblogic.kernel.ExecuteThread.execute(Lweblogic.kernel.ExecuteRequest;)V(Optimized Method)
at weblogic.kernel.ExecuteThread.run()V(ExecuteThread.java:178)
at java.lang.Thread.startThreadFromVM(Ljava.lang.Thread;)V(Unknown Source)

Name and version of the database you are using:
MS SQL Sever 2000 SP4.

The generated SQL (show_sql=true):
Hibernate:
select
extinstrum0_.ext_instr_num_id as ext1_45_,
extinstrum0_.instr_id as instr2_45_,
extinstrum0_.ext_sys_typ_c as ext3_45_,
extinstrum0_.ext_sys_instr_id as ext4_45_
from
BEQUEST.dbo.ext_instrument_num extinstrum0_
where
extinstrum0_.ext_sys_instr_id=?
and extinstrum0_.ext_sys_typ_c=?
Hibernate:
select
bond0_.instr_id as instr1_6_,
bond0_1_.instr_typ_c as instr2_6_,
bond0_1_.instr_list_typ_c as instr3_6_,
bond0_1_.registrar_id as registrar4_6_,
bond0_1_.tier_typ_c as tier5_6_,
bond0_1_.issuer_id as issuer6_6_,
bond0_1_.face_curr_c as face7_6_,
bond0_1_.trade_curr_c as trade8_6_,
bond0_1_.short_nm as short9_6_,
bond0_1_.legal_nm as legal10_6_,
bond0_1_.short_nm_alt as short11_6_,
bond0_1_.legal_nm_alt as legal12_6_,
bond0_1_.face_amount_a as face13_6_,
bond0_1_.par_q as par14_6_,
bond0_1_.orig_issue_d as orig15_6_,
bond0_1_.orig_issue_q as orig16_6_,
bond0_1_.userid as userid6_,
bond0_1_.stamp as stamp6_,
bond0_1_.portfolio_id as portfolio19_6_,
bond0_.instr_id as instr1_8_,
bond0_.guarantor_id as guarantor2_8_,
bond0_.bond_typ_c as bond3_8_,
bond0_.avr_fx_rule_c as avr4_8_,
bond0_.yield_rule_c as yield5_8_,
bond0_.pay_agent_id as pay6_8_,
bond0_.bond_form_c as bond7_8_,
bond0_.issue_fx_rule_c as issue8_8_,
bond0_.ai_rule_c as ai9_8_,
bond0_.mature_d as mature10_8_,
bond0_.expire_d as expire11_8_,
bond0_.refunding_d as refunding12_8_,
bond0_.redeem_p as redeem13_8_,
bond0_.interest_rate_p as interest14_8_,
bond0_.fst_cpn_due_d as fst15_8_,
bond0_.lst_cpn_due_d as lst16_8_,
bond0_.tot_cpn_num as tot17_8_,
bond0_.for_offshore_f as for18_8_,
bond0_.no_fraction_f as no19_8_,
bond0_.tax_free_f as tax20_8_,
bond0_.gain_tax_free_f as gain21_8_,
bond0_.userid as userid8_,
bond0_.stamp as stamp8_
from
dbo.bond bond0_
inner join
BEQUEST.dbo.instrument bond0_1_
on bond0_.instr_id=bond0_1_.instr_id
inner join
BEQUEST.dbo.instrument instrument1_
on bond0_.instr_id=instrument1_.instr_id
inner join
BEQUEST.dbo.instrument_num instrument2_
on instrument1_.instr_id=instrument2_.instr_id
inner join
BEQUEST.dbo.instrument_num_type_c instrument3_
on instrument2_.instr_num_typ_c=instrument3_.code
where
instrument3_.code=?
and instrument2_.num_tx=?
Hibernate:
select
issuer0_.pty_id as pty1_0_,
issuer0_.country_c as country2_0_,
issuer0_.sector_typ_c as sector3_0_,
issuer0_.UBS_nb as UBS4_0_,
issuer0_.MNS_a as MNS5_0_,
issuer0_.userid as userid0_,
issuer0_.stamp as stamp0_
from
BEQUEST.dbo.issuer issuer0_,
BEQUEST.dbo.party_properties partyprope1_
where
issuer0_.pty_id=partyprope1_.pty_id
and partyprope1_.property_typ_c=?
and partyprope1_.property_value=?
Hibernate:
select
registrati0_.pty_id as pty1_1_,
registrati0_.reg_period_num as reg2_1_,
registrati0_.userid as userid1_,
registrati0_.stamp as stamp1_
from
BEQUEST.dbo.registration_agents registrati0_,
BEQUEST.dbo.party_properties partyprope1_,
BEQUEST.dbo.transaction_party transactio2_,
BEQUEST.dbo.transaction_statuses transactio3_,
BEQUEST.dbo.transactions transactio4_
where
transactio2_.tran_id=transactio4_.tran_id
and registrati0_.pty_id=partyprope1_.pty_id
and partyprope1_.property_typ_c=?
and partyprope1_.property_value=?
and transactio2_.pty_id=registrati0_.pty_id
and transactio3_.trans_status_c=transactio4_.trans_status_c
and transactio3_.freeze_f=?
Hibernate:
select
extinstrum0_.ext_instr_id as ext1_43_,
extinstrum0_.ext_instr_num_id as ext2_43_,
extinstrum0_.instr_id as instr3_43_,
extinstrum0_.registrar_id as registrar4_43_,
extinstrum0_.traded_curr_id as traded5_43_,
extinstrum0_.issuer_id as issuer6_43_,
extinstrum0_.face_curr_id as face7_43_,
extinstrum0_.instr_class_id as instr8_43_,
extinstrum0_.share_class_id as share9_43_,
extinstrum0_.share_class_nm as share10_43_,
extinstrum0_.isin_num as isin11_43_,
extinstrum0_.issue_num as issue12_43_,
extinstrum0_.micex_num as micex13_43_,
extinstrum0_.dcc_num as dcc14_43_,
extinstrum0_.issuer_olympic_num as issuer15_43_,
extinstrum0_.short_nm as short16_43_,
extinstrum0_.legal_nm as legal17_43_,
extinstrum0_.short_nm_alt as short18_43_,
extinstrum0_.legal_nm_alt as legal19_43_,
extinstrum0_.registrar_olympic_num as registrar20_43_,
extinstrum0_.traded_curr_nm as traded21_43_,
extinstrum0_.face_curr_nm as face22_43_,
extinstrum0_.par_q as par23_43_,
extinstrum0_.processed_f as processed24_43_,
extinstrum0_.deleted_f as deleted25_43_,
extinstrum0_.locked_f as locked26_43_,
extinstrum0_.userid as userid43_,
extinstrum0_.stamp as stamp43_,
extinstrum0_.deleted_userid as deleted29_43_,
extinstrum0_.deleted_stamp as deleted30_43_
from
BEQUEST.dbo.ext_instrument extinstrum0_
where
extinstrum0_.isin_num=?
and extinstrum0_.processed_f=?
and extinstrum0_.deleted_f=?
and extinstrum0_.stamp<?
and extinstrum0_.issue_num=?
Hibernate:
select
extsystemt_.code,
extsystemt_.name as name44_,
extsystemt_.description as descript3_44_,
extsystemt_.name_alt as name4_44_,
extsystemt_.descr_alt as descr5_44_
from
BEQUEST.dbo.ext_system_type_c extsystemt_
where
extsystemt_.code=?
Hibernate:
insert
into
BEQUEST.dbo.ext_instrument_num
(instr_id, ext_sys_typ_c, ext_sys_instr_id)
values
(?, ?, ?) select
scope_identity()
Hibernate:
select
currencyc_.code,
currencyc_.iso as iso29_,
currencyc_.active_f as active3_29_,
currencyc_.name as name29_,
currencyc_.name_alt as name5_29_,
currencyc_.curr_nb as curr6_29_,
currencyc_.rts_code as rts7_29_
from
BEQUEST.dbo.currency_c currencyc_
where
currencyc_.code=?
Hibernate:
select
instrument_.code,
instrument_.name as name23_,
instrument_.description as descript3_23_,
instrument_.name_alt as name4_23_,
instrument_.descr_alt as descr5_23_
from
BEQUEST.dbo.instrument_type_c instrument_
where
instrument_.code=?
Hibernate:
insert
into
BEQUEST.dbo.ext_instrument
(ext_instr_num_id, instr_id, registrar_id, traded_curr_id, issuer_id, face_curr_id, instr_class_id, share_class_
id, share_class_nm, isin_num, issue_num, micex_num, dcc_num, issuer_olympic_num, short_nm, legal_nm, short_nm_alt, legal
_nm_alt, registrar_olympic_num, traded_curr_nm, face_curr_nm, par_q, processed_f, deleted_f, locked_f, userid, stamp, de
leted_userid, deleted_stamp)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) select
scope_identity()
Hibernate:
update
BEQUEST.dbo.ext_instrument
set
ext_instr_num_id=?,
instr_id=?,
registrar_id=?,
traded_curr_id=?,
issuer_id=?,
face_curr_id=?,
instr_class_id=?,
share_class_id=?,
share_class_nm=?,
isin_num=?,
issue_num=?,
micex_num=?,
dcc_num=?,
issuer_olympic_num=?,
short_nm=?,
legal_nm=?,
short_nm_alt=?,
legal_nm_alt=?,
registrar_olympic_num=?,
traded_curr_nm=?,
face_curr_nm=?,
par_q=?,
processed_f=?,
deleted_f=?,
locked_f=?,
userid=?,
stamp=?,
deleted_userid=?,
deleted_stamp=?
where
ext_instr_id=?

Debug level Hibernate log excerpt:
10:13:51,698 INFO Configuration:1308 - configuring from resource: /hibernate_config/hb_bequest_wls.cfg.xml
10:13:51,698 INFO Configuration:1285 - Configuration resource: /hibernate_config/hb_bequest_wls.cfg.xml
10:13:51,698 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/Issuer.hbm.xml
10:13:51,698 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.Issuer -> issuer
10:13:51,698 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/RegistrationAgents.hbm.xml
10:13:51,714 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.RegistrationAgents -> registration_agents
10:13:51,714 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondCoupon.hbm.xml
10:13:51,714 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondCoupon -> bond_coupon
10:13:51,729 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/Market.hbm.xml
10:13:51,729 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.Market -> market
10:13:51,729 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/LanguageC.hbm.xml
10:13:51,729 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.LanguageC -> language_c
10:13:51,729 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/PartyRole.hbm.xml
10:13:51,745 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.PartyRole -> party_role
10:13:51,745 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/Instrument.hbm.xml
10:13:51,761 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.Instrument -> instrument
10:13:51,761 INFO HbmBinder:883 - Mapping joined-subclass: com.bubsw.bequest.sd.link.model.Stock -> stock
10:13:51,761 INFO HbmBinder:883 - Mapping joined-subclass: com.bubsw.bequest.sd.link.model.Bond -> bond
10:13:51,761 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrumentGroup.hbm.xml
10:13:51,761 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrumentGroup -> instrument_group
10:13:51,761 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondIssueFxRuleC.hbm.xml
10:13:51,776 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondIssueFxRuleC -> bond_issue_fx_rule_c
10:13:51,776 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/IssuerSectorTypeC.hbm.xml
10:13:51,776 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.IssuerSectorTypeC -> issuer_sector_type_c
10:13:51,776 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/RoleTypeC.hbm.xml
10:13:51,792 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.RoleTypeC -> role_type_c
10:13:51,792 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/MarketInstrument.hbm.xml
10:13:51,792 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.MarketInstrument -> market_instrument
10:13:51,792 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/IssuerGroupC.hbm.xml
10:13:51,807 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.IssuerGroupC -> issuer_group_c
10:13:51,807 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondAiRuleC.hbm.xml
10:13:51,807 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondAiRuleC -> bond_ai_rule_c
10:13:51,807 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/StockIssue.hbm.xml
10:13:51,823 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.StockIssue -> stock_issue
10:13:51,823 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/CommentTypeC.hbm.xml
10:13:51,823 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.CommentTypeC -> comment_type_c
10:13:51,823 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondYieldRuleC.hbm.xml
10:13:51,823 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondYieldRuleC -> bond_yield_rule_c
10:13:51,839 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondCouponTypeC.hbm.xml
10:13:51,839 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondCouponTypeC -> bond_coupon_type_c
10:13:51,839 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondTypeC.hbm.xml
10:13:51,839 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondTypeC -> bond_type_c
10:13:51,839 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/StockTierTypeC.hbm.xml
10:13:51,854 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.StockTierTypeC -> stock_tier_type_c
10:13:51,854 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/IssuerGroup.hbm.xml
10:13:51,854 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.IssuerGroup -> issuer_group
10:13:51,854 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrumentTypeC.hbm.xml
10:13:51,870 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrumentTypeC -> instrument_type_c
10:13:51,870 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/CountryC.hbm.xml
10:13:51,870 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.CountryC -> country_c
10:13:51,870 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrTierTypeC.hbm.xml
10:13:51,886 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrTierTypeC -> instr_tier_type_c
10:13:51,886 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/StockTypeC.hbm.xml
10:13:51,886 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.StockTypeC -> stock_type_c
10:13:51,886 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/Party.hbm.xml
10:13:51,901 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.Party -> party
10:13:51,901 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/CommsTypeC.hbm.xml
10:13:51,901 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.CommsTypeC -> comms_type_c
10:13:51,901 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/CurrencyC.hbm.xml
10:13:51,917 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.CurrencyC -> currency_c
10:13:51,917 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrumentMark.hbm.xml
10:13:51,917 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrumentMark -> instrument_mark
10:13:51,917 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/MarketSubtypeC.hbm.xml
10:13:51,932 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.MarketSubtypeC -> market_subtype_c
10:13:51,932 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrumentGroupC.hbm.xml
10:13:51,932 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrumentGroupC -> instrument_group_c
10:13:51,932 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/CommsDetails.hbm.xml
10:13:51,948 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.CommsDetails -> comms_details
10:13:51,948 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondAvrFxRuleC.hbm.xml
10:13:51,948 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondAvrFxRuleC -> bond_avr_fx_rule_c
10:13:51,948 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/Corporate.hbm.xml
10:13:51,964 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.Corporate -> corporate
10:13:51,964 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrListTypeC.hbm.xml
10:13:51,964 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrListTypeC -> instr_list_type_c
10:13:51,964 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrumentNum.hbm.xml
10:13:51,979 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrumentNum -> instrument_num
10:13:51,979 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/InstrumentNumTypeC.hbm.xml
10:13:51,979 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.InstrumentNumTypeC -> instrument_num_type_c
10:13:51,979 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/PartyTypeC.hbm.xml
10:13:51,995 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.PartyTypeC -> party_type_c
10:13:51,995 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/BondFormC.hbm.xml
10:13:51,995 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.BondFormC -> bond_form_c
10:13:51,995 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/PartyProperties.hbm.xml
10:13:52,010 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.PartyProperties -> party_properties
10:13:52,010 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/PropertyTypeC.hbm.xml
10:13:52,010 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.PropertyTypeC -> property_type_c
10:13:52,010 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/ExtInstrument.hbm.xml
10:13:52,026 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.ExtInstrument -> ext_instrument
10:13:52,026 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/ExtSystemTypeC.hbm.xml
10:13:52,026 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.ExtSystemTypeC -> ext_system_type_c
10:13:52,026 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/ExtInstrumentNum.hbm.xml
10:13:52,042 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.ExtInstrumentNum -> ext_instrument_num
10:13:52,042 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/TransactionInstrument.hbm.xml
10:13:52,042 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.TransactionInstrument -> transaction_instrument
10:13:52,042 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/TransactionStages.hbm.xml
10:13:52,057 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.TransactionStages -> transaction_stages
10:13:52,057 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/Transactions.hbm.xml
10:13:52,057 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.Transactions -> transactions
10:13:52,057 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/TransactionStatuses.hbm.xml
10:13:52,073 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.TransactionStatuses -> transaction_statuses
10:13:52,073 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/TransactionStatusC.hbm.xml
10:13:52,073 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.TransactionStatusC -> transaction_status_c
10:13:52,073 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/TransactionStageC.hbm.xml
10:13:52,089 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.TransactionStageC -> transaction_stage_c
10:13:52,089 INFO Configuration:469 - Reading mappings from resource: com/bubsw/bequest/sd/link/model/TransactionParty.hbm.xml
10:13:52,089 INFO HbmBinder:309 - Mapping class: com.bubsw.bequest.sd.link.model.TransactionParty -> transaction_party
10:13:52,089 INFO Configuration:1419 - Configured SessionFactory: null
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Issuer.issuerGroups -> issuer_group
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Issuer.instruments -> instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Issuer.extInstruments -> ext_instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.RegistrationAgents.instruments -> instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.RegistrationAgents.extInstruments -> ext_instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Market.marketInstruments -> market_instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.LanguageC.parties -> party
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.stocks -> stock
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.instrumentGroups -> instrument_group
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.bonds -> bond
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.instrumentMarks -> instrument_mark
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.marketInstruments -> market_instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.transactionInstruments -> transaction_instrument
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Instrument.instrumentNums -> instrument_num
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Stock.stockIssues -> stock_issue
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Bond.bondCoupons -> bond_coupon
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondIssueFxRuleC.bonds -> bond
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.IssuerSectorTypeC.issuers -> issuer
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.RoleTypeC.partyRoles -> party_role
10:13:52,089 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.IssuerGroupC.issuerGroups -> issuer_group
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondAiRuleC.bonds -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondYieldRuleC.bonds -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondCouponTypeC.bondCoupons -> bond_coupon
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondTypeC.bonds -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.InstrumentTypeC.instruments -> instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.InstrumentTypeC.extInstruments -> ext_instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CountryC.partiesForIncorpCountryC -> party
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CountryC.partiesForDomicileC -> party
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CountryC.issuers -> issuer
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.InstrTierTypeC.instruments -> instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.StockTypeC.stocks -> stock
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Party.partyPropertieses -> party_properties
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Party.transactionParties -> transaction_party
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Party.partyRoles -> party_role
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Party.corporates -> corporate
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CommsTypeC.commsDetailses -> comms_details
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CurrencyC.extInstrumentsForTradedCurrId -> ext_instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CurrencyC.parties -> party
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CurrencyC.extInstrumentsForFaceCurrId -> ext_instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CurrencyC.instrumentsForTradeCurrC -> instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.CurrencyC.instrumentsForFaceCurrC -> instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.InstrumentGroupC.instrumentGroups -> instrument_group
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondAvrFxRuleC.bonds -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Corporate.registrationAgentses -> registration_agents
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Corporate.bondsForGuarantorId -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Corporate.issuers -> issuer
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Corporate.bondsForPayAgentId -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Corporate.markets -> market
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.InstrListTypeC.instruments -> instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.InstrumentNumTypeC.instrumentNums -> instrument_num
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.PartyTypeC.parties -> party
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.BondFormC.bonds -> bond
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.PropertyTypeC.partyPropertieses -> party_properties
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.ExtSystemTypeC.extInstrumentNums -> ext_instrument_num
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.ExtInstrumentNum.extInstruments -> ext_instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Transactions.transactionses -> transactions
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.Transactions.transactionInstruments -> transaction_instrument
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.TransactionStatusC.transactionStageses -> transaction_stages
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.TransactionStatusC.transactionses -> transactions
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.TransactionStatusC.transactionStatuseses -> transaction_statuses
10:13:52,104 INFO HbmBinder:2349 - Mapping collection: com.bubsw.bequest.sd.link.model.TransactionStageC.transactionStageses -> transaction_stages
10:13:52,182 INFO NamingHelper:26 - JNDI InitialContext properties:{}
10:13:52,182 INFO DatasourceConnectionProvider:61 - Using datasource: jdbc/EquitiesDB_MSSQLTX
10:13:52,182 INFO SettingsFactory:77 - RDBMS: Microsoft SQL Server, version: Microsoft SQL Server 2000 - 8.00.2039 (Intel X86)
May 3 2005 23:18:38
Copyright (c) 1988-2003 Microsoft Corporation
Enterprise Edition on Windows NT 5.2 (Build 3790: Service Pack 1)

10:13:52,182 INFO SettingsFactory:78 - JDBC driver: SQLServer, version: 2.2.0040
10:13:52,182 INFO Dialect:103 - Using dialect: org.hibernate.dialect.SQLServerDialect
10:13:52,182 INFO TransactionFactoryFactory:34 - Transaction strategy: org.hibernate.transaction.CMTTransactionFactory
10:13:52,182 INFO TransactionManagerLookupFactory:38 - instantiating TransactionManagerLookup: org.hibernate.transaction.WeblogicTransactionManagerLookup
10:13:52,182 INFO TransactionManagerLookupFactory:42 - instantiated TransactionManagerLookup
10:13:52,182 INFO SettingsFactory:125 - Automatic flush during beforeCompletion(): disabled
10:13:52,182 INFO SettingsFactory:129 - Automatic session close at end of transaction: disabled
10:13:52,182 INFO SettingsFactory:144 - Scrollable result sets: enabled
10:13:52,182 INFO SettingsFactory:152 - JDBC3 getGeneratedKeys(): disabled
10:13:52,182 INFO SettingsFactory:160 - Connection release mode: auto
10:13:52,198 INFO SettingsFactory:178 - Default schema: dbo
10:13:52,198 INFO SettingsFactory:187 - Default batch fetch size: 1
10:13:52,198 INFO SettingsFactory:191 - Generate SQL with comments: disabled
10:13:52,198 INFO SettingsFactory:195 - Order SQL updates by primary key: disabled
10:13:52,198 INFO SettingsFactory:338 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
10:13:52,198 INFO ASTQueryTranslatorFactory:24 - Using ASTQueryTranslatorFactory
10:13:52,198 INFO SettingsFactory:203 - Query language substitutions: {}
10:13:52,198 INFO SettingsFactory:209 - Second-level cache: enabled
10:13:52,198 INFO SettingsFactory:213 - Query cache: disabled
10:13:52,198 INFO SettingsFactory:325 - Cache provider: org.hibernate.cache.NoCacheProvider
10:13:52,198 INFO SettingsFactory:228 - Optimize cache for minimal puts: disabled
10:13:52,198 INFO SettingsFactory:237 - Structured second-level cache entries: disabled
10:13:52,198 INFO SettingsFactory:257 - Echoing all SQL to stdout
10:13:52,198 INFO SettingsFactory:264 - Statistics: disabled
10:13:52,198 INFO SettingsFactory:268 - Deleted entity synthetic identifier rollback: disabled
10:13:52,198 INFO SettingsFactory:283 - Default entity-mode: pojo
10:13:52,198 INFO SessionFactoryImpl:154 - building session factory
10:13:53,400 INFO SessionFactoryObjectFactory:82 - Not binding factory to JNDI, no JNDI name configured
10:13:53,400 INFO NamingHelper:26 - JNDI InitialContext properties:{}
10:13:53,400 DEBUG AbstractLinguaProcessor:74 - Instrument numbers have been set.
10:13:53,510 DEBUG AbstractLinguaProcessor:87 - Instrument related details have been set.
10:13:53,541 DEBUG AbstractLinguaProcessor:91 - Issuer has been set.
10:13:53,541 DEBUG AbstractLinguaProcessor:95 - Instrument names have been set.
10:13:53,588 DEBUG AbstractLinguaProcessor:99 - Issue details have been set.
10:13:53,588 DEBUG AbstractLinguaProcessor:103 - Specific issue details have been set.
10:13:53,588 DEBUG AbstractLinguaProcessor:105 - Instrument has been processed by StaticData EJB.
10:13:53,588 DEBUG StaticDataSessionBank:520 - Ext.Instrument flags have been set.
10:13:53,635 DEBUG StaticDataSessionBank:525 - Ext.Instrument has been arranged.


Kind regards,
Alex
10:13:53,635 ERROR AbstractFlushingEventListener:300 - Could not synchronize database state with session

_________________
--------------------------
Kind regards,
Alex


Top
 Profile  
 
 Post subject:
PostPosted: Thu Dec 07, 2006 1:55 pm 
Newbie

Joined: Thu Nov 30, 2006 10:44 am
Posts: 6
Location: Moscow
Managed to obtain debug log from Hibernate but unable to find anything criminal:

2006-12-07 16:00:21 DEBUG JDBCContext:155 - successfully registered Synchronization
2006-12-07 16:00:21 DEBUG SessionImpl:219 - opened session at timestamp: 11654964210

...

2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1941 - Inserting entity: com.bubsw.bequest.sd.link.model.ExtInstrumentNum (native id)
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
insert
into
BEQUEST.dbo.ext_instrument_num
(instr_id, ext_sys_typ_c, ext_sys_instr_id)
values
(?, ?, ?) select
scope_identity()
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [com.bubsw.bequest.sd.link.model.ExtInstrumentNum#<null>]
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 1
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '1' to parameter: 2
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '83' to parameter: 3
2006-12-07 16:00:21 DEBUG IdentifierGeneratorFactory:37 - Natively generated identity: 38
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG DefaultSaveOrUpdateEventListener:161 - saving transient instance
2006-12-07 16:00:21 DEBUG AbstractSaveEventListener:139 - saving [com.bubsw.bequest.sd.link.model.ExtInstrument#<null>]
2006-12-07 16:00:21 DEBUG AbstractSaveEventListener:221 - executing insertions
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:975 - Getting current persistent state for: [com.bubsw.bequest.sd.link.model.CurrencyC#3]
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
select
currencyc_.code,
currencyc_.iso as iso29_,
currencyc_.active_f as active3_29_,
currencyc_.name as name29_,
currencyc_.name_alt as name5_29_,
currencyc_.curr_nb as curr6_29_,
currencyc_.rts_code as rts7_29_
from
BEQUEST.dbo.currency_c currencyc_
where
currencyc_.code=?
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 1
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'RUB' as column: iso29_
2006-12-07 16:00:21 DEBUG CharacterType:122 - returning 'Y' as column: active3_29_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'Russian Rouble' as column: name29_
2006-12-07 16:00:21 DEBUG StringType:116 - returning null as column: name5_29_
2006-12-07 16:00:21 DEBUG StringType:122 - returning '643' as column: curr6_29_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'RUR' as column: rts7_29_
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:975 - Getting current persistent state for: [com.bubsw.bequest.sd.link.model.InstrumentTypeC#2]
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
select
instrument_.code,
instrument_.name as name23_,
instrument_.description as descript3_23_,
instrument_.name_alt as name4_23_,
instrument_.descr_alt as descr5_23_
from
BEQUEST.dbo.instrument_type_c instrument_
where
instrument_.code=?
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '2' to parameter: 1
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'Bond' as column: name23_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'Bond' as column: descript3_23_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'о....а...' as column: name4_23_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'о....а...' as column: descr5_23_
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1941 - Inserting entity: com.bubsw.bequest.sd.link.model.ExtInstrument (native id)
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
insert
into
BEQUEST.dbo.ext_instrument
(ext_instr_num_id, instr_id, registrar_id, traded_curr_id, issuer_id, face_curr_id, instr_class_id, share_class_id, share_class_nm, isin_num, issue_num, micex_num, dcc_num, issuer_olympic_num, short_nm, legal_nm, short_nm_alt, legal_nm_alt, registrar_olympic_num, traded_curr_nm, face_curr_nm, par_q, processed_f, deleted_f, locked_f, userid, stamp, deleted_userid, deleted_stamp, russian_num)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) select
scope_identity()
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#<null>]
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '38' to parameter: 1
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 2
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '5805' to parameter: 3
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 4
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 5
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 6
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '2' to parameter: 7
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 8
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 9
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 10
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '1' to parameter: 11
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 12
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 13
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8222287' to parameter: 14
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 15
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 16
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ТНК ВР Хо...н.' to parameter: 17
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ОАО "ТНК-BP Хо...н." о....а... сер.. K' to parameter: 18
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8642724' to parameter: 19
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 20
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 21
2006-12-07 16:00:21 DEBUG LongType:80 - binding '1000' to parameter: 22
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 23
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 24
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'Y' to parameter: 25
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'BQ IS link' to parameter: 26
2006-12-07 16:00:21 DEBUG TimestampType:80 - binding '2006-12-07 16:00:20' to parameter: 27
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 28
2006-12-07 16:00:21 DEBUG TimestampType:73 - binding null to parameter: 29
2006-12-07 16:00:21 DEBUG StringType:80 - binding '4-01-55034-E' to parameter: 30
2006-12-07 16:00:21 DEBUG IdentifierGeneratorFactory:37 - Natively generated identity: 47
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG StaticDataSessionBank:654 - External instrument has been arranged and set.
2006-12-07 16:00:21 DEBUG StaticDataSessionBank:628 - Exiting setExtInstrumentLink(message) method.
2006-12-07 16:00:21 DEBUG CacheSynchronization:40 - transaction before completion callback
2006-12-07 16:00:21 DEBUG CacheSynchronization:58 - automatically flushing session
2006-12-07 16:00:21 DEBUG SessionImpl:332 - automatically flushing session
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:58 - flushing session
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:111 - processing flush-time cascades
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:153 - dirty checking collections
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:170 - Flushing entities and processing referenced collections
2006-12-07 16:00:21 DEBUG Collections:176 - Collection found: [com.bubsw.bequest.sd.link.model.RegistrationAgents.instruments#5805], was: [com.bubsw.bequest.sd.link.model.RegistrationAgents.instruments#5805] (uninitialized)
2006-12-07 16:00:21 DEBUG Collections:176 - Collection found: [com.bubsw.bequest.sd.link.model.RegistrationAgents.extInstruments#5805], was: [com.bubsw.bequest.sd.link.model.RegistrationAgents.extInstruments#5805] (uninitialized)
2006-12-07 16:00:21 DEBUG Collections:176 - Collection found: [com.bubsw.bequest.sd.link.model.ExtInstrumentNum.extInstruments#38], was: [<unreferenced>] (initialized)
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:2863 - com.bubsw.bequest.sd.link.model.ExtInstrument.lockedF is dirty
2006-12-07 16:00:21 DEBUG DefaultFlushEntityEventListener:222 - Updating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#47]
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:209 - Processing unreferenced collections
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:223 - Scheduling collection removes/(re)creates/updates
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:85 - Flushed: 0 insertions, 1 updates, 0 deletions to 3 objects
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:91 - Flushed: 1 (re)creations, 0 updates, 0 removals to 3 collections
2006-12-07 16:00:21 DEBUG Printer:83 - listing entities:
2006-12-07 16:00:21 DEBUG Printer:90 - com.bubsw.bequest.sd.link.model.ExtInstrumentNum{instrument=null, extInstruments=[com.bubsw.bequest.sd.link.model.ExtInstrument#47], extSysInstrId=83, extInstrNumId=38, extSystemTypeC=com.bubsw.bequest.sd.link.model.ExtSystemTypeC#1}
2006-12-07 16:00:21 DEBUG Printer:90 - com.bubsw.bequest.sd.link.model.ExtInstrument{dccNum=null, extInstrId=47, russianNum=4-01-55034-E, tradedCurrNm=RUR, deletedUserid=null, processedF=N, registrationAgents=com.bubsw.bequest.sd.link.model.RegistrationAgents#5805, deletedF=N, isinNum=RU000A0GK1T7, registrarOlympicNum=8642724, userid=BQ IS link, shareClassId=null, issuerOlympicNum=8222287, issueNum=1, faceCurrNm=RUR, shortNm=TNK-BP HOLDING, shareClassNm=null, instrumentTypeC=com.bubsw.bequest.sd.link.model.InstrumentTypeC#2, instrument=null, parQ=1000, currencyCByFaceCurrId=com.bubsw.bequest.sd.link.model.CurrencyC#3, currencyCByTradedCurrId=com.bubsw.bequest.sd.link.model.CurrencyC#3, stamp=2006-12-07 16:00:20, issuer=null, legalNm=TNK-BP HOLDING, shortNmAlt=ТНК ВР Хо...н., legalNmAlt=ОАО "ТНК-BP Хо...н." о....а... сер.. K, lockedF=N, micexNum=RU000A0GK1T7, deletedStamp=null, extInstrumentNum=com.bubsw.bequest.sd.link.model.ExtInstrumentNum#38}
2006-12-07 16:00:21 DEBUG Printer:90 - com.bubsw.bequest.sd.link.model.RegistrationAgents{instruments=<uninitialized>, regPeriodNum=0, extInstruments=<uninitialized>, userid=ATotskay, ptyId=5805, stamp=2006-11-28 14:37:02, corporate=com.bubsw.bequest.sd.link.model.Corporate#5805}
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:289 - executing flush
2006-12-07 16:00:21 DEBUG ConnectionManager:463 - registering flush begin
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:2149 - Updating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#47]
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
update
BEQUEST.dbo.ext_instrument
set
ext_instr_num_id=?,
instr_id=?,
registrar_id=?,
traded_curr_id=?,
issuer_id=?,
face_curr_id=?,
instr_class_id=?,
share_class_id=?,
share_class_nm=?,
isin_num=?,
issue_num=?,
micex_num=?,
dcc_num=?,
issuer_olympic_num=?,
short_nm=?,
legal_nm=?,
short_nm_alt=?,
legal_nm_alt=?,
registrar_olympic_num=?,
traded_curr_nm=?,
face_curr_nm=?,
par_q=?,
processed_f=?,
deleted_f=?,
locked_f=?,
userid=?,
stamp=?,
deleted_userid=?,
deleted_stamp=?,
russian_num=?
where
ext_instr_id=?
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#47]
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '38' to parameter: 1
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 2
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '5805' to parameter: 3
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 4
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 5
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 6
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '2' to parameter: 7
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 8
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 9
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 10
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '1' to parameter: 11
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 12
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 13
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8222287' to parameter: 14
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 15
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 16
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ТНК ВР Хо...н.' to parameter: 17
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ОАО "ТНК-BP Хо...н." о....а... сер.. K' to parameter: 18
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8642724' to parameter: 19
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 20
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 21
2006-12-07 16:00:21 DEBUG LongType:80 - binding '1000' to parameter: 22
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 23
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 24
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 25
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'BQ IS link' to parameter: 26
2006-12-07 16:00:21 DEBUG TimestampType:80 - binding '2006-12-07 16:00:20' to parameter: 27
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 28
2006-12-07 16:00:21 DEBUG TimestampType:73 - binding null to parameter: 29
2006-12-07 16:00:21 DEBUG StringType:80 - binding '4-01-55034-E' to parameter: 30
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '47' to parameter: 31
2006-12-07 16:00:21 ERROR AbstractFlushingEventListener:300 - Could not synchronize database state with session
org.hibernate.StaleStateException: Unexpected row count: 0 expected: 1
at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(I)V(NonBatchingBatcher.java:27)
at org.hibernate.persister.entity.AbstractEntityPersister.update(Ljava.io.Serializable;[Ljava.lang.Object;[Ljava.lang.Object;Ljava.lang.Object;[ZILjava.lang.Object;Ljava.lang.Object;Ljava.lang.String;Lorg.hibernate.engine.SessionImplementor;)Z(AbstractEntityPersister.java:2204)
at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(Ljava.io.Serializable;[Ljava.lang.Object;[Ljava.lang.Object;Ljava.lang.Object;[ZILjava.lang.Object;Ljava.lang.Object;Ljava.lang.String;Lorg.hibernate.engine.SessionImplementor;)V(AbstractEntityPersister.java:2118)
at org.hibernate.persister.entity.AbstractEntityPersister.update(Ljava.io.Serializable;[Ljava.lang.Object;[IZ[Ljava.lang.Object;Ljava.lang.Object;Ljava.lang.Object;Ljava.lang.Object;Lorg.hibernate.engine.SessionImplementor;)V(AbstractEntityPersister.java:2374)
at org.hibernate.action.EntityUpdateAction.execute()V(EntityUpdateAction.java:91)
at org.hibernate.engine.ActionQueue.execute(Lorg.hibernate.action.Executable;)V(ActionQueue.java:248)
at org.hibernate.engine.ActionQueue.executeActions(Ljava.util.List;)V(ActionQueue.java:232)
at org.hibernate.engine.ActionQueue.executeActions()V(ActionQueue.java:140)
at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(Lorg.hibernate.event.EventSource;)V(AbstractFlushingEventListener.java:297)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(Lorg.hibernate.event.FlushEvent;)V(DefaultFlushEventListener.java:27)
at org.hibernate.impl.SessionImpl.flush()V(SessionImpl.java:985)
at org.hibernate.impl.SessionImpl.managedFlush()V(SessionImpl.java:333)
at org.hibernate.transaction.CacheSynchronization.beforeCompletion()V(CacheSynchronization.java:59)
at weblogic.transaction.internal.ServerSCInfo.callBeforeCompletions(Lweblogic.transaction.internal.TransactionImpl;)V(ServerSCInfo.java:1010)
at weblogic.transaction.internal.ServerSCInfo.startPrePrepareAndChain(Lweblogic.transaction.internal.ServerTransactionImpl;I)V(ServerSCInfo.java:115)
at weblogic.transaction.internal.ServerTransactionImpl.localPrePrepareAndChain()V(ServerTransactionImpl.java:1216)
at weblogic.transaction.internal.ServerTransactionImpl.globalPrePrepare()V(ServerTransactionImpl.java:1990)
at weblogic.transaction.internal.ServerTransactionImpl.internalCommit()V(Optimized Method)
at weblogic.transaction.internal.ServerTransactionImpl.commit()V(ServerTransactionImpl.java:246)
at weblogic.ejb20.internal.BaseEJBObject.postInvoke(Lweblogic.ejb20.internal.InvocationWrapper;Ljava.lang.Throwable;)V(Optimized Method)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl.setExtInstrumentLink(Ljava.lang.String;)V(StaticDataSession_muwkuo_EOImpl.java:4265)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl_WLSkel.internalInvoke1(ILweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;Ljava.lang.Object;)Lweblogic.rmi.spi.OutboundResponse;(Unknown Source)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl_WLSkel.invoke(ILweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;Ljava.lang.Object;)Lweblogic.rmi.spi.OutboundResponse;(Unknown Source)
at weblogic.rmi.internal.activation.ActivatableServerRef.invoke(Lweblogic.rmi.extensions.server.RuntimeMethodDescriptor;Lweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;)V(ActivatableServerRef.java:90)
at weblogic.rmi.internal.BasicServerRef$1.run()Ljava.lang.Object;(BasicServerRef.java:420)
at weblogic.security.acl.internal.AuthenticatedSubject.doAs(Lweblogic.security.subject.AbstractSubject;Ljava.security.PrivilegedExceptionAction;)Ljava.lang.Object;(AuthenticatedSubject.java:363)
at weblogic.security.service.SecurityManager.runAs(Lweblogic.security.acl.internal.AuthenticatedSubject;Lweblogic.security.acl.internal.AuthenticatedSubject;Ljava.security.PrivilegedExceptionAction;)Ljava.lang.Object;(SecurityManager.java:147)
at weblogic.rmi.internal.BasicServerRef.handleRequest(Lweblogic.rmi.spi.InboundRequest;)V(BasicServerRef.java:415)
at weblogic.rmi.internal.BasicExecuteRequest.execute(Lweblogic.kernel.ExecuteThread;)V(BasicExecuteRequest.java:30)
at weblogic.kernel.ExecuteThread.execute(Lweblogic.kernel.ExecuteRequest;)V(Optimized Method)
at weblogic.kernel.ExecuteThread.run()V(ExecuteThread.java:178)
at java.lang.Thread.startThreadFromVM(Ljava.lang.Thread;)V(Unknown Source)
2006-12-07 16:00:21 DEBUG ConnectionManager:472 - registering flush end
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 1, globally: 1) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG JDBCContext:185 - before transaction completion
2006-12-07 16:00:21 DEBUG SessionImpl:388 - before transaction completion
2006-12-07 16:00:21 DEBUG CacheSynchronization:82 - transaction after completion callback, status: 4
2006-12-07 16:00:21 DEBUG JDBCContext:199 - after transaction completion
2006-12-07 16:00:21 DEBUG SessionImpl:417 - after transaction completion
2006-12-07 16:00:21 DEBUG CacheSynchronization:89 - automatically closing session
2006-12-07 16:00:21 DEBUG SessionImpl:348 - automatically closing session
2006-12-07 16:00:21 DEBUG SessionImpl:268 - closing session
2006-12-07 16:00:21 DEBUG ConnectionManager:369 - connection already null in cleanup : no action

Kind regards,
Alex

_________________
--------------------------
Kind regards,
Alex


Top
 Profile  
 
 Post subject:
PostPosted: Thu Dec 07, 2006 1:59 pm 
Newbie

Joined: Thu Nov 30, 2006 10:44 am
Posts: 6
Location: Moscow
Managed to obtain debug log from Hibernate but unable to find anything criminal:

2006-12-07 16:00:21 DEBUG JDBCContext:155 - successfully registered Synchronization
2006-12-07 16:00:21 DEBUG SessionImpl:219 - opened session at timestamp: 11654964210

...

2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1941 - Inserting entity: com.bubsw.bequest.sd.link.model.ExtInstrumentNum (native id)
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
insert
into
BEQUEST.dbo.ext_instrument_num
(instr_id, ext_sys_typ_c, ext_sys_instr_id)
values
(?, ?, ?) select
scope_identity()
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [com.bubsw.bequest.sd.link.model.ExtInstrumentNum#<null>]
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 1
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '1' to parameter: 2
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '83' to parameter: 3
2006-12-07 16:00:21 DEBUG IdentifierGeneratorFactory:37 - Natively generated identity: 38
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG DefaultSaveOrUpdateEventListener:161 - saving transient instance
2006-12-07 16:00:21 DEBUG AbstractSaveEventListener:139 - saving [com.bubsw.bequest.sd.link.model.ExtInstrument#<null>]
2006-12-07 16:00:21 DEBUG AbstractSaveEventListener:221 - executing insertions
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:975 - Getting current persistent state for: [com.bubsw.bequest.sd.link.model.CurrencyC#3]
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
select
currencyc_.code,
currencyc_.iso as iso29_,
currencyc_.active_f as active3_29_,
currencyc_.name as name29_,
currencyc_.name_alt as name5_29_,
currencyc_.curr_nb as curr6_29_,
currencyc_.rts_code as rts7_29_
from
BEQUEST.dbo.currency_c currencyc_
where
currencyc_.code=?
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 1
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'RUB' as column: iso29_
2006-12-07 16:00:21 DEBUG CharacterType:122 - returning 'Y' as column: active3_29_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'Russian Rouble' as column: name29_
2006-12-07 16:00:21 DEBUG StringType:116 - returning null as column: name5_29_
2006-12-07 16:00:21 DEBUG StringType:122 - returning '643' as column: curr6_29_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'RUR' as column: rts7_29_
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:975 - Getting current persistent state for: [com.bubsw.bequest.sd.link.model.InstrumentTypeC#2]
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
select
instrument_.code,
instrument_.name as name23_,
instrument_.description as descript3_23_,
instrument_.name_alt as name4_23_,
instrument_.descr_alt as descr5_23_
from
BEQUEST.dbo.instrument_type_c instrument_
where
instrument_.code=?
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '2' to parameter: 1
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'Bond' as column: name23_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'Bond' as column: descript3_23_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'о....а...' as column: name4_23_
2006-12-07 16:00:21 DEBUG StringType:122 - returning 'о....а...' as column: descr5_23_
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1941 - Inserting entity: com.bubsw.bequest.sd.link.model.ExtInstrument (native id)
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
insert
into
BEQUEST.dbo.ext_instrument
(ext_instr_num_id, instr_id, registrar_id, traded_curr_id, issuer_id, face_curr_id, instr_class_id, share_class_id, share_class_nm, isin_num, issue_num, micex_num, dcc_num, issuer_olympic_num, short_nm, legal_nm, short_nm_alt, legal_nm_alt, registrar_olympic_num, traded_curr_nm, face_curr_nm, par_q, processed_f, deleted_f, locked_f, userid, stamp, deleted_userid, deleted_stamp, russian_num)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) select
scope_identity()
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#<null>]
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '38' to parameter: 1
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 2
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '5805' to parameter: 3
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 4
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 5
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 6
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '2' to parameter: 7
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 8
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 9
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 10
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '1' to parameter: 11
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 12
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 13
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8222287' to parameter: 14
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 15
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 16
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ТНК ВР Хо...н.' to parameter: 17
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ОАО "ТНК-BP Хо...н." о....а... сер.. K' to parameter: 18
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8642724' to parameter: 19
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 20
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 21
2006-12-07 16:00:21 DEBUG LongType:80 - binding '1000' to parameter: 22
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 23
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 24
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'Y' to parameter: 25
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'BQ IS link' to parameter: 26
2006-12-07 16:00:21 DEBUG TimestampType:80 - binding '2006-12-07 16:00:20' to parameter: 27
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 28
2006-12-07 16:00:21 DEBUG TimestampType:73 - binding null to parameter: 29
2006-12-07 16:00:21 DEBUG StringType:80 - binding '4-01-55034-E' to parameter: 30
2006-12-07 16:00:21 DEBUG IdentifierGeneratorFactory:37 - Natively generated identity: 47
2006-12-07 16:00:21 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-12-07 16:00:21 DEBUG AbstractBatcher:470 - closing statement
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG StaticDataSessionBank:654 - External instrument has been arranged and set.
2006-12-07 16:00:21 DEBUG StaticDataSessionBank:628 - Exiting setExtInstrumentLink(message) method.
2006-12-07 16:00:21 DEBUG CacheSynchronization:40 - transaction before completion callback
2006-12-07 16:00:21 DEBUG CacheSynchronization:58 - automatically flushing session
2006-12-07 16:00:21 DEBUG SessionImpl:332 - automatically flushing session
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:58 - flushing session
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:111 - processing flush-time cascades
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:153 - dirty checking collections
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:170 - Flushing entities and processing referenced collections
2006-12-07 16:00:21 DEBUG Collections:176 - Collection found: [com.bubsw.bequest.sd.link.model.RegistrationAgents.instruments#5805], was: [com.bubsw.bequest.sd.link.model.RegistrationAgents.instruments#5805] (uninitialized)
2006-12-07 16:00:21 DEBUG Collections:176 - Collection found: [com.bubsw.bequest.sd.link.model.RegistrationAgents.extInstruments#5805], was: [com.bubsw.bequest.sd.link.model.RegistrationAgents.extInstruments#5805] (uninitialized)
2006-12-07 16:00:21 DEBUG Collections:176 - Collection found: [com.bubsw.bequest.sd.link.model.ExtInstrumentNum.extInstruments#38], was: [<unreferenced>] (initialized)
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:2863 - com.bubsw.bequest.sd.link.model.ExtInstrument.lockedF is dirty
2006-12-07 16:00:21 DEBUG DefaultFlushEntityEventListener:222 - Updating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#47]
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:209 - Processing unreferenced collections
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:223 - Scheduling collection removes/(re)creates/updates
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:85 - Flushed: 0 insertions, 1 updates, 0 deletions to 3 objects
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:91 - Flushed: 1 (re)creations, 0 updates, 0 removals to 3 collections
2006-12-07 16:00:21 DEBUG Printer:83 - listing entities:
2006-12-07 16:00:21 DEBUG Printer:90 - com.bubsw.bequest.sd.link.model.ExtInstrumentNum{instrument=null, extInstruments=[com.bubsw.bequest.sd.link.model.ExtInstrument#47], extSysInstrId=83, extInstrNumId=38, extSystemTypeC=com.bubsw.bequest.sd.link.model.ExtSystemTypeC#1}
2006-12-07 16:00:21 DEBUG Printer:90 - com.bubsw.bequest.sd.link.model.ExtInstrument{dccNum=null, extInstrId=47, russianNum=4-01-55034-E, tradedCurrNm=RUR, deletedUserid=null, processedF=N, registrationAgents=com.bubsw.bequest.sd.link.model.RegistrationAgents#5805, deletedF=N, isinNum=RU000A0GK1T7, registrarOlympicNum=8642724, userid=BQ IS link, shareClassId=null, issuerOlympicNum=8222287, issueNum=1, faceCurrNm=RUR, shortNm=TNK-BP HOLDING, shareClassNm=null, instrumentTypeC=com.bubsw.bequest.sd.link.model.InstrumentTypeC#2, instrument=null, parQ=1000, currencyCByFaceCurrId=com.bubsw.bequest.sd.link.model.CurrencyC#3, currencyCByTradedCurrId=com.bubsw.bequest.sd.link.model.CurrencyC#3, stamp=2006-12-07 16:00:20, issuer=null, legalNm=TNK-BP HOLDING, shortNmAlt=ТНК ВР Хо...н., legalNmAlt=ОАО "ТНК-BP Хо...н." о....а... сер.. K, lockedF=N, micexNum=RU000A0GK1T7, deletedStamp=null, extInstrumentNum=com.bubsw.bequest.sd.link.model.ExtInstrumentNum#38}
2006-12-07 16:00:21 DEBUG Printer:90 - com.bubsw.bequest.sd.link.model.RegistrationAgents{instruments=<uninitialized>, regPeriodNum=0, extInstruments=<uninitialized>, userid=ATotskay, ptyId=5805, stamp=2006-11-28 14:37:02, corporate=com.bubsw.bequest.sd.link.model.Corporate#5805}
2006-12-07 16:00:21 DEBUG AbstractFlushingEventListener:289 - executing flush
2006-12-07 16:00:21 DEBUG ConnectionManager:463 - registering flush begin
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:2149 - Updating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#47]
2006-12-07 16:00:21 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-12-07 16:00:21 DEBUG ConnectionManager:415 - opening JDBC connection
2006-12-07 16:00:21 DEBUG SQL:346 -
update
BEQUEST.dbo.ext_instrument
set
ext_instr_num_id=?,
instr_id=?,
registrar_id=?,
traded_curr_id=?,
issuer_id=?,
face_curr_id=?,
instr_class_id=?,
share_class_id=?,
share_class_nm=?,
isin_num=?,
issue_num=?,
micex_num=?,
dcc_num=?,
issuer_olympic_num=?,
short_nm=?,
legal_nm=?,
short_nm_alt=?,
legal_nm_alt=?,
registrar_olympic_num=?,
traded_curr_nm=?,
face_curr_nm=?,
par_q=?,
processed_f=?,
deleted_f=?,
locked_f=?,
userid=?,
stamp=?,
deleted_userid=?,
deleted_stamp=?,
russian_num=?
where
ext_instr_id=?
2006-12-07 16:00:21 DEBUG AbstractBatcher:424 - preparing statement
2006-12-07 16:00:21 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [com.bubsw.bequest.sd.link.model.ExtInstrument#47]
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '38' to parameter: 1
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 2
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '5805' to parameter: 3
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 4
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 5
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '3' to parameter: 6
2006-12-07 16:00:21 DEBUG IdentifierValue:77 - id unsaved-value strategy UNDEFINED
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '2' to parameter: 7
2006-12-07 16:00:21 DEBUG IntegerType:73 - binding null to parameter: 8
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 9
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 10
2006-12-07 16:00:21 DEBUG ShortType:80 - binding '1' to parameter: 11
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RU000A0GK1T7' to parameter: 12
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 13
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8222287' to parameter: 14
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 15
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'TNK-BP HOLDING' to parameter: 16
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ТНК ВР Хо...н.' to parameter: 17
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'ОАО "ТНК-BP Хо...н." о....а... сер.. K' to parameter: 18
2006-12-07 16:00:21 DEBUG StringType:80 - binding '8642724' to parameter: 19
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 20
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'RUR' to parameter: 21
2006-12-07 16:00:21 DEBUG LongType:80 - binding '1000' to parameter: 22
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 23
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 24
2006-12-07 16:00:21 DEBUG CharacterType:80 - binding 'N' to parameter: 25
2006-12-07 16:00:21 DEBUG StringType:80 - binding 'BQ IS link' to parameter: 26
2006-12-07 16:00:21 DEBUG TimestampType:80 - binding '2006-12-07 16:00:20' to parameter: 27
2006-12-07 16:00:21 DEBUG StringType:73 - binding null to parameter: 28
2006-12-07 16:00:21 DEBUG TimestampType:73 - binding null to parameter: 29
2006-12-07 16:00:21 DEBUG StringType:80 - binding '4-01-55034-E' to parameter: 30
2006-12-07 16:00:21 DEBUG IntegerType:80 - binding '47' to parameter: 31
2006-12-07 16:00:21 ERROR AbstractFlushingEventListener:300 - Could not synchronize database state with session
org.hibernate.StaleStateException: Unexpected row count: 0 expected: 1
at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(I)V(NonBatchingBatcher.java:27)
at org.hibernate.persister.entity.AbstractEntityPersister.update(Ljava.io.Serializable;[Ljava.lang.Object;[Ljava.lang.Object;Ljava.lang.Object;[ZILjava.lang.Object;Ljava.lang.Object;Ljava.lang.String;Lorg.hibernate.engine.SessionImplementor;)Z(AbstractEntityPersister.java:2204)
at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(Ljava.io.Serializable;[Ljava.lang.Object;[Ljava.lang.Object;Ljava.lang.Object;[ZILjava.lang.Object;Ljava.lang.Object;Ljava.lang.String;Lorg.hibernate.engine.SessionImplementor;)V(AbstractEntityPersister.java:2118)
at org.hibernate.persister.entity.AbstractEntityPersister.update(Ljava.io.Serializable;[Ljava.lang.Object;[IZ[Ljava.lang.Object;Ljava.lang.Object;Ljava.lang.Object;Ljava.lang.Object;Lorg.hibernate.engine.SessionImplementor;)V(AbstractEntityPersister.java:2374)
at org.hibernate.action.EntityUpdateAction.execute()V(EntityUpdateAction.java:91)
at org.hibernate.engine.ActionQueue.execute(Lorg.hibernate.action.Executable;)V(ActionQueue.java:248)
at org.hibernate.engine.ActionQueue.executeActions(Ljava.util.List;)V(ActionQueue.java:232)
at org.hibernate.engine.ActionQueue.executeActions()V(ActionQueue.java:140)
at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(Lorg.hibernate.event.EventSource;)V(AbstractFlushingEventListener.java:297)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(Lorg.hibernate.event.FlushEvent;)V(DefaultFlushEventListener.java:27)
at org.hibernate.impl.SessionImpl.flush()V(SessionImpl.java:985)
at org.hibernate.impl.SessionImpl.managedFlush()V(SessionImpl.java:333)
at org.hibernate.transaction.CacheSynchronization.beforeCompletion()V(CacheSynchronization.java:59)
at weblogic.transaction.internal.ServerSCInfo.callBeforeCompletions(Lweblogic.transaction.internal.TransactionImpl;)V(ServerSCInfo.java:1010)
at weblogic.transaction.internal.ServerSCInfo.startPrePrepareAndChain(Lweblogic.transaction.internal.ServerTransactionImpl;I)V(ServerSCInfo.java:115)
at weblogic.transaction.internal.ServerTransactionImpl.localPrePrepareAndChain()V(ServerTransactionImpl.java:1216)
at weblogic.transaction.internal.ServerTransactionImpl.globalPrePrepare()V(ServerTransactionImpl.java:1990)
at weblogic.transaction.internal.ServerTransactionImpl.internalCommit()V(Optimized Method)
at weblogic.transaction.internal.ServerTransactionImpl.commit()V(ServerTransactionImpl.java:246)
at weblogic.ejb20.internal.BaseEJBObject.postInvoke(Lweblogic.ejb20.internal.InvocationWrapper;Ljava.lang.Throwable;)V(Optimized Method)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl.setExtInstrumentLink(Ljava.lang.String;)V(StaticDataSession_muwkuo_EOImpl.java:4265)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl_WLSkel.internalInvoke1(ILweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;Ljava.lang.Object;)Lweblogic.rmi.spi.OutboundResponse;(Unknown Source)
at com.bubsw.bequest.sd.server.StaticDataSession_muwkuo_EOImpl_WLSkel.invoke(ILweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;Ljava.lang.Object;)Lweblogic.rmi.spi.OutboundResponse;(Unknown Source)
at weblogic.rmi.internal.activation.ActivatableServerRef.invoke(Lweblogic.rmi.extensions.server.RuntimeMethodDescriptor;Lweblogic.rmi.spi.InboundRequest;Lweblogic.rmi.spi.OutboundResponse;)V(ActivatableServerRef.java:90)
at weblogic.rmi.internal.BasicServerRef$1.run()Ljava.lang.Object;(BasicServerRef.java:420)
at weblogic.security.acl.internal.AuthenticatedSubject.doAs(Lweblogic.security.subject.AbstractSubject;Ljava.security.PrivilegedExceptionAction;)Ljava.lang.Object;(AuthenticatedSubject.java:363)
at weblogic.security.service.SecurityManager.runAs(Lweblogic.security.acl.internal.AuthenticatedSubject;Lweblogic.security.acl.internal.AuthenticatedSubject;Ljava.security.PrivilegedExceptionAction;)Ljava.lang.Object;(SecurityManager.java:147)
at weblogic.rmi.internal.BasicServerRef.handleRequest(Lweblogic.rmi.spi.InboundRequest;)V(BasicServerRef.java:415)
at weblogic.rmi.internal.BasicExecuteRequest.execute(Lweblogic.kernel.ExecuteThread;)V(BasicExecuteRequest.java:30)
at weblogic.kernel.ExecuteThread.execute(Lweblogic.kernel.ExecuteRequest;)V(Optimized Method)
at weblogic.kernel.ExecuteThread.run()V(ExecuteThread.java:178)
at java.lang.Thread.startThreadFromVM(Ljava.lang.Thread;)V(Unknown Source)
2006-12-07 16:00:21 DEBUG ConnectionManager:472 - registering flush end
2006-12-07 16:00:21 DEBUG ConnectionManager:398 - aggressively releasing JDBC connection
2006-12-07 16:00:21 DEBUG ConnectionManager:435 - releasing JDBC connection [ (open PreparedStatements: 1, globally: 1) (open ResultSets: 0, globally: 0)]
2006-12-07 16:00:21 DEBUG JDBCContext:185 - before transaction completion
2006-12-07 16:00:21 DEBUG SessionImpl:388 - before transaction completion
2006-12-07 16:00:21 DEBUG CacheSynchronization:82 - transaction after completion callback, status: 4
2006-12-07 16:00:21 DEBUG JDBCContext:199 - after transaction completion
2006-12-07 16:00:21 DEBUG SessionImpl:417 - after transaction completion
2006-12-07 16:00:21 DEBUG CacheSynchronization:89 - automatically closing session
2006-12-07 16:00:21 DEBUG SessionImpl:348 - automatically closing session
2006-12-07 16:00:21 DEBUG SessionImpl:268 - closing session
2006-12-07 16:00:21 DEBUG ConnectionManager:369 - connection already null in cleanup : no action

Kind regards,
Alex

_________________
--------------------------
Kind regards,
Alex


Top
 Profile  
 
 Post subject:
PostPosted: Thu Dec 07, 2006 4:16 pm 
Expert
Expert

Joined: Fri Aug 19, 2005 2:11 pm
Posts: 628
Location: Cincinnati
I don't mean to sound rude, but in your free time would you want to read through a thousand lines of a log file and gigantic sql statements to help someone out?

_________________
Chris

If you were at work doing this voluntarily, imagine what you'd want to see to answer a question.


Top
 Profile  
 
 Post subject:
PostPosted: Fri Dec 08, 2006 4:17 am 
Newbie

Joined: Thu Nov 30, 2006 10:44 am
Posts: 6
Location: Moscow
I personally think you are not rude - instead of giving any idea you spend your energy to write useless things.

If you see the first message you'll see the brief explanation of the problem at the top of the message. Nobody force you to read it whole. And the long log is due to the connection problem (had to press submit twice).
------------------------------------------------------------------------------
Anyway, I've found the nature of the problem - SET NOCOUNT ON statements in every our stored procedure. If I set the 'No count' property to true in MSSQL server Enterprise manager for whole server Hibernate throws the exception on regular basis because of no count is returned for updated/deleted/inserted rows.

The strange fact is I'm still unable to emulte the exception when 'No count' property is set to false:
1. I have set the connection pool to use only one JDBC connection.
2. Have written and run 5 EJB clients to call EJBs simulteniously that use the same pool Hibernate uses and execute SPs with SET NOCOUNT ON statements.
3. Still no exception
4. Exception is thrown during 0,5 .. 1 hour of work with application that runs under 2 users
5. Once StaleStateException is thrown I can't get rid of this untill Weblogic reboot.

Any idea how to emulate?

Kind regards,
Alex[/quote]

_________________
--------------------------
Kind regards,
Alex


Top
 Profile  
 
Display posts from previous:  Sort by  
Forum locked This topic is locked, you cannot edit posts or make further replies.  [ 5 posts ] 

All times are UTC - 5 hours [ DST ]


You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum

Search for:
© Copyright 2014, Red Hat Inc. All rights reserved. JBoss and Hibernate are registered trademarks and servicemarks of Red Hat, Inc.