Recovery creator service fails to start in JacORBGenericRecoveryCreatorUnitTest ------------------------------------------------------------------------------- Key: JBTM-1829 URL: https://issues.jboss.org/browse/JBTM-1829 Project: JBoss Transaction Manager Issue Type: Bug Security Level: Public(Everyone can see) Components: JTS Reporter: Gytis Trikleris Assignee: Tom Jenkinson Priority: Minor Fix For: 5.0.0.M4 http://172.17.131.2/view/Narayana+BlackTie/job/narayana/177/TESTS=MAIN,jd... {code} Emptying /home/hudson/workspace/narayana/TESTS/MAIN/jdk/jdk7.latest/label/linux/ArjunaJTS/jts/target/test-classes/ObjectStore 1 [main] WARN jacorb.config - jacorb.home unset! Will use '.' 1 [main] WARN jacorb.config - File ./jacorb.properties for configuration jacorb not found 2 [main] WARN jacorb.config - no properties found for configuration jacorb 5 [main] INFO jacorb.orb.print_version - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ JacORB V 2.3.1 (JBoss patch01), www.jacorb.org (C) The JacORB project 29-Jul-2009 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 40 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.HashMap Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCServiceInit initORBandOA INFO: ARJUNA022087: RecoveryServer using existing ORB 201 [main] WARN jacorb.config - jacorb.home unset! Will use '.' 201 [main] WARN jacorb.config - File ./jacorb.properties for configuration jacorb not found 202 [main] WARN jacorb.config - no properties found for configuration jacorb 202 [main] INFO jacorb.orb.singleton - created ORBSingleton 313 [main] INFO jacorb.poa - oid: 00 17 4C 48 03 47 41 4C 02 05 32 ..LH.GAL..2 object is activated 314 [main] INFO jacorb.poa - Using server ID (4426315968) for transient POA Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.jts.recovery.recoverycoordinators.GenericRecoveryCreator create WARN: ARJUNA022156: GenericRecoveryCreator: Missing params to create 318 [Thread-3] INFO jacorb.orb - ORB run 321 [POADestructor] INFO jacorb.poa - POA RcvCo-RecCoService_jaimerecovery_coordinator destroyed 322 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed 322 [main] INFO jacorb.orb - prepare ORB for shutdown... 322 [main] INFO jacorb.orb - ORB going down... 323 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited 323 [main] INFO jacorb.orb - ORB shutdown complete Emptying /home/hudson/workspace/narayana/TESTS/MAIN/jdk/jdk7.latest/label/linux/ArjunaJTS/jts/target/test-classes/ObjectStore Emptying /home/hudson/workspace/narayana/TESTS/MAIN/jdk/jdk7.latest/label/linux/ArjunaJTS/jts/target/test-classes/ObjectStore 328 [main] WARN jacorb.config - jacorb.home unset! Will use '.' 328 [main] WARN jacorb.config - File ./jacorb.properties for configuration jacorb not found 329 [main] WARN jacorb.config - no properties found for configuration jacorb 329 [main] INFO jacorb.orb.print_version - ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ JacORB V 2.3.1 (JBoss patch01), www.jacorb.org (C) The JacORB project 29-Jul-2009 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ 329 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.HashMap 330 [Thread-3] INFO jacorb.orb - ORB run, exit Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCServiceInit initORBandOA INFO: ARJUNA022087: RecoveryServer using existing ORB 333 [POADestructor] INFO jacorb.poa - POA RcvCo-RecCoService_jaimerecovery_coordinator destroyed 333 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed 333 [Thread-3] INFO jacorb.orb - prepare ORB for shutdown... 333 [Thread-3] INFO jacorb.orb - ORB going down... 333 [Thread-3] INFO jacorb.orb - ORB shutdown complete 334 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCServiceInit startRCservice WARN: ARJUNA022083: JacOrbRCServiceInit - Failed to start RC service org.omg.CORBA.OBJECT_NOT_EXIST: POA destroyed vmcid: 0x0 minor code: 0 completed: No at org.jacorb.poa.POA.checkDestructionApparent(Unknown Source) at org.jacorb.poa.POA.the_POAManager(Unknown Source) at com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCServiceInit.startRCservice(JacOrbRCServiceInit.java:225) at com.hp.mwtests.ts.jts.recovery.JacORBGenericRecoveryCreatorUnitTest.testSuccess(JacORBGenericRecoveryCreatorUnitTest.java:95) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) Jul 07, 2013 5:55:53 PM com.arjuna.orbportability.RootOA objectIsReady WARN: ARJUNA021004: objectIsReady - invalid POA: rootPOA Jul 07, 2013 5:55:53 PM com.arjuna.ats.arjuna.recovery.TransactionStatusManager addService INFO: ARJUNA012163: Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 48098 Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.arjuna.recovery.TransactionStatusManagerItem <init> INFO: ARJUNA012337: TransactionStatusManagerItem host: 127.0.0.1 port: 48098 Jul 07, 2013 5:55:53 PM com.arjuna.ats.arjuna.recovery.TransactionStatusManager start INFO: ARJUNA012170: TransactionStatusManager started on port 48098 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCManager makeRC WARN: ARJUNA022077: RCManager.makeRC did not make rcvco reference org.omg.CORBA.BAD_INV_ORDER: vmcid: 0x0 minor code: 4 completed: No at org.jacorb.orb.ORB.work_pending(Unknown Source) at org.jacorb.orb.ORB.perform_work(Unknown Source) at org.jacorb.orb.ORB.string_to_object(Unknown Source) at com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCManager.makeRC(JacOrbRCManager.java:102) at com.arjuna.ats.internal.jts.recovery.recoverycoordinators.GenericRecoveryCreator.create(GenericRecoveryCreator.java:121) at com.hp.mwtests.ts.jts.recovery.JacORBGenericRecoveryCreatorUnitTest.testSuccess(JacORBGenericRecoveryCreatorUnitTest.java:111) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) Emptying /home/hudson/workspace/narayana/TESTS/MAIN/jdk/jdk7.latest/label/linux/ArjunaJTS/jts/target/test-classes/ObjectStore Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.arjuna.objectstore.ShadowingStore remove_state WARN: ARJUNA012284: ShadowingStore::remove_state() - access problems on 0:ffffac118321:8b3f:51d99d99:3 and /Recovery/TransactionStatusManager Jul 07, 2013 5:55:53 PM com.arjuna.ats.internal.arjuna.objectstore.ShadowingStore remove_state WARN: ARJUNA012265: ShadowingStore::remove_state() - state 0:ffffac118321:8b3f:51d99d99:3 does not exist for type /Recovery/TransactionStatusManager {code}