[JBoss JIRA] (JBTM-1979) REST-AT integration API recovery tests failed on IPv6
by Gytis Trikleris (JIRA)
Gytis Trikleris created JBTM-1979:
-------------------------------------
Summary: REST-AT integration API recovery tests failed on IPv6
Key: JBTM-1979
URL: https://issues.jboss.org/browse/JBTM-1979
Project: JBoss Transaction Manager
Issue Type: Bug
Security Level: Public (Everyone can see)
Components: REST
Reporter: Gytis Trikleris
Assignee: Gytis Trikleris
Priority: Minor
Fix For: 5.0.0.Final
http://172.17.131.2/view/Narayana+BlackTie/job/narayana-dualstack/137/con...
{code}
Running org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase
Tests run: 4, Failures: 0, Errors: 4, Skipped: 0, Time elapsed: 5,989.309 sec <<< FAILURE!
testCrashAfterPrepare(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase) Time elapsed: 2,994.019 sec <<< ERROR!
org.jboss.arquillian.container.spi.client.container.LifecycleException: Could not start container
at org.jboss.as.arquillian.container.managed.ManagedDeployableContainer.startInternal(ManagedDeployableContainer.java:199)
at org.jboss.as.arquillian.container.CommonDeployableContainer.start(CommonDeployableContainer.java:112)
at org.jboss.arquillian.container.impl.ContainerImpl.start(ContainerImpl.java:199)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$8.perform(ContainerLifecycleController.java:163)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$8.perform(ContainerLifecycleController.java:157)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.forContainer(ContainerLifecycleController.java:255)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.startContainer(ContainerLifecycleController.java:156)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.container.impl.client.ContainerDeploymentContextHandler.createContainerContext(ContainerDeploymentContextHandler.java:57)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
at org.jboss.arquillian.container.test.impl.client.container.ClientContainerController.start(ClientContainerController.java:150)
at org.jboss.narayana.rest.integration.test.integration.AbstractIntegrationTestCase.startContainer(AbstractIntegrationTestCase.java:65)
at org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase.testCrashAfterPrepare(RecoveryIntegrationTestCase.java:57)
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.jboss.arquillian.junit.Arquillian$6$1.invoke(Arquillian.java:270)
at org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:60)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
at org.jboss.arquillian.container.test.impl.execution.ClientTestExecuter.execute(ClientTestExecuter.java:53)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:142)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createTestContext(ContainerEventController.java:129)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:111)
at org.jboss.arquillian.junit.Arquillian$6.evaluate(Arquillian.java:263)
at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:226)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240)
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.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199)
at org.junit.runners.ParentRunner.run(ParentRunner.java:300)
at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
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)
Caused by: java.util.concurrent.TimeoutException: Managed server was not started within [60] s
at org.jboss.as.arquillian.container.managed.ManagedDeployableContainer.startInternal(ManagedDeployableContainer.java:195)
... 115 more
testCrashAfterPrepare(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase) Time elapsed: 2,994.021 sec <<< ERROR!
java.lang.IllegalArgumentException: Deployment with name test could not be undeployed. Container jboss must be still running.
at org.jboss.arquillian.container.test.impl.client.deployment.ClientDeployer.undeploy(ClientDeployer.java:128)
at org.jboss.narayana.rest.integration.test.integration.AbstractIntegrationTestCase.stopContainer(AbstractIntegrationTestCase.java:83)
at org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase.after(RecoveryIntegrationTestCase.java:52)
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.RunAfters.evaluate(RunAfters.java:36)
at org.jboss.arquillian.junit.Arquillian$StatementLifecycleExecutor.invoke(Arquillian.java:351)
at org.jboss.arquillian.container.test.impl.execution.ClientBeforeAfterLifecycleEventExecuter.execute(ClientBeforeAfterLifecycleEventExecuter.java:99)
at org.jboss.arquillian.container.test.impl.execution.ClientBeforeAfterLifecycleEventExecuter.on(ClientBeforeAfterLifecycleEventExecuter.java:80)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:142)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createAfterContext(ContainerEventController.java:134)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.after(EventTestRunnerAdaptor.java:103)
at org.jboss.arquillian.junit.Arquillian$5$1.evaluate(Arquillian.java:245)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240)
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.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199)
at org.junit.runners.ParentRunner.run(ParentRunner.java:300)
at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
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)
testCrashBeforeCommit(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase) Time elapsed: 2,994.231 sec <<< ERROR!
org.jboss.arquillian.container.spi.client.container.LifecycleException: Could not start container
at org.jboss.as.arquillian.container.managed.ManagedDeployableContainer.startInternal(ManagedDeployableContainer.java:199)
at org.jboss.as.arquillian.container.CommonDeployableContainer.start(CommonDeployableContainer.java:112)
at org.jboss.arquillian.container.impl.ContainerImpl.start(ContainerImpl.java:199)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$8.perform(ContainerLifecycleController.java:163)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$8.perform(ContainerLifecycleController.java:157)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.forContainer(ContainerLifecycleController.java:255)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.startContainer(ContainerLifecycleController.java:156)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.container.impl.client.ContainerDeploymentContextHandler.createContainerContext(ContainerDeploymentContextHandler.java:57)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
at org.jboss.arquillian.container.test.impl.client.container.ClientContainerController.start(ClientContainerController.java:150)
at org.jboss.narayana.rest.integration.test.integration.AbstractIntegrationTestCase.startContainer(AbstractIntegrationTestCase.java:65)
at org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase.testCrashBeforeCommit(RecoveryIntegrationTestCase.java:91)
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.jboss.arquillian.junit.Arquillian$6$1.invoke(Arquillian.java:270)
at org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:60)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
at org.jboss.arquillian.container.test.impl.execution.ClientTestExecuter.execute(ClientTestExecuter.java:53)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:142)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createTestContext(ContainerEventController.java:129)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:111)
at org.jboss.arquillian.junit.Arquillian$6.evaluate(Arquillian.java:263)
at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:226)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240)
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.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199)
at org.junit.runners.ParentRunner.run(ParentRunner.java:300)
at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
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)
Caused by: java.util.concurrent.TimeoutException: Managed server was not started within [60] s
at org.jboss.as.arquillian.container.managed.ManagedDeployableContainer.startInternal(ManagedDeployableContainer.java:195)
... 115 more
testCrashBeforeCommit(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase) Time elapsed: 2,994.234 sec <<< ERROR!
java.lang.IllegalArgumentException: Deployment with name test could not be undeployed. Container jboss must be still running.
at org.jboss.arquillian.container.test.impl.client.deployment.ClientDeployer.undeploy(ClientDeployer.java:128)
at org.jboss.narayana.rest.integration.test.integration.AbstractIntegrationTestCase.stopContainer(AbstractIntegrationTestCase.java:83)
at org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase.after(RecoveryIntegrationTestCase.java:52)
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.RunAfters.evaluate(RunAfters.java:36)
at org.jboss.arquillian.junit.Arquillian$StatementLifecycleExecutor.invoke(Arquillian.java:351)
at org.jboss.arquillian.container.test.impl.execution.ClientBeforeAfterLifecycleEventExecuter.execute(ClientBeforeAfterLifecycleEventExecuter.java:99)
at org.jboss.arquillian.container.test.impl.execution.ClientBeforeAfterLifecycleEventExecuter.on(ClientBeforeAfterLifecycleEventExecuter.java:80)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:142)
at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createAfterContext(ContainerEventController.java:134)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
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.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.after(EventTestRunnerAdaptor.java:103)
at org.jboss.arquillian.junit.Arquillian$5$1.evaluate(Arquillian.java:245)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240)
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.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185)
at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314)
at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46)
at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199)
at org.junit.runners.ParentRunner.run(ParentRunner.java:300)
at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
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)
Results :
Tests in error:
testCrashAfterPrepare(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase): Could not start container
testCrashAfterPrepare(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase): Deployment with name test could not be undeployed. Container jboss must be still running.
testCrashBeforeCommit(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase): Could not start container
testCrashBeforeCommit(org.jboss.narayana.rest.integration.test.integration.RecoveryIntegrationTestCase): Deployment with name test could not be undeployed. Container jboss must be still running.
{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
11 years, 2 months
[JBoss JIRA] (JBTM-1978) QA test suite failure: CrashRecovery05_1_Test01
by Gytis Trikleris (JIRA)
[ https://issues.jboss.org/browse/JBTM-1978?page=com.atlassian.jira.plugin.... ]
Gytis Trikleris updated JBTM-1978:
----------------------------------
Assignee: Tom Jenkinson (was: Gytis Trikleris)
> QA test suite failure: CrashRecovery05_1_Test01
> -----------------------------------------------
>
> Key: JBTM-1978
> URL: https://issues.jboss.org/browse/JBTM-1978
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: Testing
> Reporter: Gytis Trikleris
> Assignee: Tom Jenkinson
> Priority: Minor
> Fix For: 5.0.0.Final
>
>
> http://172.17.131.2/view/Narayana+BlackTie/job/narayana/303/TESTS=QA_JTS_...
> client0_output.txt:
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client0-coverage.ec -DportOffsetId=3 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01b value_1
> 2013-10-11 20:34:34,355 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:34,355 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:34,421 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:34,421 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:34,421 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:34,426 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:34,439 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:34,440 err: 11 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:34,449 err: 20 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:34,466 err: 37 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:34,466 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:34,466 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:34,466 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:34,466 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:34,538 err: 109 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:34,585 err: 156 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:34,784 err: 355 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:34,784 err: 355 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:34,786 err: 357 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:34,786 err: 357 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:34,877 err: 448 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:34,878 err: 00 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:34,878 err: object is activated
> 2013-10-11 20:34:34,880 err: 451 [main] INFO jacorb.poa - Using server ID (3870081024) for transient POA
> 2013-10-11 20:34:34,989 err: 560 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
> 2013-10-11 20:34:35,111 err: 682 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:35,111 err: 01 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:35,111 err: object is activated
> 2013-10-11 20:34:35,114 err: 685 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:42337 (e33ad7)
> 2013-10-11 20:34:35,153 err: 724 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:42337 from local port 52671
> 2013-10-11 20:34:35,298 err: 869 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54297
> 2013-10-11 20:34:35,326 err: 896 [RequestProcessor-4] INFO jacorb.poa - oid:
> 2013-10-11 20:34:35,326 err: 02 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:35,326 err: object is activated
> 2013-10-11 20:34:35,374 err: 945 [RequestProcessor-4] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:42337 (e33ad7)
> 2013-10-11 20:34:35,754 err: 1325 [ClientMessageReceptor1] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:42337 closed.
> 2013-10-11 20:34:35,754 err: 1325 [ClientMessageReceptor1] WARN jacorb.giop.conn - Abnormal connection termination. Lost 1 outstanding replie(s)!
> 2013-10-11 20:34:35,757 out: 2013-10-11 20:34:35,757 [main] WARN com.arjuna.ats.arjuna - ARJUNA012084: One-phase commit of action 0:ffffac118322:9684:525852ca:3 received heuristic decision: TwoPhaseOutcome.HEURISTIC_HAZARD
> 2013-10-11 20:34:35,768 err: 1339 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54297
> 2013-10-11 20:34:35,810 err: 1381 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:36,311 err: 1882 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:36,812 err: 2383 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:37,313 err: 2883 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:37,813 err: 3384 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:38,328 out: 2013-10-11 20:34:38,316 [main] WARN com.arjuna.ats.jts - ARJUNA022230: ResourceRecord.forgetHeuristic caught exception
> 2013-10-11 20:34:38,328 out: org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 172.17.131.34:42337 vmcid: 0x0 minor code: 0 completed: No
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.iiop.ClientIIOPConnection.connect(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.GIOPConnection.sendMessage(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.GIOPConnection.sendRequest(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.ClientConnection.sendRequest(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.ClientConnection.sendRequest(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.is_a(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:130)
> 2013-10-11 20:34:38,328 out: at org.omg.CosTransactions.TransactionalObjectHelper.narrow(TransactionalObjectHelper.java:57)
> 2013-10-11 20:34:38,328 out: at com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionClientRequestInterceptorImpl.send_request(InterpositionClientRequestInterceptorImpl.java:141)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.invoke(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.AbstractInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.ClientInterceptorHandler.invokeInterceptors(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.ClientInterceptorHandler.handle_send_request(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:475)
> 2013-10-11 20:34:38,328 out: at org.omg.CosTransactions._ResourceStub.forget(_ResourceStub.java:205)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.resources.ResourceRecord.forgetHeuristic(ResourceRecord.java:569)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.doForget(BasicAction.java:3510)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.forgetHeuristics(BasicAction.java:1329)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.forgetHeuristics(ArjunaTransactionImple.java:1365)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2429)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.commit(ArjunaTransactionImple.java:375)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.ControlWrapper.commit(ControlWrapper.java:243)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.CurrentImple.commit(CurrentImple.java:247)
> 2013-10-11 20:34:38,329 out: at org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01b.main(Client01b.java:93)
> 2013-10-11 20:34:38,371 err: 3941 [main] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
> 2013-10-11 20:34:38,371 err: 3942 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54304
> 2013-10-11 20:34:38,372 err: 3943 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54304
> 2013-10-11 20:34:38,374 err: 3945 [AOM_RemovalThread] INFO jacorb.poa - oid:
> 2013-10-11 20:34:38,374 err: 01 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:38,374 err: object is deactivated
> 2013-10-11 20:34:38,375 out: Passed
> 2013-10-11 20:34:38,375 err: 3946 [AOM_RemovalThread] INFO jacorb.poa - oid:
> 2013-10-11 20:34:38,375 err: 02 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:38,375 err: object is deactivated
> 2013-10-11 20:34:38,378 err: 3949 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
> 2013-10-11 20:34:38,378 err: 3949 [main] INFO jacorb.orb - prepare ORB for shutdown...
> 2013-10-11 20:34:38,378 err: 3949 [main] INFO jacorb.orb - ORB going down...
> 2013-10-11 20:34:38,379 err: 3950 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
> 2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54304
> 2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:54307 closed.
> 2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb - ORB shutdown complete
> 2013-10-11 20:34:38,382 err: 3952 [ClientMessageReceptor0] INFO jacorb.giop.conn - Received CloseConnection on ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
> 2013-10-11 20:34:38,382 err: 3952 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> 2013-10-11 20:34:38,382 err: 3953 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54304
> 2013-10-11 20:34:38,382 err: 3953 [ServerMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> 2013-10-11 20:34:38,382 err: 3953 [ClientMessageReceptor1] INFO jacorb.util.tpool - ConsumerTie exited
> {code}
> client1_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client1-coverage.ec -DportOffsetId=3 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01a value_2
> 2013-10-11 20:34:39,769 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:39,769 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:39,834 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:39,835 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:39,835 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:39,835 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:39,848 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:39,851 err: 13 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:39,859 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:39,874 err: 36 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:39,874 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:39,874 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:39,875 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:39,875 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:39,947 err: 108 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:39,993 err: 155 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:40,192 err: 354 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:40,192 err: 354 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:40,194 err: 356 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:40,197 err: 359 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:40,283 err: 445 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:44860 (512e3872)
> 2013-10-11 20:34:40,307 err: 469 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:44860 from local port 55790
> 2013-10-11 20:34:40,629 out: Using timeout delay factor of 2
> 2013-10-11 20:35:00,641 out: Failed
> 2013-10-11 20:35:00,642 err: 20804 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
> 2013-10-11 20:35:00,642 err: 20804 [main] INFO jacorb.orb - prepare ORB for shutdown...
> 2013-10-11 20:35:00,642 err: 20804 [main] INFO jacorb.orb - ORB going down...
> 2013-10-11 20:35:00,645 err: 20805 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:44860 closed.
> 2013-10-11 20:35:00,645 err: 20805 [main] INFO jacorb.orb - ORB shutdown complete
> 2013-10-11 20:35:00,645 err: 20806 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
> 2013-10-11 20:35:00,647 err: 20809 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> {code}
> server0_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server0-coverage.ec -DportOffsetId=1 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server0 -DRecoveryEnvironmentBean.recoveryListener=true com.arjuna.ats.arjuna.recovery.RecoveryManager -test
> 2013-10-11 20:34:32,252 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:32,252 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:32,355 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:32,355 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:32,355 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:32,356 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:32,361 err: 3 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:32,361 err: 3 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:32,363 err: 5 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:32,378 err: 20 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:32,378 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:32,378 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:32,378 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:32,378 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:32,449 err: 91 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:32,495 err: 137 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:32,722 err: 364 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:32,722 err: 364 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:32,724 err: 366 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:32,727 err: 369 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:32,866 err: 508 [Thread-3] INFO jacorb.orb - ORB run
> 2013-10-11 20:34:32,962 out: Ready
> 2013-10-11 20:34:40,464 err: 8104 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54289
> 2013-10-11 20:34:40,575 err: 8217 [RequestProcessor-4] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (2451c70d)
> 2013-10-11 20:34:40,587 err: 8229 [RequestProcessor-4] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54307
> 2013-10-11 20:34:40,589 err: 8230 [ClientMessageReceptor0] WARN jacorb.orb.giop - Received a request on a non-bidir connection
> 2013-10-11 20:34:40,605 out: 2013-10-11 20:34:40,595 [RequestProcessor-4] WARN com.arjuna.ats.jts - ARJUNA022139: CORBA exception on trying to contact original process
> 2013-10-11 20:34:40,606 out: org.omg.CORBA.INV_POLICY: Server-side Exception: null vmcid: 0x0 minor code: 0 completed: No
> 2013-10-11 20:34:40,606 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> 2013-10-11 20:34:40,606 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
> 2013-10-11 20:34:40,606 out: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> 2013-10-11 20:34:40,606 out: at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.SystemExceptionHelper.read(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.ReplyReceiver.getReply(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.is_a(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:130)
> 2013-10-11 20:34:40,606 out: at org.omg.CosTransactions.TransactionalObjectHelper.narrow(TransactionalObjectHelper.java:57)
> 2013-10-11 20:34:40,606 out: at com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionClientRequestInterceptorImpl.send_request(InterpositionClientRequestInterceptorImpl.java:141)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.invoke(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.portableInterceptor.AbstractInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.ClientInterceptorHandler.invokeInterceptors(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.ClientInterceptorHandler.handle_send_request(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:475)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ArjunaOTS._ArjunaFactoryStub.getCurrentStatus(_ArjunaFactoryStub.java:31)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.getStatus(StatusChecker.java:176)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.checkOriginalStatus(StatusChecker.java:144)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.get_current_status(StatusChecker.java:110)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.recovery.recoverycoordinators.GenericRecoveryCoordinator.get_status(GenericRecoveryCoordinator.java:328)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.recovery.recoverycoordinators.GenericRecoveryCoordinator.replay_completion(GenericRecoveryCoordinator.java:145)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCDefaultServant.replay_completion(JacOrbRCDefaultServant.java:90)
> 2013-10-11 20:34:40,607 out: at org.omg.CosTransactions.RecoveryCoordinatorPOA._invoke(RecoveryCoordinatorPOA.java:44)
> 2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.process(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.run(Unknown Source)
> {code}
> server1_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server1-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Servers.Server01 value_3 value_1
> 2013-10-11 20:34:33,392 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:33,392 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:33,458 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:33,458 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:33,458 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:33,458 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:33,470 err: 9 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:33,473 err: 12 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:33,482 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:33,498 err: 37 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:33,498 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:33,498 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:33,498 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:33,498 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:33,575 err: 114 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:33,622 err: 161 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:33,803 out: BeforeCrashServiceImpl01(231605477, 0)
> 2013-10-11 20:34:33,804 err: 343 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:33,804 err: 00 18 06 33 00 42 14 0E 17 4D ...3.B...M
> 2013-10-11 20:34:33,804 err: object is activated
> 2013-10-11 20:34:33,810 err: 348 [main] INFO jacorb.poa - Using server ID (3589635481) for transient POA
> 2013-10-11 20:34:33,850 err: 389 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:33,850 err: 389 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:33,852 err: 391 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:33,852 err: 391 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:33,914 err: 453 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:42337 (59966240)
> 2013-10-11 20:34:33,925 out: Ready
> 2013-10-11 20:34:33,926 err: 465 [main] INFO jacorb.orb - ORB run
> 2013-10-11 20:34:35,151 err: 1690 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:52671
> 2013-10-11 20:34:35,234 err: 1773 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (d13e8c7)
> 2013-10-11 20:34:35,271 err: 1810 [RequestProcessor-5] INFO jacorb.poa - oid:
> 2013-10-11 20:34:35,271 err: 01 18 06 33 00 42 14 0E 17 4D ...3.B...M
> 2013-10-11 20:34:35,271 err: object is activated
> 2013-10-11 20:34:35,273 err: 1811 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:42337 (59966240)
> 2013-10-11 20:34:35,301 err: 1840 [RequestProcessor-5] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54297
> 2013-10-11 20:34:35,333 err: 1870 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:54307 (d13e8c7)
> 2013-10-11 20:34:35,389 out: BeforeCrashServiceImpl01: storing IOR "RecoveryCoordinator_231605477_0_0"
> 2013-10-11 20:34:35,428 err: BeforeCrashServiceImpl01.is_correct: true
> 2013-10-11 20:34:35,434 err: ResourceImpl01.commit_one_phase [O0.R0]: Crash
> {code}
> server2_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server2-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Servers.Server02 value_3 value_2
> 2013-10-11 20:34:38,819 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:38,820 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:38,885 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:38,885 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:38,885 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:38,885 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:38,898 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:38,901 err: 13 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:38,909 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:38,925 err: 37 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:38,926 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:38,926 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:38,926 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:38,926 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:39,001 err: 112 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:39,045 err: 157 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:39,228 out: AfterCrashServiceImpl01(231605477, 0)
> 2013-10-11 20:34:39,232 err: 344 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:39,233 err: 00 18 06 33 00 43 2C 4B 21 2D ...3.C,K!-
> 2013-10-11 20:34:39,233 err: object is activated
> 2013-10-11 20:34:39,235 err: 347 [main] INFO jacorb.poa - Using server ID (1830494895) for transient POA
> 2013-10-11 20:34:39,275 err: 387 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:39,275 err: 387 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:39,277 err: 389 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:39,279 err: 391 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:39,337 err: 449 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:44860 (5c2f4f8b)
> 2013-10-11 20:34:39,356 out: Ready
> 2013-10-11 20:34:39,357 err: 469 [main] INFO jacorb.orb - ORB run
> 2013-10-11 20:34:40,306 err: 1418 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:55790
> 2013-10-11 20:34:40,401 err: 1513 [RequestProcessor-5] INFO jacorb.poa - oid:
> 2013-10-11 20:34:40,401 err: 01 18 06 33 00 43 2C 4B 21 2D ...3.C,K!-
> 2013-10-11 20:34:40,401 err: object is activated
> 2013-10-11 20:34:40,404 err: 1516 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:44860 (5c2f4f8b)
> 2013-10-11 20:34:40,409 out: AfterCrashServiceImpl01: loading IOR "RecoveryCoordinator_231605477_0_0"
> 2013-10-11 20:34:40,425 err: 1536 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:4711 (25a226eb)
> 2013-10-11 20:34:40,435 err: 1547 [RequestProcessor-5] ERROR jacorb.orb - no adapter activator exists for RcvCo-RecCoService_cersei.buildnet.ncl.jboss.comrecovery_coordinator
> 2013-10-11 20:34:40,461 err: 1573 [RequestProcessor-5] INFO jacorb.orb.iiop - Connected to 172.17.131.34:4711 from local port 54289
> 2013-10-11 20:34:40,612 err: AfterCrashServiceImpl01.check_oper [O0.R0]: replay_completion returned: org.omg.CosTransactions.Status@b93c476
> 2013-10-11 20:35:00,640 err: AfterCrashServiceImpl01.get_resource_trace [O0.R0]: ResourceTraceNone
> 2013-10-11 20:35:00,646 err: 21758 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:55790
> {code}
> task0_output.txt
> {code}
> performing command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/task0-coverage.ec -DportOffsetId=6 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/task0 -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.Utils.RemoveServerIORStore value_1 value_2 value_3
> 2013-10-11 20:35:00,832 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
> 2013-10-11 20:35:00,832 err: at java.io.FileInputStream.open(Native Method)
> 2013-10-11 20:35:00,833 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
> 2013-10-11 20:35:00,833 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
> 2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
> 2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
> 2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
> 2013-10-11 20:35:00,834 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
> 2013-10-11 20:35:00,834 err: at java.io.FileInputStream.open(Native Method)
> 2013-10-11 20:35:00,834 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
> 2013-10-11 20:35:00,834 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
> 2013-10-11 20:35:00,834 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
> 2013-10-11 20:35:00,834 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
> 2013-10-11 20:35:00,835 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
> 2013-10-11 20:35:00,835 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
> 2013-10-11 20:35:00,835 err: at java.io.FileInputStream.open(Native Method)
> 2013-10-11 20:35:00,835 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
> 2013-10-11 20:35:00,835 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
> 2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
> 2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
> 2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
> 2013-10-11 20:35:00,836 out: Passed
> {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
11 years, 2 months
[JBoss JIRA] (JBTM-1978) QA test suite failure: CrashRecovery05_1_Test01
by Gytis Trikleris (JIRA)
[ https://issues.jboss.org/browse/JBTM-1978?page=com.atlassian.jira.plugin.... ]
Gytis Trikleris updated JBTM-1978:
----------------------------------
Issue Type: Bug (was: Feature Request)
> QA test suite failure: CrashRecovery05_1_Test01
> -----------------------------------------------
>
> Key: JBTM-1978
> URL: https://issues.jboss.org/browse/JBTM-1978
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: Testing
> Reporter: Gytis Trikleris
> Assignee: Gytis Trikleris
> Priority: Minor
> Fix For: 5.0.0.Final
>
>
> http://172.17.131.2/view/Narayana+BlackTie/job/narayana/303/TESTS=QA_JTS_...
> client0_output.txt:
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client0-coverage.ec -DportOffsetId=3 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01b value_1
> 2013-10-11 20:34:34,355 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:34,355 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:34,421 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:34,421 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:34,421 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:34,426 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:34,439 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:34,440 err: 11 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:34,449 err: 20 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:34,466 err: 37 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:34,466 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:34,466 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:34,466 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:34,466 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:34,538 err: 109 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:34,585 err: 156 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:34,784 err: 355 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:34,784 err: 355 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:34,786 err: 357 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:34,786 err: 357 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:34,877 err: 448 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:34,878 err: 00 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:34,878 err: object is activated
> 2013-10-11 20:34:34,880 err: 451 [main] INFO jacorb.poa - Using server ID (3870081024) for transient POA
> 2013-10-11 20:34:34,989 err: 560 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
> 2013-10-11 20:34:35,111 err: 682 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:35,111 err: 01 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:35,111 err: object is activated
> 2013-10-11 20:34:35,114 err: 685 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:42337 (e33ad7)
> 2013-10-11 20:34:35,153 err: 724 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:42337 from local port 52671
> 2013-10-11 20:34:35,298 err: 869 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54297
> 2013-10-11 20:34:35,326 err: 896 [RequestProcessor-4] INFO jacorb.poa - oid:
> 2013-10-11 20:34:35,326 err: 02 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:35,326 err: object is activated
> 2013-10-11 20:34:35,374 err: 945 [RequestProcessor-4] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:42337 (e33ad7)
> 2013-10-11 20:34:35,754 err: 1325 [ClientMessageReceptor1] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:42337 closed.
> 2013-10-11 20:34:35,754 err: 1325 [ClientMessageReceptor1] WARN jacorb.giop.conn - Abnormal connection termination. Lost 1 outstanding replie(s)!
> 2013-10-11 20:34:35,757 out: 2013-10-11 20:34:35,757 [main] WARN com.arjuna.ats.arjuna - ARJUNA012084: One-phase commit of action 0:ffffac118322:9684:525852ca:3 received heuristic decision: TwoPhaseOutcome.HEURISTIC_HAZARD
> 2013-10-11 20:34:35,768 err: 1339 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54297
> 2013-10-11 20:34:35,810 err: 1381 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:36,311 err: 1882 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:36,812 err: 2383 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:37,313 err: 2883 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:37,813 err: 3384 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
> 2013-10-11 20:34:38,328 out: 2013-10-11 20:34:38,316 [main] WARN com.arjuna.ats.jts - ARJUNA022230: ResourceRecord.forgetHeuristic caught exception
> 2013-10-11 20:34:38,328 out: org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 172.17.131.34:42337 vmcid: 0x0 minor code: 0 completed: No
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.iiop.ClientIIOPConnection.connect(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.GIOPConnection.sendMessage(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.GIOPConnection.sendRequest(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.ClientConnection.sendRequest(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.ClientConnection.sendRequest(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.is_a(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:130)
> 2013-10-11 20:34:38,328 out: at org.omg.CosTransactions.TransactionalObjectHelper.narrow(TransactionalObjectHelper.java:57)
> 2013-10-11 20:34:38,328 out: at com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionClientRequestInterceptorImpl.send_request(InterpositionClientRequestInterceptorImpl.java:141)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.invoke(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.AbstractInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.ClientInterceptorHandler.invokeInterceptors(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.ClientInterceptorHandler.handle_send_request(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:38,328 out: at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:475)
> 2013-10-11 20:34:38,328 out: at org.omg.CosTransactions._ResourceStub.forget(_ResourceStub.java:205)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.resources.ResourceRecord.forgetHeuristic(ResourceRecord.java:569)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.doForget(BasicAction.java:3510)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.forgetHeuristics(BasicAction.java:1329)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.forgetHeuristics(ArjunaTransactionImple.java:1365)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2429)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.commit(ArjunaTransactionImple.java:375)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.ControlWrapper.commit(ControlWrapper.java:243)
> 2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.CurrentImple.commit(CurrentImple.java:247)
> 2013-10-11 20:34:38,329 out: at org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01b.main(Client01b.java:93)
> 2013-10-11 20:34:38,371 err: 3941 [main] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
> 2013-10-11 20:34:38,371 err: 3942 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54304
> 2013-10-11 20:34:38,372 err: 3943 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54304
> 2013-10-11 20:34:38,374 err: 3945 [AOM_RemovalThread] INFO jacorb.poa - oid:
> 2013-10-11 20:34:38,374 err: 01 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:38,374 err: object is deactivated
> 2013-10-11 20:34:38,375 out: Passed
> 2013-10-11 20:34:38,375 err: 3946 [AOM_RemovalThread] INFO jacorb.poa - oid:
> 2013-10-11 20:34:38,375 err: 02 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
> 2013-10-11 20:34:38,375 err: object is deactivated
> 2013-10-11 20:34:38,378 err: 3949 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
> 2013-10-11 20:34:38,378 err: 3949 [main] INFO jacorb.orb - prepare ORB for shutdown...
> 2013-10-11 20:34:38,378 err: 3949 [main] INFO jacorb.orb - ORB going down...
> 2013-10-11 20:34:38,379 err: 3950 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
> 2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54304
> 2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:54307 closed.
> 2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb - ORB shutdown complete
> 2013-10-11 20:34:38,382 err: 3952 [ClientMessageReceptor0] INFO jacorb.giop.conn - Received CloseConnection on ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
> 2013-10-11 20:34:38,382 err: 3952 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> 2013-10-11 20:34:38,382 err: 3953 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54304
> 2013-10-11 20:34:38,382 err: 3953 [ServerMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> 2013-10-11 20:34:38,382 err: 3953 [ClientMessageReceptor1] INFO jacorb.util.tpool - ConsumerTie exited
> {code}
> client1_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client1-coverage.ec -DportOffsetId=3 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01a value_2
> 2013-10-11 20:34:39,769 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:39,769 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:39,834 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:39,835 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:39,835 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:39,835 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:39,848 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:39,851 err: 13 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:39,859 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:39,874 err: 36 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:39,874 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:39,874 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:39,875 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:39,875 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:39,947 err: 108 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:39,993 err: 155 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:40,192 err: 354 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:40,192 err: 354 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:40,194 err: 356 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:40,197 err: 359 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:40,283 err: 445 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:44860 (512e3872)
> 2013-10-11 20:34:40,307 err: 469 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:44860 from local port 55790
> 2013-10-11 20:34:40,629 out: Using timeout delay factor of 2
> 2013-10-11 20:35:00,641 out: Failed
> 2013-10-11 20:35:00,642 err: 20804 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
> 2013-10-11 20:35:00,642 err: 20804 [main] INFO jacorb.orb - prepare ORB for shutdown...
> 2013-10-11 20:35:00,642 err: 20804 [main] INFO jacorb.orb - ORB going down...
> 2013-10-11 20:35:00,645 err: 20805 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:44860 closed.
> 2013-10-11 20:35:00,645 err: 20805 [main] INFO jacorb.orb - ORB shutdown complete
> 2013-10-11 20:35:00,645 err: 20806 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
> 2013-10-11 20:35:00,647 err: 20809 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> {code}
> server0_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server0-coverage.ec -DportOffsetId=1 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server0 -DRecoveryEnvironmentBean.recoveryListener=true com.arjuna.ats.arjuna.recovery.RecoveryManager -test
> 2013-10-11 20:34:32,252 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:32,252 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:32,355 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:32,355 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:32,355 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:32,356 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:32,361 err: 3 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:32,361 err: 3 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:32,363 err: 5 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:32,378 err: 20 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:32,378 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:32,378 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:32,378 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:32,378 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:32,449 err: 91 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:32,495 err: 137 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:32,722 err: 364 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:32,722 err: 364 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:32,724 err: 366 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:32,727 err: 369 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:32,866 err: 508 [Thread-3] INFO jacorb.orb - ORB run
> 2013-10-11 20:34:32,962 out: Ready
> 2013-10-11 20:34:40,464 err: 8104 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54289
> 2013-10-11 20:34:40,575 err: 8217 [RequestProcessor-4] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (2451c70d)
> 2013-10-11 20:34:40,587 err: 8229 [RequestProcessor-4] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54307
> 2013-10-11 20:34:40,589 err: 8230 [ClientMessageReceptor0] WARN jacorb.orb.giop - Received a request on a non-bidir connection
> 2013-10-11 20:34:40,605 out: 2013-10-11 20:34:40,595 [RequestProcessor-4] WARN com.arjuna.ats.jts - ARJUNA022139: CORBA exception on trying to contact original process
> 2013-10-11 20:34:40,606 out: org.omg.CORBA.INV_POLICY: Server-side Exception: null vmcid: 0x0 minor code: 0 completed: No
> 2013-10-11 20:34:40,606 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> 2013-10-11 20:34:40,606 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
> 2013-10-11 20:34:40,606 out: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> 2013-10-11 20:34:40,606 out: at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.SystemExceptionHelper.read(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.ReplyReceiver.getReply(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.is_a(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:130)
> 2013-10-11 20:34:40,606 out: at org.omg.CosTransactions.TransactionalObjectHelper.narrow(TransactionalObjectHelper.java:57)
> 2013-10-11 20:34:40,606 out: at com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionClientRequestInterceptorImpl.send_request(InterpositionClientRequestInterceptorImpl.java:141)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.invoke(Unknown Source)
> 2013-10-11 20:34:40,606 out: at org.jacorb.orb.portableInterceptor.AbstractInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.iterate(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.ClientInterceptorHandler.invokeInterceptors(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.ClientInterceptorHandler.handle_send_request(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:475)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ArjunaOTS._ArjunaFactoryStub.getCurrentStatus(_ArjunaFactoryStub.java:31)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.getStatus(StatusChecker.java:176)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.checkOriginalStatus(StatusChecker.java:144)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.get_current_status(StatusChecker.java:110)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.recovery.recoverycoordinators.GenericRecoveryCoordinator.get_status(GenericRecoveryCoordinator.java:328)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.recovery.recoverycoordinators.GenericRecoveryCoordinator.replay_completion(GenericRecoveryCoordinator.java:145)
> 2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCDefaultServant.replay_completion(JacOrbRCDefaultServant.java:90)
> 2013-10-11 20:34:40,607 out: at org.omg.CosTransactions.RecoveryCoordinatorPOA._invoke(RecoveryCoordinatorPOA.java:44)
> 2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.process(Unknown Source)
> 2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.run(Unknown Source)
> {code}
> server1_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server1-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Servers.Server01 value_3 value_1
> 2013-10-11 20:34:33,392 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:33,392 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:33,458 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:33,458 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:33,458 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:33,458 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:33,470 err: 9 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:33,473 err: 12 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:33,482 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:33,498 err: 37 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:33,498 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:33,498 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:33,498 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:33,498 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:33,575 err: 114 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:33,622 err: 161 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:33,803 out: BeforeCrashServiceImpl01(231605477, 0)
> 2013-10-11 20:34:33,804 err: 343 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:33,804 err: 00 18 06 33 00 42 14 0E 17 4D ...3.B...M
> 2013-10-11 20:34:33,804 err: object is activated
> 2013-10-11 20:34:33,810 err: 348 [main] INFO jacorb.poa - Using server ID (3589635481) for transient POA
> 2013-10-11 20:34:33,850 err: 389 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:33,850 err: 389 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:33,852 err: 391 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:33,852 err: 391 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:33,914 err: 453 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:42337 (59966240)
> 2013-10-11 20:34:33,925 out: Ready
> 2013-10-11 20:34:33,926 err: 465 [main] INFO jacorb.orb - ORB run
> 2013-10-11 20:34:35,151 err: 1690 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:52671
> 2013-10-11 20:34:35,234 err: 1773 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (d13e8c7)
> 2013-10-11 20:34:35,271 err: 1810 [RequestProcessor-5] INFO jacorb.poa - oid:
> 2013-10-11 20:34:35,271 err: 01 18 06 33 00 42 14 0E 17 4D ...3.B...M
> 2013-10-11 20:34:35,271 err: object is activated
> 2013-10-11 20:34:35,273 err: 1811 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:42337 (59966240)
> 2013-10-11 20:34:35,301 err: 1840 [RequestProcessor-5] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54297
> 2013-10-11 20:34:35,333 err: 1870 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:54307 (d13e8c7)
> 2013-10-11 20:34:35,389 out: BeforeCrashServiceImpl01: storing IOR "RecoveryCoordinator_231605477_0_0"
> 2013-10-11 20:34:35,428 err: BeforeCrashServiceImpl01.is_correct: true
> 2013-10-11 20:34:35,434 err: ResourceImpl01.commit_one_phase [O0.R0]: Crash
> {code}
> server2_output.txt
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server2-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Servers.Server02 value_3 value_2
> 2013-10-11 20:34:38,819 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-10-11 20:34:38,820 err: log4j:WARN Please initialize the log4j system properly.
> 2013-10-11 20:34:38,885 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-10-11 20:34:38,885 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:38,885 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-10-11 20:34:38,885 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-10-11 20:34:38,898 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:38,901 err: 13 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:38,909 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:38,925 err: 37 [main] INFO jacorb.orb.print_version -
> 2013-10-11 20:34:38,926 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:38,926 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-10-11 20:34:38,926 err: (C) The JacORB project 29-Jul-2009
> 2013-10-11 20:34:38,926 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-10-11 20:34:39,001 err: 112 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-10-11 20:34:39,045 err: 157 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-10-11 20:34:39,228 out: AfterCrashServiceImpl01(231605477, 0)
> 2013-10-11 20:34:39,232 err: 344 [main] INFO jacorb.poa - oid:
> 2013-10-11 20:34:39,233 err: 00 18 06 33 00 43 2C 4B 21 2D ...3.C,K!-
> 2013-10-11 20:34:39,233 err: object is activated
> 2013-10-11 20:34:39,235 err: 347 [main] INFO jacorb.poa - Using server ID (1830494895) for transient POA
> 2013-10-11 20:34:39,275 err: 387 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-10-11 20:34:39,275 err: 387 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-10-11 20:34:39,277 err: 389 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-10-11 20:34:39,279 err: 391 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-10-11 20:34:39,337 err: 449 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:44860 (5c2f4f8b)
> 2013-10-11 20:34:39,356 out: Ready
> 2013-10-11 20:34:39,357 err: 469 [main] INFO jacorb.orb - ORB run
> 2013-10-11 20:34:40,306 err: 1418 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:55790
> 2013-10-11 20:34:40,401 err: 1513 [RequestProcessor-5] INFO jacorb.poa - oid:
> 2013-10-11 20:34:40,401 err: 01 18 06 33 00 43 2C 4B 21 2D ...3.C,K!-
> 2013-10-11 20:34:40,401 err: object is activated
> 2013-10-11 20:34:40,404 err: 1516 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:44860 (5c2f4f8b)
> 2013-10-11 20:34:40,409 out: AfterCrashServiceImpl01: loading IOR "RecoveryCoordinator_231605477_0_0"
> 2013-10-11 20:34:40,425 err: 1536 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:4711 (25a226eb)
> 2013-10-11 20:34:40,435 err: 1547 [RequestProcessor-5] ERROR jacorb.orb - no adapter activator exists for RcvCo-RecCoService_cersei.buildnet.ncl.jboss.comrecovery_coordinator
> 2013-10-11 20:34:40,461 err: 1573 [RequestProcessor-5] INFO jacorb.orb.iiop - Connected to 172.17.131.34:4711 from local port 54289
> 2013-10-11 20:34:40,612 err: AfterCrashServiceImpl01.check_oper [O0.R0]: replay_completion returned: org.omg.CosTransactions.Status@b93c476
> 2013-10-11 20:35:00,640 err: AfterCrashServiceImpl01.get_resource_trace [O0.R0]: ResourceTraceNone
> 2013-10-11 20:35:00,646 err: 21758 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:55790
> {code}
> task0_output.txt
> {code}
> performing command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/task0-coverage.ec -DportOffsetId=6 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/task0 -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.Utils.RemoveServerIORStore value_1 value_2 value_3
> 2013-10-11 20:35:00,832 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
> 2013-10-11 20:35:00,832 err: at java.io.FileInputStream.open(Native Method)
> 2013-10-11 20:35:00,833 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
> 2013-10-11 20:35:00,833 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
> 2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
> 2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
> 2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
> 2013-10-11 20:35:00,834 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
> 2013-10-11 20:35:00,834 err: at java.io.FileInputStream.open(Native Method)
> 2013-10-11 20:35:00,834 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
> 2013-10-11 20:35:00,834 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
> 2013-10-11 20:35:00,834 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
> 2013-10-11 20:35:00,834 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
> 2013-10-11 20:35:00,835 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
> 2013-10-11 20:35:00,835 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
> 2013-10-11 20:35:00,835 err: at java.io.FileInputStream.open(Native Method)
> 2013-10-11 20:35:00,835 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
> 2013-10-11 20:35:00,835 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
> 2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
> 2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
> 2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
> 2013-10-11 20:35:00,836 out: Passed
> {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
11 years, 2 months
[JBoss JIRA] (JBTM-1978) QA test suite failure: CrashRecovery05_1_Test01
by Gytis Trikleris (JIRA)
Gytis Trikleris created JBTM-1978:
-------------------------------------
Summary: QA test suite failure: CrashRecovery05_1_Test01
Key: JBTM-1978
URL: https://issues.jboss.org/browse/JBTM-1978
Project: JBoss Transaction Manager
Issue Type: Feature Request
Security Level: Public (Everyone can see)
Components: Testing
Reporter: Gytis Trikleris
Assignee: Gytis Trikleris
Priority: Minor
Fix For: 5.0.0.Final
http://172.17.131.2/view/Narayana+BlackTie/job/narayana/303/TESTS=QA_JTS_...
client0_output.txt:
{code}
starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client0-coverage.ec -DportOffsetId=3 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01b value_1
2013-10-11 20:34:34,355 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
2013-10-11 20:34:34,355 err: log4j:WARN Please initialize the log4j system properly.
2013-10-11 20:34:34,421 err: SLF4J: Class path contains multiple SLF4J bindings.
2013-10-11 20:34:34,421 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:34,421 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:34,426 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2013-10-11 20:34:34,439 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:34,440 err: 11 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:34,449 err: 20 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:34,466 err: 37 [main] INFO jacorb.orb.print_version -
2013-10-11 20:34:34,466 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:34,466 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
2013-10-11 20:34:34,466 err: (C) The JacORB project 29-Jul-2009
2013-10-11 20:34:34,466 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:34,538 err: 109 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
2013-10-11 20:34:34,585 err: 156 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
2013-10-11 20:34:34,784 err: 355 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:34,784 err: 355 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:34,786 err: 357 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:34,786 err: 357 [main] INFO jacorb.orb.singleton - created ORBSingleton
2013-10-11 20:34:34,877 err: 448 [main] INFO jacorb.poa - oid:
2013-10-11 20:34:34,878 err: 00 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
2013-10-11 20:34:34,878 err: object is activated
2013-10-11 20:34:34,880 err: 451 [main] INFO jacorb.poa - Using server ID (3870081024) for transient POA
2013-10-11 20:34:34,989 err: 560 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
2013-10-11 20:34:35,111 err: 682 [main] INFO jacorb.poa - oid:
2013-10-11 20:34:35,111 err: 01 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
2013-10-11 20:34:35,111 err: object is activated
2013-10-11 20:34:35,114 err: 685 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:42337 (e33ad7)
2013-10-11 20:34:35,153 err: 724 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:42337 from local port 52671
2013-10-11 20:34:35,298 err: 869 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54297
2013-10-11 20:34:35,326 err: 896 [RequestProcessor-4] INFO jacorb.poa - oid:
2013-10-11 20:34:35,326 err: 02 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
2013-10-11 20:34:35,326 err: object is activated
2013-10-11 20:34:35,374 err: 945 [RequestProcessor-4] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:42337 (e33ad7)
2013-10-11 20:34:35,754 err: 1325 [ClientMessageReceptor1] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:42337 closed.
2013-10-11 20:34:35,754 err: 1325 [ClientMessageReceptor1] WARN jacorb.giop.conn - Abnormal connection termination. Lost 1 outstanding replie(s)!
2013-10-11 20:34:35,757 out: 2013-10-11 20:34:35,757 [main] WARN com.arjuna.ats.arjuna - ARJUNA012084: One-phase commit of action 0:ffffac118322:9684:525852ca:3 received heuristic decision: TwoPhaseOutcome.HEURISTIC_HAZARD
2013-10-11 20:34:35,768 err: 1339 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54297
2013-10-11 20:34:35,810 err: 1381 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
2013-10-11 20:34:36,311 err: 1882 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
2013-10-11 20:34:36,812 err: 2383 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
2013-10-11 20:34:37,313 err: 2883 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
2013-10-11 20:34:37,813 err: 3384 [main] INFO jacorb.orb.iiop - Retrying to connect to 172.17.131.34:42337
2013-10-11 20:34:38,328 out: 2013-10-11 20:34:38,316 [main] WARN com.arjuna.ats.jts - ARJUNA022230: ResourceRecord.forgetHeuristic caught exception
2013-10-11 20:34:38,328 out: org.omg.CORBA.TRANSIENT: Retries exceeded, couldn't reconnect to 172.17.131.34:42337 vmcid: 0x0 minor code: 0 completed: No
2013-10-11 20:34:38,328 out: at org.jacorb.orb.iiop.ClientIIOPConnection.connect(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.GIOPConnection.sendMessage(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.GIOPConnection.sendRequest(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.ClientConnection.sendRequest(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.giop.ClientConnection.sendRequest(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.is_a(Unknown Source)
2013-10-11 20:34:38,328 out: at org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:130)
2013-10-11 20:34:38,328 out: at org.omg.CosTransactions.TransactionalObjectHelper.narrow(TransactionalObjectHelper.java:57)
2013-10-11 20:34:38,328 out: at com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionClientRequestInterceptorImpl.send_request(InterpositionClientRequestInterceptorImpl.java:141)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.invoke(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.AbstractInterceptorIterator.iterate(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.iterate(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.ClientInterceptorHandler.invokeInterceptors(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.ClientInterceptorHandler.handle_send_request(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
2013-10-11 20:34:38,328 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
2013-10-11 20:34:38,328 out: at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:475)
2013-10-11 20:34:38,328 out: at org.omg.CosTransactions._ResourceStub.forget(_ResourceStub.java:205)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.resources.ResourceRecord.forgetHeuristic(ResourceRecord.java:569)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.doForget(BasicAction.java:3510)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.forgetHeuristics(BasicAction.java:1329)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.forgetHeuristics(ArjunaTransactionImple.java:1365)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2429)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.coordinator.ArjunaTransactionImple.commit(ArjunaTransactionImple.java:375)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.ControlWrapper.commit(ControlWrapper.java:243)
2013-10-11 20:34:38,329 out: at com.arjuna.ats.internal.jts.orbspecific.CurrentImple.commit(CurrentImple.java:247)
2013-10-11 20:34:38,329 out: at org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01b.main(Client01b.java:93)
2013-10-11 20:34:38,371 err: 3941 [main] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
2013-10-11 20:34:38,371 err: 3942 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54304
2013-10-11 20:34:38,372 err: 3943 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54304
2013-10-11 20:34:38,374 err: 3945 [AOM_RemovalThread] INFO jacorb.poa - oid:
2013-10-11 20:34:38,374 err: 01 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
2013-10-11 20:34:38,374 err: object is deactivated
2013-10-11 20:34:38,375 out: Passed
2013-10-11 20:34:38,375 err: 3946 [AOM_RemovalThread] INFO jacorb.poa - oid:
2013-10-11 20:34:38,375 err: 02 18 06 33 00 42 25 11 01 0F 11 ...3.B%....
2013-10-11 20:34:38,375 err: object is deactivated
2013-10-11 20:34:38,378 err: 3949 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
2013-10-11 20:34:38,378 err: 3949 [main] INFO jacorb.orb - prepare ORB for shutdown...
2013-10-11 20:34:38,378 err: 3949 [main] INFO jacorb.orb - ORB going down...
2013-10-11 20:34:38,379 err: 3950 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54304
2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:54307 closed.
2013-10-11 20:34:38,382 err: 3952 [main] INFO jacorb.orb - ORB shutdown complete
2013-10-11 20:34:38,382 err: 3952 [ClientMessageReceptor0] INFO jacorb.giop.conn - Received CloseConnection on ClientGIOPConnection to 172.17.131.34:54307 (b8872d0)
2013-10-11 20:34:38,382 err: 3952 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
2013-10-11 20:34:38,382 err: 3953 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:54304
2013-10-11 20:34:38,382 err: 3953 [ServerMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
2013-10-11 20:34:38,382 err: 3953 [ClientMessageReceptor1] INFO jacorb.util.tpool - ConsumerTie exited
{code}
client1_output.txt
{code}
starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client1-coverage.ec -DportOffsetId=3 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients1.Client01a value_2
2013-10-11 20:34:39,769 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
2013-10-11 20:34:39,769 err: log4j:WARN Please initialize the log4j system properly.
2013-10-11 20:34:39,834 err: SLF4J: Class path contains multiple SLF4J bindings.
2013-10-11 20:34:39,835 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:39,835 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:39,835 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2013-10-11 20:34:39,848 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:39,851 err: 13 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:39,859 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:39,874 err: 36 [main] INFO jacorb.orb.print_version -
2013-10-11 20:34:39,874 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:39,874 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
2013-10-11 20:34:39,875 err: (C) The JacORB project 29-Jul-2009
2013-10-11 20:34:39,875 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:39,947 err: 108 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
2013-10-11 20:34:39,993 err: 155 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
2013-10-11 20:34:40,192 err: 354 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:40,192 err: 354 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:40,194 err: 356 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:40,197 err: 359 [main] INFO jacorb.orb.singleton - created ORBSingleton
2013-10-11 20:34:40,283 err: 445 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:44860 (512e3872)
2013-10-11 20:34:40,307 err: 469 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.34:44860 from local port 55790
2013-10-11 20:34:40,629 out: Using timeout delay factor of 2
2013-10-11 20:35:00,641 out: Failed
2013-10-11 20:35:00,642 err: 20804 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
2013-10-11 20:35:00,642 err: 20804 [main] INFO jacorb.orb - prepare ORB for shutdown...
2013-10-11 20:35:00,642 err: 20804 [main] INFO jacorb.orb - ORB going down...
2013-10-11 20:35:00,645 err: 20805 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.34:44860 closed.
2013-10-11 20:35:00,645 err: 20805 [main] INFO jacorb.orb - ORB shutdown complete
2013-10-11 20:35:00,645 err: 20806 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
2013-10-11 20:35:00,647 err: 20809 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
{code}
server0_output.txt
{code}
starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server0-coverage.ec -DportOffsetId=1 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server0 -DRecoveryEnvironmentBean.recoveryListener=true com.arjuna.ats.arjuna.recovery.RecoveryManager -test
2013-10-11 20:34:32,252 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
2013-10-11 20:34:32,252 err: log4j:WARN Please initialize the log4j system properly.
2013-10-11 20:34:32,355 err: SLF4J: Class path contains multiple SLF4J bindings.
2013-10-11 20:34:32,355 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:32,355 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:32,356 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2013-10-11 20:34:32,361 err: 3 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:32,361 err: 3 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:32,363 err: 5 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:32,378 err: 20 [main] INFO jacorb.orb.print_version -
2013-10-11 20:34:32,378 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:32,378 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
2013-10-11 20:34:32,378 err: (C) The JacORB project 29-Jul-2009
2013-10-11 20:34:32,378 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:32,449 err: 91 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
2013-10-11 20:34:32,495 err: 137 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
2013-10-11 20:34:32,722 err: 364 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:32,722 err: 364 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:32,724 err: 366 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:32,727 err: 369 [main] INFO jacorb.orb.singleton - created ORBSingleton
2013-10-11 20:34:32,866 err: 508 [Thread-3] INFO jacorb.orb - ORB run
2013-10-11 20:34:32,962 out: Ready
2013-10-11 20:34:40,464 err: 8104 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:54289
2013-10-11 20:34:40,575 err: 8217 [RequestProcessor-4] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (2451c70d)
2013-10-11 20:34:40,587 err: 8229 [RequestProcessor-4] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54307
2013-10-11 20:34:40,589 err: 8230 [ClientMessageReceptor0] WARN jacorb.orb.giop - Received a request on a non-bidir connection
2013-10-11 20:34:40,605 out: 2013-10-11 20:34:40,595 [RequestProcessor-4] WARN com.arjuna.ats.jts - ARJUNA022139: CORBA exception on trying to contact original process
2013-10-11 20:34:40,606 out: org.omg.CORBA.INV_POLICY: Server-side Exception: null vmcid: 0x0 minor code: 0 completed: No
2013-10-11 20:34:40,606 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2013-10-11 20:34:40,606 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
2013-10-11 20:34:40,606 out: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2013-10-11 20:34:40,606 out: at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.SystemExceptionHelper.read(Unknown Source)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.ReplyReceiver.getReply(Unknown Source)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.Delegate.is_a(Unknown Source)
2013-10-11 20:34:40,606 out: at org.omg.CORBA.portable.ObjectImpl._is_a(ObjectImpl.java:130)
2013-10-11 20:34:40,606 out: at org.omg.CosTransactions.TransactionalObjectHelper.narrow(TransactionalObjectHelper.java:57)
2013-10-11 20:34:40,606 out: at com.arjuna.ats.jts.orbspecific.jacorb.interceptors.interposition.InterpositionClientRequestInterceptorImpl.send_request(InterpositionClientRequestInterceptorImpl.java:141)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.invoke(Unknown Source)
2013-10-11 20:34:40,606 out: at org.jacorb.orb.portableInterceptor.AbstractInterceptorIterator.iterate(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.orb.portableInterceptor.ClientInterceptorIterator.iterate(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.orb.ClientInterceptorHandler.invokeInterceptors(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.orb.ClientInterceptorHandler.handle_send_request(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.orb.Delegate.invoke_internal(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.orb.Delegate.invoke(Unknown Source)
2013-10-11 20:34:40,607 out: at org.omg.CORBA.portable.ObjectImpl._invoke(ObjectImpl.java:475)
2013-10-11 20:34:40,607 out: at com.arjuna.ArjunaOTS._ArjunaFactoryStub.getCurrentStatus(_ArjunaFactoryStub.java:31)
2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.getStatus(StatusChecker.java:176)
2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.checkOriginalStatus(StatusChecker.java:144)
2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.recovery.contact.StatusChecker.get_current_status(StatusChecker.java:110)
2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.recovery.recoverycoordinators.GenericRecoveryCoordinator.get_status(GenericRecoveryCoordinator.java:328)
2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.recovery.recoverycoordinators.GenericRecoveryCoordinator.replay_completion(GenericRecoveryCoordinator.java:145)
2013-10-11 20:34:40,607 out: at com.arjuna.ats.internal.jts.orbspecific.jacorb.recoverycoordinators.JacOrbRCDefaultServant.replay_completion(JacOrbRCDefaultServant.java:90)
2013-10-11 20:34:40,607 out: at org.omg.CosTransactions.RecoveryCoordinatorPOA._invoke(RecoveryCoordinatorPOA.java:44)
2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.process(Unknown Source)
2013-10-11 20:34:40,607 out: at org.jacorb.poa.RequestProcessor.run(Unknown Source)
{code}
server1_output.txt
{code}
starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server1-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Servers.Server01 value_3 value_1
2013-10-11 20:34:33,392 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
2013-10-11 20:34:33,392 err: log4j:WARN Please initialize the log4j system properly.
2013-10-11 20:34:33,458 err: SLF4J: Class path contains multiple SLF4J bindings.
2013-10-11 20:34:33,458 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:33,458 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:33,458 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2013-10-11 20:34:33,470 err: 9 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:33,473 err: 12 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:33,482 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:33,498 err: 37 [main] INFO jacorb.orb.print_version -
2013-10-11 20:34:33,498 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:33,498 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
2013-10-11 20:34:33,498 err: (C) The JacORB project 29-Jul-2009
2013-10-11 20:34:33,498 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:33,575 err: 114 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
2013-10-11 20:34:33,622 err: 161 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
2013-10-11 20:34:33,803 out: BeforeCrashServiceImpl01(231605477, 0)
2013-10-11 20:34:33,804 err: 343 [main] INFO jacorb.poa - oid:
2013-10-11 20:34:33,804 err: 00 18 06 33 00 42 14 0E 17 4D ...3.B...M
2013-10-11 20:34:33,804 err: object is activated
2013-10-11 20:34:33,810 err: 348 [main] INFO jacorb.poa - Using server ID (3589635481) for transient POA
2013-10-11 20:34:33,850 err: 389 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:33,850 err: 389 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:33,852 err: 391 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:33,852 err: 391 [main] INFO jacorb.orb.singleton - created ORBSingleton
2013-10-11 20:34:33,914 err: 453 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:42337 (59966240)
2013-10-11 20:34:33,925 out: Ready
2013-10-11 20:34:33,926 err: 465 [main] INFO jacorb.orb - ORB run
2013-10-11 20:34:35,151 err: 1690 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:52671
2013-10-11 20:34:35,234 err: 1773 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:54307 (d13e8c7)
2013-10-11 20:34:35,271 err: 1810 [RequestProcessor-5] INFO jacorb.poa - oid:
2013-10-11 20:34:35,271 err: 01 18 06 33 00 42 14 0E 17 4D ...3.B...M
2013-10-11 20:34:35,271 err: object is activated
2013-10-11 20:34:35,273 err: 1811 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:42337 (59966240)
2013-10-11 20:34:35,301 err: 1840 [RequestProcessor-5] INFO jacorb.orb.iiop - Connected to 172.17.131.34:54307 from local port 54297
2013-10-11 20:34:35,333 err: 1870 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:54307 (d13e8c7)
2013-10-11 20:34:35,389 out: BeforeCrashServiceImpl01: storing IOR "RecoveryCoordinator_231605477_0_0"
2013-10-11 20:34:35,428 err: BeforeCrashServiceImpl01.is_correct: true
2013-10-11 20:34:35,434 err: ResourceImpl01.commit_one_phase [O0.R0]: Crash
{code}
server2_output.txt
{code}
starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server2-coverage.ec -DportOffsetId=2 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/server -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Servers.Server02 value_3 value_2
2013-10-11 20:34:38,819 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
2013-10-11 20:34:38,820 err: log4j:WARN Please initialize the log4j system properly.
2013-10-11 20:34:38,885 err: SLF4J: Class path contains multiple SLF4J bindings.
2013-10-11 20:34:38,885 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:38,885 err: SLF4J: Found binding in [jar:file:/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
2013-10-11 20:34:38,885 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
2013-10-11 20:34:38,898 err: 10 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:38,901 err: 13 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:38,909 err: 21 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:38,925 err: 37 [main] INFO jacorb.orb.print_version -
2013-10-11 20:34:38,926 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:38,926 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
2013-10-11 20:34:38,926 err: (C) The JacORB project 29-Jul-2009
2013-10-11 20:34:38,926 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2013-10-11 20:34:39,001 err: 112 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
2013-10-11 20:34:39,045 err: 157 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
2013-10-11 20:34:39,228 out: AfterCrashServiceImpl01(231605477, 0)
2013-10-11 20:34:39,232 err: 344 [main] INFO jacorb.poa - oid:
2013-10-11 20:34:39,233 err: 00 18 06 33 00 43 2C 4B 21 2D ...3.C,K!-
2013-10-11 20:34:39,233 err: object is activated
2013-10-11 20:34:39,235 err: 347 [main] INFO jacorb.poa - Using server ID (1830494895) for transient POA
2013-10-11 20:34:39,275 err: 387 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
2013-10-11 20:34:39,275 err: 387 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
2013-10-11 20:34:39,277 err: 389 [main] INFO jacorb.config - configuration jacorb loaded from classpath
2013-10-11 20:34:39,279 err: 391 [main] INFO jacorb.orb.singleton - created ORBSingleton
2013-10-11 20:34:39,337 err: 449 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:44860 (5c2f4f8b)
2013-10-11 20:34:39,356 out: Ready
2013-10-11 20:34:39,357 err: 469 [main] INFO jacorb.orb - ORB run
2013-10-11 20:34:40,306 err: 1418 [ServerSocketListener] INFO jacorb.orb.iiop - Opened new server-side TCP/IP transport to 172.17.131.34:55790
2013-10-11 20:34:40,401 err: 1513 [RequestProcessor-5] INFO jacorb.poa - oid:
2013-10-11 20:34:40,401 err: 01 18 06 33 00 43 2C 4B 21 2D ...3.C,K!-
2013-10-11 20:34:40,401 err: object is activated
2013-10-11 20:34:40,404 err: 1516 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: found ClientGIOPConnection to 172.17.131.34:44860 (5c2f4f8b)
2013-10-11 20:34:40,409 out: AfterCrashServiceImpl01: loading IOR "RecoveryCoordinator_231605477_0_0"
2013-10-11 20:34:40,425 err: 1536 [RequestProcessor-5] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.34:4711 (25a226eb)
2013-10-11 20:34:40,435 err: 1547 [RequestProcessor-5] ERROR jacorb.orb - no adapter activator exists for RcvCo-RecCoService_cersei.buildnet.ncl.jboss.comrecovery_coordinator
2013-10-11 20:34:40,461 err: 1573 [RequestProcessor-5] INFO jacorb.orb.iiop - Connected to 172.17.131.34:4711 from local port 54289
2013-10-11 20:34:40,612 err: AfterCrashServiceImpl01.check_oper [O0.R0]: replay_completion returned: org.omg.CosTransactions.Status@b93c476
2013-10-11 20:35:00,640 err: AfterCrashServiceImpl01.get_resource_trace [O0.R0]: ResourceTraceNone
2013-10-11 20:35:00,646 err: 21758 [ServerMessageReceptor0] INFO jacorb.orb.iiop - Closed server-side transport to 172.17.131.34:55790
{code}
task0_output.txt
{code}
performing command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.Final-SNAPSHOT/etc/:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.Final-SNAPSHOT/jacorb/etc/:ext/fscontext.jar:ext/providerutil.jar:ext/jboss-profiler-jvmti.jar:ext/jboss-logging-spi.jar:tests/build/classes/:dbdrivers/selected_dbdriver/*:dbdrivers/DB2_v9.7/db2jcc.jar:dbdrivers/jConnect-6_0/classes/jconn3.jar:dbdrivers/mssql2005_sqljdbc_2.0/enu/sqljdbc4.jar:dbdrivers/mysql-connector-java-5.1.8-bin.jar:dbdrivers/oracle_10_2_0_4/ojdbc14.jar:dbdrivers/postgresql-8.3-605.jdbc4.jar:ext/netty.jar -Dqa.debug=true -Djava.naming.provider.url=file:///tmp -Djava.naming.factory.initial=com.sun.jndi.fscontext.RefFSContextFactory -Dperformanceprofilestore.dir=config/perf_profiles/ -Djdbcprofilestore.dir=config/jdbc_profiles -Dmemorytestprofilestore.dir=config/memory_profiles/ -Dots.server.bindname=value_1 -DCoordinatorEnvironmentBean.maintainHeuristics=NO -DRecoveryEnvironmentBean.recoveryBackoffPeriod=5 -DCoreEnvironmentBean.timeoutFactor=2 -DCoordinatorEnvironmentBean.defaultTimeout=240 -DCoordinatorEnvironmentBean.dynamic1PC=false -Demma.coverage.out.file=./testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/task0-coverage.ec -DportOffsetId=6 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_1/CrashRecovery05_1_Test01/task0 -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.Utils.RemoveServerIORStore value_1 value_2 value_3
2013-10-11 20:35:00,832 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
2013-10-11 20:35:00,832 err: at java.io.FileInputStream.open(Native Method)
2013-10-11 20:35:00,833 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
2013-10-11 20:35:00,833 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
2013-10-11 20:35:00,833 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
2013-10-11 20:35:00,834 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
2013-10-11 20:35:00,834 err: at java.io.FileInputStream.open(Native Method)
2013-10-11 20:35:00,834 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
2013-10-11 20:35:00,834 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
2013-10-11 20:35:00,834 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
2013-10-11 20:35:00,834 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
2013-10-11 20:35:00,835 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
2013-10-11 20:35:00,835 err: java.io.FileNotFoundException: ServerIORs (No such file or directory)
2013-10-11 20:35:00,835 err: at java.io.FileInputStream.open(Native Method)
2013-10-11 20:35:00,835 err: at java.io.FileInputStream.<init>(FileInputStream.java:138)
2013-10-11 20:35:00,835 err: at java.io.FileInputStream.<init>(FileInputStream.java:97)
2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.FileServerIORStore.removeIOR(FileServerIORStore.java:69)
2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.ServerIORStore.removeIOR(ServerIORStore.java:47)
2013-10-11 20:35:00,836 err: at org.jboss.jbossts.qa.Utils.RemoveServerIORStore.main(RemoveServerIORStore.java:42)
2013-10-11 20:35:00,836 out: Passed
{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
11 years, 2 months
[JBoss JIRA] (JBTM-1494) Produce performance figures to show performance improvements of Compensations over ACID
by Paul Robinson (JIRA)
[ https://issues.jboss.org/browse/JBTM-1494?page=com.atlassian.jira.plugin.... ]
Paul Robinson commented on JBTM-1494:
-------------------------------------
I don't think "performance" is the right term to use here. I think I really mean "scalability".
I was thinking about taking an example where a service (backed by a DB) is part of a distributed transaction. In the ACID case, the throughput would be limited due to the locking of contended data. In the compensations case, the locking window would be reduced and (hopefully) the throughput would increase.
This issue fell under the remit of showing the community why they would chose to use compensating-transactions.
> Produce performance figures to show performance improvements of Compensations over ACID
> ---------------------------------------------------------------------------------------
>
> Key: JBTM-1494
> URL: https://issues.jboss.org/browse/JBTM-1494
> Project: JBoss Transaction Manager
> Issue Type: Task
> Security Level: Public(Everyone can see)
> Components: Performance Testing, XTS
> Reporter: Paul Robinson
> Assignee: Paul Robinson
> Fix For: 6.0.0.Final
>
>
> As part of evangelising compensations, it would be useful to show performance comparisons for different classes of applications.
> Ideally, these results should show that that, for certain classes of applications, the performance of a compensation-based transaction is a lot better than for an ACID transaction.
--
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
11 years, 2 months
[JBoss JIRA] (JBTM-659) Support ipv6
by Mark Little (JIRA)
[ https://issues.jboss.org/browse/JBTM-659?page=com.atlassian.jira.plugin.s... ]
Mark Little commented on JBTM-659:
----------------------------------
Can we mark this as complete?
> Support ipv6
> ------------
>
> Key: JBTM-659
> URL: https://issues.jboss.org/browse/JBTM-659
> Project: JBoss Transaction Manager
> Issue Type: Feature Request
> Security Level: Public(Everyone can see)
> Components: Testing
> Affects Versions: 4.16.2
> Reporter: Jonathan Halliday
> Assignee: Michael Musgrove
> Fix For: 4.6.1.CP14
>
>
> The changes to Uid in JBTM-522 pave the way for full IPv6 support in the codebase. Although ad-hoc testing gives good results, we really need to modify the test setup and servers to ensure a reasonable subset of tests are run on IPv6.
--
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
11 years, 2 months