[jbossts-issues] [JBoss JIRA] (JBTM-1829) Recovery creator service fails to start in JacORBGenericRecoveryCreatorUnitTest

Tom Jenkinson (JIRA) jira-events at lists.jboss.org
Mon Jul 8 10:13:20 EDT 2013


     [ https://issues.jboss.org/browse/JBTM-1829?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Tom Jenkinson updated JBTM-1829:
--------------------------------

              Status: Pull Request Sent  (was: Open)
    Git Pull Request: https://github.com/jbosstm/narayana/pull/370


It looks likely that this is an underlying threading difference between dev boxes and CI nodes. I can't replicate it here but suspect it is due to the fact that the ORB::shutdown is currently waitForCompletion=false and so the next test may start with an orb that is shutting down. Will raise an FR to make that parameterised and merge a fix to the tests to trigger a second call to the underlying orb to force a shutdown
                
> 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,jdk=jdk7.latest,label=linux/
> http://172.17.131.2/view/Narayana+BlackTie/job/narayana-codeCoverage/50
> {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}

--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira


More information about the jbossts-issues mailing list