But it doesn't happen, because I inicialize ORB before loading applicationContext.xml.
public class Main {
public static void main(String[] args) throws Exception {
/* initialize ORB */
ORB myORB = ORB.getInstance("ClientSide");
RootOA myOA = OA.getRootOA(myORB);
myORB.initORB(new String[] {}, null);
myOA.initOA();
/* loading Spring Context */
ApplicationContext mine = new ClassPathXmlApplicationContext(
new String[] { "applicationContext.xml" });
/* retrieve client bean */
ClientBean client = (ClientBean) mine.getBean("client");
/* invoke remote SLSB over IIOP */
client.executeTx();
/* stop orb */
myOA.destroy();
myORB.shutdown();
}
}
Anyway, I've refined my jar dependencies (I've deleted many jars). Definitely, I think that have all the JBoss Client Jars (C:\Java\jboss-5.0.0.CR1\client) in the classpath wasn't a good idea!!!
Now the exception stacktrace has changed, but it seems this is the good way.
This is my new stacktrace (with debugging messages)
CLIENT
[17 sep 15:58:01,050] DEBUG - ORB.initialise() - using ORB Implementation com.arjuna.orbportability.internal.orbspecific.jacorb.orb.implementations.jacorb_2_0
[17 sep 15:58:01,050] DEBUG - ORB::addAttribute (com.arjuna.orbportability.internal.common.LocalSetup@b89838)
[17 sep 15:58:01,051] DEBUG - OA.initialise() - using OA Implementation com.arjuna.orbportability.internal.orbspecific.jacorb.oa.implementations.jacorb_2_0
[17 sep 15:58:01,051] DEBUG - ORB::initORB (String[], Properties)
[17 sep 15:58:01,051] INFO - [com.arjuna.orbportability.internal.utils.InitLoader.loading] Loading PreInitLoader class -
com.arjuna.ats.internal.jts.context.ContextPropagationManager
[17 sep 15:58:01,098] DEBUG - ORB::parseProperties (String[], false)
[17 sep 15:58:01,223] DEBUG - InterpositionORBInitializerImpl ()
[17 sep 15:58:01,223] DEBUG - InterpositionORBInitializer.pre_init ()
[17 sep 15:58:01,239] DEBUG - InterpositionORBInitializerImpl - getting reference to ENCODING_CDR_ENCAPS codec
[17 sep 15:58:01,239] DEBUG - InterpositionORBInitializerImpl - registering ClientRequestInterceptor
[17 sep 15:58:01,239] DEBUG - InterpositionClientRequestInterceptorImpl ( 0 )
[17 sep 15:58:01,239] DEBUG - InterpositionORBInitializerImpl - registering ServerRequestInterceptor
[17 sep 15:58:01,239] DEBUG - InterpositionServerRequestInterceptorImpl ( 1 )
[17 sep 15:58:01,301] DEBUG - InterpositionORBInitializerImpl.post_init ()
[17 sep 15:58:01,317] DEBUG - ORB::parseProperties (String[], true)
[17 sep 15:58:01,317] DEBUG - Attribute com.arjuna.orbportability.internal.common.LocalSetup@b89838 initialising.
[17 sep 15:58:01,317] DEBUG - LocalSetup.initialise(String[] params)
[17 sep 15:58:01,317] INFO - [com.arjuna.orbportability.internal.utils.InitLoader.loading] Loading PostInitLoader class - com.arjuna.ats.internal.jts.recovery.RecoveryInit
[17 sep 15:58:01,332] DEBUG - [com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRecoveryInit_1]
[com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRecoveryInit_1] - JacOrb RecoveryCoordinator creator setup
[17 sep 15:58:01,332] DEBUG - Can't find resource for bundle java.util.PropertyResourceBundle, key com.arjuna.ats.internal.jts.recovery.RecoveryInit_1:
[key='com.arjuna.ats.internal.jts.recovery.RecoveryInit_1']
[17 sep 15:58:01,332] DEBUG - Can't find resource for bundle java.util.PropertyResourceBundle, key com.arjuna.ats.internal.jts.recovery.RecoveryInit_2:
[key='com.arjuna.ats.internal.jts.recovery.RecoveryInit_2']com.arjuna.orbportability.eventHandler_Recovery, com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter,
[17 sep 15:58:01,332] INFO - [com.arjuna.orbportability.internal.utils.InitLoader.loading] Loading PostInitLoader class - com.arjuna.ats.jts.utils.ORBSetup
[17 sep 15:58:01,332] DEBUG - The ORBSetup.invoke method has been invoked
[17 sep 15:58:01,332] DEBUG - The JTS ORB has been set to com.arjuna.orbportability.ORB@dd20f6
[17 sep 15:58:01,332] DEBUG - OA::initPOA (String[])
[17 sep 15:58:01,332] DEBUG - OA::parseProperties (String[], true)
[17 sep 15:58:01,488] DEBUG - OA::parseProperties (String[], false)
[17 sep 15:58:01,519] INFO - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296: display name
[org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296]; startup date [Wed Sep 17 15:58:01 CEST 2008]; root of context hierarchy
[17 sep 15:58:01,582] DEBUG - Class [org.apache.commons.collections.map.CaseInsensitiveMap] or one of its dependencies is not present: java.lang.ClassNotFoundException:
org.apache.commons.collections.map.CaseInsensitiveMap
[17 sep 15:58:01,644] INFO - Loading XML bean definitions from class path resource [applicationContext.xml]
[17 sep 15:58:01,644] DEBUG - Using JAXP provider [com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderFactoryImpl]
[17 sep 15:58:01,675] DEBUG - Loading schema mappings from [META-INF/spring.schemas]
[17 sep 15:58:01,675] DEBUG - Loaded schema mappings: {http://www.springframework.org/schema/lang/spring-lang.xsd=org/springframework/scripting/config/spring-lang-2.5.xsd,
http://www.springframework.org/schema/lang/spring-lang-2.5.xsd=org/springframework/scripting/config/spring-lang-2.5.xsd,
http://www.springframework.org/schema/context/spring-context-2.5.xsd=org/springframework/context/config/spring-context-2.5.xsd,
http://www.springframework.org/schema/context/spring-context.xsd=org/springframework/context/config/spring-context-2.5.xsd,
http://www.springframework.org/schema/util/spring-util-2.0.xsd=org/springframework/beans/factory/xml/spring-util-2.0.xsd,
http://www.springframework.org/schema/util/spring-util-2.5.xsd=org/springframework/beans/factory/xml/spring-util-2.5.xsd,
http://www.springframework.org/schema/tool/spring-tool-2.0.xsd=org/springframework/beans/factory/xml/spring-tool-2.0.xsd,
http://www.springframework.org/schema/tx/spring-tx-2.0.xsd=org/springframework/transaction/config/spring-tx-2.0.xsd,
http://www.springframework.org/schema/beans/spring-beans-2.0.xsd=org/springframework/beans/factory/xml/spring-beans-2.0.xsd,
http://www.springframework.org/schema/tool/spring-tool-2.5.xsd=org/springframework/beans/factory/xml/spring-tool-2.5.xsd,
http://www.springframework.org/schema/tx/spring-tx-2.5.xsd=org/springframework/transaction/config/spring-tx-2.5.xsd,
http://www.springframework.org/schema/beans/spring-beans-2.5.xsd=org/springframework/beans/factory/xml/spring-beans-2.5.xsd,
http://www.springframework.org/schema/beans/spring-beans.xsd=org/springframework/beans/factory/xml/spring-beans-2.5.xsd,
http://www.springframework.org/schema/jee/spring-jee.xsd=org/springframework/ejb/config/spring-jee-2.5.xsd,
http://www.springframework.org/schema/tool/spring-tool.xsd=org/springframework/beans/factory/xml/spring-tool-2.5.xsd,
http://www.springframework.org/schema/tx/spring-tx.xsd=org/springframework/transaction/config/spring-tx-2.5.xsd,
http://www.springframework.org/schema/jee/spring-jee-2.0.xsd=org/springframework/ejb/config/spring-jee-2.0.xsd,
http://www.springframework.org/schema/jee/spring-jee-2.5.xsd=org/springframework/ejb/config/spring-jee-2.5.xsd,
http://www.springframework.org/schema/lang/spring-lang-2.0.xsd=org/springframework/scripting/config/spring-lang-2.0.xsd,
http://www.springframework.org/schema/util/spring-util.xsd=org/springframework/beans/factory/xml/spring-util-2.5.xsd}
[17 sep 15:58:01,675] DEBUG - Found XML schema [http://www.springframework.org/schema/beans/spring-beans-2.0.xsd] in classpath:
org/springframework/beans/factory/xml/spring-beans-2.0.xsd
[17 sep 15:58:01,738] DEBUG - Loading bean definitions
[17 sep 15:58:01,769] DEBUG - Loaded 4 bean definitions from location pattern [applicationContext.xml]
[17 sep 15:58:01,769] INFO - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296]:
org.springframework.beans.factory.support.DefaultListableBeanFactory@d42d08
[17 sep 15:58:01,769] DEBUG - 4 beans defined in org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296: display name
[org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296]; startup date [Wed Sep 17 15:58:01 CEST 2008]; root of context hierarchy
[17 sep 15:58:01,800] DEBUG - Unable to locate MessageSource with name 'messageSource': using default [org.springframework.context.support.DelegatingMessageSource@f47bf5]
[17 sep 15:58:01,800] DEBUG - Unable to locate ApplicationEventMulticaster with name 'applicationEventMulticaster': using default
[org.springframework.context.event.SimpleApplicationEventMulticaster@18825b3]
[17 sep 15:58:01,800] INFO - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@d42d08: defining beans
[jbossTransactionManager,jbossUserTransaction,transactionManager,client]; root of factory hierarchy
[17 sep 15:58:01,816] DEBUG - Creating shared instance of singleton bean 'jbossTransactionManager'
[17 sep 15:58:01,816] DEBUG - Creating instance of bean 'jbossTransactionManager'
[17 sep 15:58:01,863] DEBUG - Eagerly caching bean 'jbossTransactionManager' to allow for resolving potential circular references
[17 sep 15:58:01,863] DEBUG - Finished creating instance of bean 'jbossTransactionManager'
[17 sep 15:58:01,863] DEBUG - Creating shared instance of singleton bean 'jbossUserTransaction'
[17 sep 15:58:01,863] DEBUG - Creating instance of bean 'jbossUserTransaction'
[17 sep 15:58:01,863] DEBUG - Eagerly caching bean 'jbossUserTransaction' to allow for resolving potential circular references
[17 sep 15:58:01,863] DEBUG - Finished creating instance of bean 'jbossUserTransaction'
[17 sep 15:58:01,863] DEBUG - Creating shared instance of singleton bean 'transactionManager'
[17 sep 15:58:01,863] DEBUG - Creating instance of bean 'transactionManager'
[17 sep 15:58:01,878] DEBUG - Eagerly caching bean 'transactionManager' to allow for resolving potential circular references
[17 sep 15:58:01,878] DEBUG - Returning cached instance of singleton bean 'jbossTransactionManager'
[17 sep 15:58:01,894] DEBUG - Returning cached instance of singleton bean 'jbossUserTransaction'
[17 sep 15:58:01,894] DEBUG - Invoking afterPropertiesSet() on bean with name 'transactionManager'
[17 sep 15:58:01,894] INFO - Using JTA UserTransaction: com.arjuna.ats.internal.jta.transaction.jts.UserTransactionImple@e0cc23
[17 sep 15:58:01,894] INFO - Using JTA TransactionManager: com.arjuna.ats.internal.jta.transaction.jts.TransactionManagerImple@7a1576
[17 sep 15:58:01,909] DEBUG - Finished creating instance of bean 'transactionManager'
[17 sep 15:58:01,909] DEBUG - Creating shared instance of singleton bean 'client'
[17 sep 15:58:01,909] DEBUG - Creating instance of bean 'client'
[17 sep 15:58:01,909] DEBUG - Eagerly caching bean 'client' to allow for resolving potential circular references
[17 sep 15:58:01,909] DEBUG - Returning cached instance of singleton bean 'transactionManager'
[17 sep 15:58:01,909] DEBUG - Finished creating instance of bean 'client'
[17 sep 15:58:01,909] DEBUG - Publishing event in context [org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296]:
org.springframework.context.event.ContextRefreshedEvent[source=org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296: display name
[org.springframework.context.support.ClassPathXmlApplicationContext@1f6f296]; startup date [Wed Sep 17 15:58:01 CEST 2008]; root of context hierarchy]
[17 sep 15:58:01,909] DEBUG - Returning cached instance of singleton bean 'client'
*** Using 'EJBHomeLocatorJNDIInitialContext.properties' file for JNDI InitialContext in EJBHomeLocator ***
[17 sep 15:58:01,925] DEBUG - InterpositionORBInitializerImpl ()
[17 sep 15:58:01,925] DEBUG - InterpositionORBInitializer.pre_init ()
[17 sep 15:58:01,925] DEBUG - InterpositionORBInitializerImpl - getting reference to ENCODING_CDR_ENCAPS codec
[17 sep 15:58:01,925] DEBUG - InterpositionORBInitializerImpl - registering ClientRequestInterceptor
[17 sep 15:58:01,925] DEBUG - InterpositionClientRequestInterceptorImpl ( 0 )
[17 sep 15:58:01,925] DEBUG - InterpositionORBInitializerImpl - registering ServerRequestInterceptor
[17 sep 15:58:01,925] DEBUG - InterpositionServerRequestInterceptorImpl ( 1 )
[17 sep 15:58:01,925] DEBUG - InterpositionORBInitializerImpl.post_init ()
[17 sep 15:58:02,034] DEBUG - InterpositionClientRequestInterceptorImpl::send_request ( _is_a )
[17 sep 15:58:02,051] DEBUG - InterpositionClientRequestInterceptorImpl::receive_reply ( _is_a )
[17 sep 15:58:02,066] DEBUG - InterpositionClientRequestInterceptorImpl::send_request ( resolve )
[17 sep 15:58:02,066] DEBUG - Shutdown.Shutdown (ShutdownOTS)
[17 sep 15:58:02,066] DEBUG - OA::addPreShutdown (com.arjuna.ats.internal.jts.lifecycle.ShutdownOTS@1e232b5)
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - Mutex::lock()
[17 sep 15:58:02,114] DEBUG - Mutex::unlock()
[17 sep 15:58:02,114] DEBUG - TransactionFactoryImple::TransactionFactoryImple ()
[17 sep 15:58:02,114] DEBUG - RootOA::objectIsReady (Servant)
[jacorb.poa] INFO : oid:
00 15 3D 3C 2E 2E 4C 2B 4D 43 ..=<..L+MC
object is activated
[17 sep 15:58:02,130] DEBUG - [com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter_1] [com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter_1] -
RecoveryContactWriter() created
[jacorb.poa] INFO : Using server ID (3206960384) for transient POA
[17 sep 15:58:02,145] DEBUG - [com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter_2] [com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter_2] -
RecoveryContactWriter.connected( "
IOR:000000000000002B49444C3A61726A756E612E636F6D2F41726A756E614F54532F41726A756E61466163746F72793A312E300000000000010000000000000070000102000000000F3139322E3136382E31302E3139390000FC25000000000015333230363936303338342F00153D3C2E2E4C2B4D43000000000000020000000000000008000000004A414300000000010000001C00000000000100010000000105010001000101090000000105010001
")
[17 sep 15:58:02,145] DEBUG - [com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter_1] [com.arjuna.ats.internal.jts.recovery.contact.RecoveryContactWriter_1] -
RecoveryContactWriter() created
[17 sep 15:58:02,145] DEBUG - FactoryContactItem(factory)
[17 sep 15:58:02,161] DEBUG - OutputObjectState::OutputObjectState()
[17 sep 15:58:02,192] DEBUG - ShadowingStore.ShadowingStore( 14 )
[17 sep 15:58:02,192] DEBUG - ShadowNoFileLockStore.ShadowNoFileLockStore( 14 )
[17 sep 15:58:02,192] DEBUG - HashedStore.HashedStore( 14 )
[17 sep 15:58:02,192] DEBUG - HashedStore.HashedActionStore()
[17 sep 15:58:02,192] DEBUG - FileSystemStore.setupStore()
[17 sep 15:58:02,192] DEBUG - FileSystemStore.createHierarchy(PutObjectStoreDirHere\HashedActionStore\defaultStore\)
[17 sep 15:58:02,208] INFO - [com.arjuna.ats.arjuna.recovery.TransactionStatusManager_1] - Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 64554
[17 sep 15:58:02,208] INFO - TransactionStatusManagerItem - host: 192.168.10.199 port: 64554
[17 sep 15:58:02,208] DEBUG - OutputObjectState::OutputObjectState()
[17 sep 15:58:02,208] DEBUG - ShadowingStore.ShadowingStore( 14 )
[17 sep 15:58:02,208] DEBUG - ShadowNoFileLockStore.ShadowNoFileLockStore( 14 )
[17 sep 15:58:02,208] DEBUG - HashedStore.HashedStore( 14 )
[17 sep 15:58:02,208] DEBUG - HashedStore.HashedActionStore()
[17 sep 15:58:02,208] DEBUG - FileSystemStore.setupStore()
[17 sep 15:58:02,208] DEBUG - FileSystemStore.createHierarchy(PutObjectStoreDirHere\HashedActionStore\defaultStore\)
[17 sep 15:58:02,208] DEBUG - FileSystemStore.write_committed(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager)
[17 sep 15:58:02,208] DEBUG - ShadowingStore.write_state(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,208] DEBUG - HashedStore.genPathName(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,208] DEBUG - ShadowingStore.openAndLock(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\TransactionStatusManager\#0#\-3f57f539_fc29_48d10cea_0,
FileLock.F_WRLCK, true)
[17 sep 15:58:02,208] DEBUG - FileSystemStore.createHierarchy(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\TransactionStatusManager\#0#\-3f57f539_fc29_48d10cea_0)
[17 sep 15:58:02,317] DEBUG - ShadowingStore.closeAndUnlock(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\TransactionStatusManager\#0#\-3f57f539_fc29_48d10cea_0,
null, java.io.FileOutputStream@992bae)
[17 sep 15:58:02,317] INFO - [com.arjuna.ats.arjuna.recovery.TransactionStatusManager_3] - TransactionStatusManager started on port 64554 and host 192.168.10.199 with service
com.arjuna.ats.arjuna.recovery.ActionStatusService
[17 sep 15:58:02,317] DEBUG - ShadowingStore.ShadowingStore( 14 )
[17 sep 15:58:02,317] DEBUG - ShadowNoFileLockStore.ShadowNoFileLockStore( 14 )
[17 sep 15:58:02,317] DEBUG - HashedStore.HashedStore( 14 )
[17 sep 15:58:02,317] DEBUG - HashedStore.HashedActionStore()
[17 sep 15:58:02,317] DEBUG - FileSystemStore.setupStore()
[17 sep 15:58:02,317] DEBUG - FileSystemStore.createHierarchy(PutObjectStoreDirHere\HashedActionStore\defaultStore\)
[17 sep 15:58:02,317] DEBUG - FileSystemStore.write_committed(-3f57f539:fc29:48d10cea:0, /Recovery/FactoryContact)
[17 sep 15:58:02,317] DEBUG - ShadowingStore.write_state(-3f57f539:fc29:48d10cea:0, /Recovery/FactoryContact, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,317] DEBUG - HashedStore.genPathName(-3f57f539:fc29:48d10cea:0, /Recovery/FactoryContact, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,317] DEBUG - ShadowingStore.openAndLock(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\FactoryContact\#0#\-3f57f539_fc29_48d10cea_0,
FileLock.F_WRLCK, true)
[17 sep 15:58:02,317] DEBUG - FileSystemStore.createHierarchy(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\FactoryContact\#0#\-3f57f539_fc29_48d10cea_0)
[17 sep 15:58:02,364] DEBUG - ShadowingStore.closeAndUnlock(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\FactoryContact\#0#\-3f57f539_fc29_48d10cea_0, null,
java.io.FileOutputStream@1329642)
[17 sep 15:58:02,379] DEBUG - CurrentImple::CurrentImple ()
[17 sep 15:58:02,379] DEBUG - ContextManager::ContextManager ()
[17 sep 15:58:02,379] DEBUG - InterpositionClientRequestInterceptorImpl::receive_reply ( resolve )
[17 sep 15:58:02,395] DEBUG - InterpositionClientRequestInterceptorImpl::send_request ( _is_a )
[17 sep 15:58:02,395] DEBUG - InterpositionClientRequestInterceptorImpl::receive_reply ( _is_a )
[17 sep 15:58:02,473] DEBUG - Using transaction object [org.springframework.transaction.jta.JtaTransactionObject@17ba38f]
[17 sep 15:58:02,473] DEBUG - BaseTransaction.getStatus
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - CurrentImple.getControlWrapper ()
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - Creating new transaction with name [EchoClient]: PROPAGATION_REQUIRES_NEW,ISOLATION_DEFAULT
[17 sep 15:58:02,489] DEBUG - BaseTransaction.begin
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - AtomicTransaction::AtomicTransaction ()
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - CurrentImple::get_timeout - returning 0
[17 sep 15:58:02,489] DEBUG - AtomicTransaction::get_timeout returning 0
[17 sep 15:58:02,489] DEBUG - TransactionImple.getStatus
[17 sep 15:58:02,489] DEBUG - AtomicTransaction::get_status called for null returning CosTransactions::StatusNoTransaction
[17 sep 15:58:02,489] DEBUG - AtomicTransaction::begin ()
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - ContextManager::current ()
[17 sep 15:58:02,489] DEBUG - CurrentImple::begin - creating new top-level transaction.
[17 sep 15:58:02,489] DEBUG - CurrentImple::get_timeout - returning 0
[17 sep 15:58:02,489] DEBUG - TransactionFactoryImple::createLocal ( 0 )
[17 sep 15:58:02,504] DEBUG - ControlImple::ControlImple ( Control parentCon, 0:0:0:0 )
[17 sep 15:58:02,520] DEBUG - StateManager::StateManager( 2 )
[17 sep 15:58:02,520] DEBUG - BasicAction::BasicAction()
[17 sep 15:58:02,520] DEBUG - ArjunaTransactionImple Begin for < -3f57f539:fc29:48d10cea:3 , 0:0:0:0 >
[17 sep 15:58:02,520] DEBUG - BasicAction::Begin() for action-id -3f57f539:fc29:48d10cea:3
[17 sep 15:58:02,520] DEBUG - BasicAction::actionInitialise() for action-id -3f57f539:fc29:48d10cea:3
[17 sep 15:58:02,520] DEBUG - ActionHierarchy::ActionHierarchy(5)
[17 sep 15:58:02,520] DEBUG - ActionHierarchy::add(-3f57f539:fc29:48d10cea:3, 1)
[17 sep 15:58:02,535] DEBUG - ControlImple::addControl ()
[17 sep 15:58:02,535] DEBUG - ContextManager::pushAction ()
[17 sep 15:58:02,535] DEBUG - [com.arjuna.ats.arjuna.coordinator.BasicAction_6] - BasicAction::addChildThread () action -3f57f539:fc29:48d10cea:3 adding Thread[main,5,main]
[17 sep 15:58:02,535] DEBUG - [com.arjuna.ats.arjuna.coordinator.BasicAction_7] - BasicAction::addChildThread () action -3f57f539:fc29:48d10cea:3 adding Thread[main,5,main] result
= true
[17 sep 15:58:02,535] DEBUG - CurrentImple.getControlWrapper ()
[17 sep 15:58:02,535] DEBUG - ContextManager::current ()
[17 sep 15:58:02,535] DEBUG - ContextManager::current ()
[17 sep 15:58:02,535] DEBUG - ArjunaTransactionImple::get_status for -3f57f539:fc29:48d10cea:3 returning CosTransactions::StatusActive
[17 sep 15:58:02,535] DEBUG - CurrentImple::get_status - returning CosTransactions::StatusActive
[17 sep 15:58:02,535] DEBUG - AtomicTransaction::begin create -3f57f539:fc29:48d10cea:3 : com.arjuna.ats.internal.jts.ControlWrapper@778605f9
[17 sep 15:58:02,535] DEBUG - ContextManager::current ()
[17 sep 15:58:02,535] DEBUG - ContextManager::current ()
[17 sep 15:58:02,535] DEBUG - ContextManager::current ()
[17 sep 15:58:02,535] DEBUG - ContextManager::current ()
[17 sep 15:58:02,535] DEBUG - Initializing transaction synchronization
[17 sep 15:58:02,535] DEBUG - InterpositionClientRequestInterceptorImpl::send_request ( create )
[17 sep 15:58:02,535] DEBUG - ControlImple::createTransactionHandle ()
[jacorb.poa] INFO : oid:
01 15 3D 3C 2E 2E 4C 2B 4D 43 ..=<..L+MC
object is activated
[17 sep 15:58:02,535] DEBUG - RootOA::objectIsReady (Servant)
[17 sep 15:58:02,551] DEBUG - ArjunaTransactionImple::get_txcontext - called for -3f57f539:fc29:48d10cea:3
[17 sep 15:58:02,551] DEBUG - ArjunaTransactionImple::propagationContext for -3f57f539:fc29:48d10cea:3
[jacorb.poa] INFO : oid:
02 15 3D 3C 2E 2E 4C 2B 4D 43 ..=<..L+MC
object is activated
[17 sep 15:58:02,551] DEBUG - RootOA::objectIsReady (Servant)
[17 sep 15:58:02,567] DEBUG - InterpositionClientRequestInterceptorImpl.packPropagationContext ( org.omg.CosTransactions.PropagationContext@1ae90c )
[17 sep 15:58:02,598] DEBUG - InterpositionServerRequestInterceptorImpl::receive_request_service_contexts ( register_resource )
[17 sep 15:58:02,598] DEBUG - InterpositionServerRequestInterceptorImpl.receive_request ( register_resource )
[17 sep 15:58:02,598] DEBUG - ArjunaTransactionImple::register_resource (
IOR:000000000000002949444C3A6F6D672E6F72672F436F735472616E73616374696F6E732F5265736F757263653A312E30000000000000000200000000000000FC000102000000000A3132372E302E302E31000DC800000017393136333031383631382F0207153D390A1A1B3902214900000000050000000000000008000000004A414300000000010000001C00000000000100010000000105010001000101090000000105010001000000210000007400000000000000010000000000000024000000220000007E00000000000000010000000F3139322E3136382E31302E31393900000DC9004000000000000000080606678102010101000000170401000806066781020101010000000764656661756C7400000000000000000000000000000000000000002000000004000000000000001F0000000400000003000000010000002000000000000000020000002000000004000000000000001F0000000400000003
) - called for -3f57f539:fc29:48d10cea:3
[17 sep 15:58:02,598] DEBUG - ArjunaTransactionImple -3f57f539:fc29:48d10cea:3 ::register_resource:
[17 sep 15:58:02,598] DEBUG - RecoveryCreator::createRecoveryCoordinator
[17 sep 15:58:02,598] DEBUG - GenericRecoveryCreator.create()
[17 sep 15:58:02,613] DEBUG - ShadowingStore.ShadowingStore( 14 )
[17 sep 15:58:02,613] DEBUG - ShadowNoFileLockStore.ShadowNoFileLockStore( 14 )
[17 sep 15:58:02,613] DEBUG - HashedStore.HashedStore( 14 )
[17 sep 15:58:02,613] DEBUG - HashedStore.HashedActionStore()
[17 sep 15:58:02,613] DEBUG - FileSystemStore.setupStore()
[17 sep 15:58:02,613] DEBUG - FileSystemStore.createHierarchy(PutObjectStoreDirHere\HashedActionStore\defaultStore\)
[17 sep 15:58:02,613] DEBUG - FileSystemStore.read_committed(52e38d0c:c91:4140398c:0, /RecoveryCoordinator)
[17 sep 15:58:02,613] DEBUG - ShadowingStore.read_state(52e38d0c:c91:4140398c:0, /RecoveryCoordinator, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,613] DEBUG - HashedStore.genPathName(52e38d0c:c91:4140398c:0, /RecoveryCoordinator, ObjectStore.OS_SHADOW)
[17 sep 15:58:02,613] DEBUG - HashedStore.genPathName(52e38d0c:c91:4140398c:0, /RecoveryCoordinator, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,613] DEBUG - [com.arjuna.ats.internal.arjuna.objectstore.ShadowingStore_22] - ShadowingStore.currentState(52e38d0c:c91:4140398c:0, /RecoveryCoordinator) -
returning ObjectStore.OS_UNKNOWN
[17 sep 15:58:02,613] WARN - [com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCManager_4]
[com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCManager_4] - RCManager could not find file in object store during setup.
[17 sep 15:58:02,613] DEBUG - [com.arjuna.ats.internal.jts.recovery.recoverycoordinators.RecoveryCoordinatorId_1]
[com.arjuna.ats.internal.jts.recovery.recoverycoordinators.RecoveryCoordinatorId_1] - RecoveryCoordinatorId: created RCkey
-3f57f539:fc29:48d10cea:5*-3f57f539:fc29:48d10cea:3*-3f57f539:fc29:48d10cea:0*false
[17 sep 15:58:02,613] WARN - [com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCManager_3]
[com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCManager_3] - RCManager could not find file in object store.
[17 sep 15:58:02,613] WARN - [com.arjuna.ats.internal.jts.orbspecific.coordinator.rccreate] Creation of RecoveryCoordinator for -3f57f539:fc29:48d10cea:3 threw:
org.omg.CORBA.BAD_OPERATION: RecoveryCoordinator [com.arjuna.ats.internal.jts.orbspecific.coordinator.rcnotcreated] not created! vmcid: 0x0 minor code: 0 completed: No
[17 sep 15:58:02,613] DEBUG - ArjunaTransactionImple::rollback_only - called for -3f57f539:fc29:48d10cea:3
[17 sep 15:58:02,613] DEBUG - BasicAction::preventCommit( ArjunaTransactionImple < -3f57f539:fc29:48d10cea:3 >)
[jacorb.poa.controlle] INFO : rid: 0 opname: register_resource invocation: system exception was thrown.
org.omg.CORBA.BAD_OPERATION: RecoveryCoordinator [com.arjuna.ats.internal.jts.orbspecific.coordinator.rcnotcreated] not created! vmcid: 0x0 minor code: 0 completed: No
at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.register_resource(ArjunaTransactionImple.java:872)
at com.arjuna.ArjunaOTS.ArjunaTransactionPOATie.register_resource(ArjunaTransactionPOATie.java:63)
at com.arjuna.ArjunaOTS.ArjunaTransactionPOA._invoke(ArjunaTransactionPOA.java:78)
at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
at org.jacorb.poa.RequestProcessor.process(Unknown Source)
at org.jacorb.poa.RequestProcessor.run(Unknown Source)
[17 sep 15:58:02,613] DEBUG - InterpositionServerRequestInterceptorImpl::send_exception ( register_resource )
[17 sep 15:58:02,613] DEBUG - InterpositionServerRequestInterceptorImpl.suspendContext ( register_resource )
[17 sep 15:58:02,629] DEBUG - InterpositionClientRequestInterceptorImpl::receive_exception ( create )
Exception in thread "main" java.rmi.RemoteException: CORBA UNKNOWN 0 No; nested exception is:
org.omg.CORBA.UNKNOWN: org.omg.CORBA.portable.UnknownException vmcid: 0x0 minor code: 0 completed: No
at com.sun.corba.se.impl.javax.rmi.CORBA.Util.mapSystemException(Util.java:282)
at javax.rmi.CORBA.Util.mapSystemException(Util.java:67)
at org.jboss.proxy.ejb.DynamicIIOPStub.invoke(DynamicIIOPStub.java:149)
at es.tic.echo._EchoServiceHome_Stub.create(Unknown Source)
at es.tic.springclient.ClientBean.executeTx(ClientBean.java:49)
at es.tic.springclient.Main.main(Main.java:28)
Caused by: org.omg.CORBA.UNKNOWN: org.omg.CORBA.portable.UnknownException vmcid: 0x0 minor code: 0 completed: No
at org.jacorb.orb.SystemExceptionHelper.read(Unknown Source)
at org.jacorb.orb.ReplyReceiver.getReply(Unknown Source)
at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
at org.jacorb.orb.Delegate.invoke(Unknown Source)
at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:457)
at org.jboss.proxy.ejb.DynamicIIOPStub.invoke(DynamicIIOPStub.java:124)
... 3 more
Caused by: java.lang.NoSuchMethodException: org.omg.CORBA.portable.UnknownException.<init>(java.lang.String, int, org.omg.CORBA.CompletionStatus)
at java.lang.Class.getConstructor0(Class.java:2706)
at java.lang.Class.getConstructor(Class.java:1657)
... 9 more
[17 sep 15:58:02,645] DEBUG - FileSystemStore.remove_committed(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager)
[17 sep 15:58:02,645] DEBUG - ShadowingStore.remove_state(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,645] DEBUG - HashedStore.genPathName(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager, ObjectStore.OS_SHADOW)
[17 sep 15:58:02,645] DEBUG - HashedStore.genPathName(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,645] DEBUG - [com.arjuna.ats.internal.arjuna.objectstore.ShadowingStore_22] - ShadowingStore.currentState(-3f57f539:fc29:48d10cea:0,
/Recovery/TransactionStatusManager) - returning ObjectStore.OS_COMMITTED
[17 sep 15:58:02,645] DEBUG - HashedStore.genPathName(-3f57f539:fc29:48d10cea:0, /Recovery/TransactionStatusManager, ObjectStore.OS_ORIGINAL)
[17 sep 15:58:02,645] DEBUG - ShadowingStore.openAndLock(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\TransactionStatusManager\#0#\-3f57f539_fc29_48d10cea_0,
FileLock.F_WRLCK, false)
[17 sep 15:58:02,645] DEBUG - ShadowingStore.closeAndUnlock(PutObjectStoreDirHere\HashedActionStore\defaultStore\Recovery\TransactionStatusManager\#0#\-3f57f539_fc29_48d10cea_0,
null, null)
SERVER
15:58:02,034 INFO [iiop] Opened new server-side TCP/IP transport to 127.0.0.1:64552
15:58:02,567 INFO [poa] oid:
02 06 15 3D 39 0A 1A 1B 39 02 21 49 ...=9...9.!I
object is activated
15:58:02,567 INFO [poa] oid:
02 07 15 3D 39 0A 1A 1B 39 02 21 49 ...=9...9.!I
object is activated
15:58:02,567 INFO [giop] ClientConnectionManager: created new ClientGIOPConnection to 192.168.10.199:64549 (13ca484)
15:58:02,567 INFO [iiop] Connected to 192.168.10.199:64549 from local port 64556
15:58:02,629 WARN [loggerI18N] [com.arjuna.ats.internal.jts.orbspecific.interposition.resources.arjuna.generror] ServerTopLevelAction.registerResource caught exception: org.omg.CORBA.BAD_OPERATION: Server-side Exception: RecoveryCoordinator [com.arjuna.ats.internal.jts.orbspecific.coordinator.rcnotcreated] not created! vmcid: 0x0 minor code: 0 completed: No
15:58:02,629 ERROR [STDERR] org.omg.CORBA.BAD_OPERATION: Server-side Exception: RecoveryCoordinator [com.arjuna.ats.internal.jts.orbspecific.coordinator.rcnotcreated] not created! vmcid: 0x0 minor code: 0 completed: No
15:58:02,629 ERROR [STDERR] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
15:58:02,629 ERROR [STDERR] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
15:58:02,629 ERROR [STDERR] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
15:58:02,629 ERROR [STDERR] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
15:58:02,629 ERROR [STDERR] at org.jacorb.orb.SystemExceptionHelper.read(Unknown Source)
15:58:02,629 ERROR [STDERR] at org.jacorb.orb.ReplyReceiver.getReply(Unknown Source)
15:58:02,629 ERROR [STDERR] at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
15:58:02,629 ERROR [STDERR] at org.jacorb.orb.Delegate.invoke(Unknown Source)
15:58:02,629 ERROR [STDERR] at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:457)
15:58:02,629 ERROR [STDERR] at org.omg.CosTransactions._CoordinatorStub.register_resource(Unknown Source)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.orbspecific.interposition.resources.arjuna.ServerTopLevelAction.registerResource(ServerTopLevelAction.java:673)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.orbspecific.interposition.resources.arjuna.ServerTopLevelAction.<init>(ServerTopLevelAction.java:130)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.interposition.resources.arjuna.Interposition.createHierarchy(Interposition.java:174)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.interposition.resources.arjuna.Interposition.setupHierarchy(Interposition.java:115)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.interposition.resources.arjuna.Interposition.create(Interposition.java:74)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.interposition.resources.arjuna.InterpositionCreator.recreateLocal(InterpositionCreator.java:48)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.interposition.FactoryElement.recreateLocal(FactoryList.java:72)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.interposition.FactoryList.recreateLocal(FactoryList.java:119)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.orbspecific.TransactionFactoryImple.recreateLocal(TransactionFactoryImple.java:246)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.context.ContextManager.createHierarchy(ContextManager.java:790)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.context.ContextManager.currentPIContext(ContextManager.java:682)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.context.ContextManager.current(ContextManager.java:212)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.context.ContextManager.associate(ContextManager.java:363)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jts.OTSImpleManager.current(OTSImpleManager.java:91)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jta.transaction.jts.TransactionImple.getTransaction(TransactionImple.java:1220)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jta.transaction.jts.TransactionManagerImple.getTransaction(TransactionManagerImple.java:74)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.getTransaction(BaseTransactionManagerDelegate.java:95)
15:58:02,629 ERROR [STDERR] at com.arjuna.ats.internal.jbossatx.jts.InboundTransactionCurrentImple.getCurrentTransaction(InboundTransactionCurrentImple.java:65)
15:58:02,629 ERROR [STDERR] at org.jboss.proxy.ejb.EjbHomeCorbaServant._invoke(EjbHomeCorbaServant.java:250)
15:58:02,629 ERROR [STDERR] at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
15:58:02,629 ERROR [STDERR] at org.jacorb.poa.RequestProcessor.process(Unknown Source)
15:58:02,629 ERROR [STDERR] at org.jacorb.poa.RequestProcessor.run(Unknown Source)
15:58:02,629 WARN [loggerI18N] [com.arjuna.ats.internal.jts.orbspecific.interposition.resources.arjuna.ipfailed] ServerTopLevelAction - could not register interposed hierarchy!
15:58:02,629 INFO [giop] ClientConnectionManager: found ClientGIOPConnection to 127.0.0.1:3528 (72fcf6)
15:58:02,629 WARN [loggerI18N] [com.arjuna.ats.internal.jts.interposition.resources.arjuna.ipfail] Interposition.removeHeirarchy - could not find -3f57f539:fc29:48d10cea:3 to remove.
15:58:02,629 INFO [poa] oid:
02 06 15 3D 39 0A 1A 1B 39 02 21 49 ...=9...9.!I
object is deactivated
15:58:02,629 WARN [loggerI18N] [com.arjuna.ats.internal.jts.interposition.resources.arjuna.ipfail] Interposition.removeHeirarchy - could not find -3f57f539:fc29:48d10cea:3 to remove.
15:58:02,629 WARN [loggerI18N] [com.arjuna.ats.internal.jts.context.genfail] ContextManager.createHierarchy caught exception: org.omg.CORBA.TRANSACTION_ROLLEDBACK: vmcid: 0x0 minor code: 0 completed: No
15:58:02,629 ERROR [InboundTransactionCurrentImple] InboundTransactionCurrentImple.getCurrentTransaction() failed
javax.transaction.SystemException
at com.arjuna.ats.internal.jta.transaction.jts.TransactionManagerImple.getTransaction(TransactionManagerImple.java:82)
at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.getTransaction(BaseTransactionManagerDelegate.java:95)
at com.arjuna.ats.internal.jbossatx.jts.InboundTransactionCurrentImple.getCurrentTransaction(InboundTransactionCurrentImple.java:65)
at org.jboss.proxy.ejb.EjbHomeCorbaServant._invoke(EjbHomeCorbaServant.java:250)
at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
at org.jacorb.poa.RequestProcessor.process(Unknown Source)
at org.jacorb.poa.RequestProcessor.run(Unknown Source)
15:58:02,629 INFO [controller] rid: 6 opname: create invocation: system exception was thrown.
org.omg.CORBA.portable.UnknownException: vmcid: 0x0 minor code: 0 completed: Maybe
at org.jboss.iiop.rmi.marshal.strategy.SkeletonStrategy.writeException(SkeletonStrategy.java:176)
at org.jboss.proxy.ejb.EjbHomeCorbaServant._invoke(EjbHomeCorbaServant.java:322)
at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
at org.jacorb.poa.RequestProcessor.process(Unknown Source)
at org.jacorb.poa.RequestProcessor.run(Unknown Source)
15:58:02,629 INFO [poa] oid:
02 07 15 3D 39 0A 1A 1B 39 02 21 49 ...=9...9.!I
object is deactivated
15:58:03,159 INFO [iiop] Client-side TCP transport to 192.168.10.199:64549 closed.
15:58:03,159 INFO [iiop] Closed server-side transport to 127.0.0.1:64552
I'm lost in the stacktrace!!!