[JBoss JIRA] (JBTM-1844) CrashRecovery05_2_Test028 failed
by Michael Musgrove (JIRA)
[ https://issues.jboss.org/browse/JBTM-1844?page=com.atlassian.jira.plugin.... ]
Michael Musgrove edited comment on JBTM-1844 at 8/5/13 5:37 AM:
----------------------------------------------------------------
The root cause seems similar to the linked JIRA
was (Author: mmusgrov):
The root cause seems similar
> CrashRecovery05_2_Test028 failed
> --------------------------------
>
> Key: JBTM-1844
> URL: https://issues.jboss.org/browse/JBTM-1844
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: JTS, Testing
> Reporter: Gytis Trikleris
> Assignee: Michael Musgrove
> Priority: Minor
> Fix For: 5.0.0.M4
>
> Attachments: CrashRecovery05_2_Test028.zip, TEST-org.jboss.jbossts.qa.junit.testgroup.TestGroup_crashrecovery05_2.txt
>
>
> http://172.17.131.2/view/Narayana+BlackTie/job/narayana/192/TESTS=QA_JTS_...
> {code}
> starting command: /usr/local/jdk1.7.0_17/bin/java -classpath dist/narayana-full-5.0.0.M4-SNAPSHOT/etc/:dist/narayana-full-5.0.0.M4-SNAPSHOT/lib/*:dist/narayana-full-5.0.0.M4-SNAPSHOT/lib/ext/*:dist/narayana-full-5.0.0.M4-SNAPSHOT/jacorb/lib/*:dist/narayana-full-5.0.0.M4-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_2/CrashRecovery05_2_Test028/client1-coverage.ec -DportOffsetId=4 -DObjectStoreBaseDir=/home/hudson/workspace/narayana/TESTS/QA_JTS_JACORB/jdk/jdk7.latest/label/linux/qa/testoutput/crashrecovery05_2/CrashRecovery05_2_Test028/client -DRecoveryEnvironmentBean.recoveryListener=true org.jboss.jbossts.qa.CrashRecovery05Clients2.Client03a value_3 value_4
> 2013-07-13 09:09:35,696 err: log4j:WARN No appenders could be found for logger (org.jboss.logging).
> 2013-07-13 09:09:35,696 err: log4j:WARN Please initialize the log4j system properly.
> 2013-07-13 09:09:35,778 err: SLF4J: Class path contains multiple SLF4J bindings.
> 2013-07-13 09:09:35,778 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.M4-SNAPSHOT/lib/ext/slf4j-simple.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-07-13 09:09:35,779 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.M4-SNAPSHOT/jacorb/lib/slf4j-jdk14-1.5.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
> 2013-07-13 09:09:35,779 err: SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
> 2013-07-13 09:09:35,787 err: 5 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-07-13 09:09:35,788 err: 5 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-07-13 09:09:35,794 err: 12 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-07-13 09:09:35,827 err: 45 [main] INFO jacorb.orb.print_version -
> 2013-07-13 09:09:35,827 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-07-13 09:09:35,827 err: JacORB V 2.3.1 (JBoss patch01), www.jacorb.org
> 2013-07-13 09:09:35,827 err: (C) The JacORB project 29-Jul-2009
> 2013-07-13 09:09:35,827 err: ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
> 2013-07-13 09:09:35,969 err: 187 [main] INFO jacorb.orb - Property "jacorb.hashtable_class" is set to: java.util.Hashtable
> 2013-07-13 09:09:35,986 err: 204 [main] INFO jacorb.orb.interceptors - InterceptorManager started with 1 Server Interceptors, 1 Client Interceptors and 1 IOR Interceptors
> 2013-07-13 09:09:36,156 err: 374 [main] INFO jacorb.config - base configuration loaded from classpath orb.properties
> 2013-07-13 09:09:36,156 err: 374 [main] WARN jacorb.config - File e:/JacORB_CONFIG_FEATURE/etc/jacorb.properties for configuration jacorb not found
> 2013-07-13 09:09:36,157 err: 375 [main] INFO jacorb.config - configuration jacorb loaded from classpath
> 2013-07-13 09:09:36,158 err: 376 [main] INFO jacorb.orb.singleton - created ORBSingleton
> 2013-07-13 09:09:36,270 err: 488 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.36:36476 (3ec35868)
> 2013-07-13 09:09:36,299 err: 517 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.36:36476 from local port 43572
> 2013-07-13 09:09:36,378 err: 596 [main] INFO jacorb.orb.giop - ClientConnectionManager: created new ClientGIOPConnection to 172.17.131.36:40980 (62565cf8)
> 2013-07-13 09:09:36,384 err: 602 [main] INFO jacorb.orb.iiop - Connected to 172.17.131.36:40980 from local port 55512
> 2013-07-13 09:09:38,189 out: Gonna fail1
> 2013-07-13 09:09:38,190 out: Gonna fail2
> 2013-07-13 09:09:38,190 out: Gonna fail3
> 2013-07-13 09:09:38,190 out: Gonna fail4
> 2013-07-13 09:09:38,190 out: Using timeout delay factor of 2
> 2013-07-13 09:09:58,219 out: Gonna fail5: ResourceTraceNone
> 2013-07-13 09:09:58,219 out: Gonna fail6
> 2013-07-13 09:09:58,219 out: Failed
> 2013-07-13 09:09:58,220 err: 22438 [POADestructor] INFO jacorb.poa - POA RootPOA destroyed
> 2013-07-13 09:09:58,220 err: 22438 [main] INFO jacorb.orb - prepare ORB for shutdown...
> 2013-07-13 09:09:58,220 err: 22438 [main] INFO jacorb.orb - ORB going down...
> 2013-07-13 09:09:58,227 err: 22439 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.36:36476 closed.
> 2013-07-13 09:09:58,227 err: 22439 [main] INFO jacorb.orb.iiop - Client-side TCP transport to 172.17.131.36:40980 closed.
> 2013-07-13 09:09:58,227 err: 22439 [main] INFO jacorb.orb - ORB shutdown complete
> 2013-07-13 09:09:58,227 err: 22445 [ClientMessageReceptor0] INFO jacorb.util.tpool - ConsumerTie exited
> 2013-07-13 09:09:58,227 err: 22445 [ClientMessageReceptor1] INFO jacorb.util.tpool - ConsumerTie exited
> 2013-07-13 09:09:58,227 err: 22445 [ServerSocketListener] INFO jacorb.orb.iiop - Listener exited
> {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
12 years, 8 months
[JBoss JIRA] (JBTM-1685) QA suite failure: org.jboss.jbossts.qa.junit.testgroup.TestGroup_crashrecovery02_2#Test28
by Michael Musgrove (JIRA)
[ https://issues.jboss.org/browse/JBTM-1685?page=com.atlassian.jira.plugin.... ]
Michael Musgrove commented on JBTM-1685:
----------------------------------------
The following JIRAs seem to be failing for a similar reason:
JBTM-1842
start two serves one crashes in rollback the other in prepare
start a client (wait 10 seconds) and then check that both servers rolled back
when the test fails one of the servers is still waiting for recovery manager to tell it what to do
JBTM-1510
start two servers one proceeds normally the second crashes in commit
start a client (wait 10 seconds) and then check that both servers committed
when the test fails the second server is still waiting for the replay request
JBTM-1844
start two serves both of which crash in rollback
start a client (wait 10 seconds) and then check that both servers rolled back
when the test fails one or both of the servers are waiting for the recovery manager
I have a dedicated job that runs each of these tests hourly (all of which are passing:- but do note that only JBTM-1685 was reproducible in the first instance).
> QA suite failure: org.jboss.jbossts.qa.junit.testgroup.TestGroup_crashrecovery02_2#Test28
> -----------------------------------------------------------------------------------------
>
> Key: JBTM-1685
> URL: https://issues.jboss.org/browse/JBTM-1685
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: Testing
> Reporter: Gytis Trikleris
> Assignee: Michael Musgrove
> Priority: Minor
> Fix For: 5.0.0.M4
>
> Attachments: CrashRecovery02_2_Test28.zip
>
> Original Estimate: 2 days
> Remaining Estimate: 2 days
>
> http://172.17.131.2/view/Narayana+BlackTie/job/narayana-dualstack/99/cons...
--
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
12 years, 8 months
[JBoss JIRA] (JBTM-1685) QA suite failure: org.jboss.jbossts.qa.junit.testgroup.TestGroup_crashrecovery02_2#Test28
by Michael Musgrove (JIRA)
[ https://issues.jboss.org/browse/JBTM-1685?page=com.atlassian.jira.plugin.... ]
Michael Musgrove commented on JBTM-1685:
----------------------------------------
Seems to be timing related. This test starts 2 servers both of which are asked to crash during commit. On restart an external recovery manager is supposed to replay the commit phase. A second client starts and uses CrashRecoveryDelays.java to ensure enough time has elapsed (using method awaitReplayCompletion). [Note CrashRecoveryDelays has 2 approaches, either awaitReplayCompletion or explicitly tell the recovery manager to trigger recovery (method awaitRecovery)].
After the second client has waited for the replay it asks each server what it did. When the test fails the servers are still waiting for the replayCompletion request. I modified the second client to wait longer before asking the servers what they did and the test passes consistently.
> QA suite failure: org.jboss.jbossts.qa.junit.testgroup.TestGroup_crashrecovery02_2#Test28
> -----------------------------------------------------------------------------------------
>
> Key: JBTM-1685
> URL: https://issues.jboss.org/browse/JBTM-1685
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: Testing
> Reporter: Gytis Trikleris
> Assignee: Michael Musgrove
> Priority: Minor
> Fix For: 5.0.0.M4
>
> Attachments: CrashRecovery02_2_Test28.zip
>
> Original Estimate: 2 days
> Remaining Estimate: 2 days
>
> http://172.17.131.2/view/Narayana+BlackTie/job/narayana-dualstack/99/cons...
--
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
12 years, 8 months
[JBoss JIRA] (JBTM-1873) Create quickstart showing new features implemented with @Compensatable
by Gytis Trikleris (JIRA)
Gytis Trikleris created JBTM-1873:
-------------------------------------
Summary: Create quickstart showing new features implemented with @Compensatable
Key: JBTM-1873
URL: https://issues.jboss.org/browse/JBTM-1873
Project: JBoss Transaction Manager
Issue Type: Feature Request
Security Level: Public (Everyone can see)
Components: Demonstrator, TXFramework, XTS
Reporter: Gytis Trikleris
Assignee: Gytis Trikleris
Priority: Critical
Fix For: 5.0.0.M4
Show main features provided by @Compensatable annotation: dontCancelOn, cancelOn, compensation types.
Reuse Paul's quickstart JBTM-1682.
--
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
12 years, 8 months
[JBoss JIRA] (JBTM-1751) TransactionRolledBackException thrown during commit in com.jboss.transaction.txinterop.interop.ATTest
by Amos Feng (JIRA)
[ https://issues.jboss.org/browse/JBTM-1751?page=com.atlassian.jira.plugin.... ]
Amos Feng commented on JBTM-1751:
---------------------------------
It looks like JBTM-1872 has the similar problem.
> TransactionRolledBackException thrown during commit in com.jboss.transaction.txinterop.interop.ATTest
> -----------------------------------------------------------------------------------------------------
>
> Key: JBTM-1751
> URL: https://issues.jboss.org/browse/JBTM-1751
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: XTS
> Reporter: Gytis Trikleris
> Assignee: Amos Feng
> Fix For: 4.17.8, 5.0.0.M4
>
> Original Estimate: 2 hours
> Time Spent: 2 hours
> Remaining Estimate: 1 day
>
> http://172.17.131.2/view/Narayana+BlackTie/job/jbossts-EAP61/1791/console...
> {code}
> [0m[31m22:25:44,948 ERROR [org.jboss.arquillian.protocol.jmx.JMXTestRunner] (pool-1-thread-1) Failed: com.jboss.transaction.txinterop.interop.ATTest.testAT4_1: com.arjuna.wst.TransactionRolledBackException
> at com.arjuna.wst11.stub.CompletionStub.commit(CompletionStub.java:65) [jbossxts-4.17.5.Final-SNAPSHOT.jar:4.17.5.Final-SNAPSHOT]
> at com.jboss.transaction.txinterop.interop.ATTestCase.testAT4_1(ATTestCase.java:281) [classes:]
> at com.jboss.transaction.txinterop.interop.ATTest.testAT4_1(ATTest.java:104) [classes:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) [arquillian-service:]
> at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) [arquillian-service:]
> at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$6$1.invoke(Arquillian.java:270) [arquillian-service:]
> at org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:60) [arquillian-service:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81) [arquillian-service:]
> at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135) [arquillian-service:]
> at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67) [arquillian-service:]
> at org.jboss.arquillian.container.test.impl.execution.ContainerTestExecuter.execute(ContainerTestExecuter.java:38) [arquillian-service:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81) [arquillian-service:]
> at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89) [arquillian-service:]
> at sun.reflect.GeneratedMethodAccessor91.invoke(Unknown Source) [:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88) [arquillian-service:]
> at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75) [arquillian-service:]
> at sun.reflect.GeneratedMethodAccessor90.invoke(Unknown Source) [:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88) [arquillian-service:]
> at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60) [arquillian-service:]
> at sun.reflect.GeneratedMethodAccessor87.invoke(Unknown Source) [:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88) [arquillian-service:]
> at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135) [arquillian-service:]
> at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:111) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$6.evaluate(Arquillian.java:263) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:226) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240) [arquillian-service:]
> at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) [arquillian-service:]
> at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) [arquillian-service:]
> at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) [arquillian-service:]
> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) [arquillian-service:]
> at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) [arquillian-service:]
> at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) [arquillian-service:]
> at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) [arquillian-service:]
> at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199) [arquillian-service:]
> at org.junit.runners.ParentRunner.run(ParentRunner.java:300) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147) [arquillian-service:]
> at org.junit.runner.JUnitCore.run(JUnitCore.java:157) [arquillian-service:]
> at org.junit.runner.JUnitCore.run(JUnitCore.java:136) [arquillian-service:]
> at org.jboss.arquillian.junit.container.JUnitTestRunner.execute(JUnitTestRunner.java:65) [arquillian-service:]
> at org.jboss.arquillian.protocol.jmx.JMXTestRunner.runTestMethodInternal(JMXTestRunner.java:128) [arquillian-service:]
> at org.jboss.arquillian.protocol.jmx.JMXTestRunner.runTestMethod(JMXTestRunner.java:107) [arquillian-service:]
> at org.jboss.as.arquillian.service.ArquillianService$ExtendedJMXTestRunner.runTestMethod(ArquillianService.java:214) [arquillian-service:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(StandardMBeanIntrospector.java:93) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(StandardMBeanIntrospector.java:27) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.MBeanIntrospector.invokeM(MBeanIntrospector.java:208) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.PerInterface.invoke(PerInterface.java:120) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.MBeanSupport.invoke(MBeanSupport.java:262) [rt.jar:1.6.0_37]
> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.invoke(DefaultMBeanServerInterceptor.java:836) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.JmxMBeanServer.invoke(JmxMBeanServer.java:761) [rt.jar:1.6.0_37]
> at org.jboss.as.jmx.PluggableMBeanServerImpl$TcclMBeanServer.invoke(PluggableMBeanServerImpl.java:527)
> at org.jboss.as.jmx.PluggableMBeanServerImpl.invoke(PluggableMBeanServerImpl.java:263)
> at org.jboss.remotingjmx.protocol.v2.ServerProxy$InvokeHandler.handle(ServerProxy.java:915)
> at org.jboss.remotingjmx.protocol.v2.ServerCommon$MessageReciever$1.run(ServerCommon.java:152)
> at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_37]
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_37]
> at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_37]
> {code}
> Another errors in the log:
> {code}
> [0m[33m22:26:26,763 WARN [com.arjuna.mw.wstx] (TaskWorker-2) ARJUNA045035: comms timeout attempting to prepare WS-AT participant D0:ffffac118324:4acfdb55:51afacb7:87
> [0m[31m22:26:26,763 ERROR [stderr] (TaskWorker-2) com.arjuna.mw.wsas.exceptions.SystemCommunicationException: com.arjuna.wst.stub.SystemCommunicationException
> [0m[31m22:26:26,763 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.prepare(DurableTwoPhaseCommitParticipant.java:124)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.confirmOnePhase(DurableTwoPhaseCommitParticipant.java:233)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.ParticipantRecord.topLevelOnePhaseCommit(ParticipantRecord.java:429)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2263)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorControl.complete(CoordinatorControl.java:137)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf11.model.twophase.arjunacore.TwoPhaseHLSImple.complete(TwoPhaseHLSImple.java:130)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.activity.ActivityImple.end(ActivityImple.java:293)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.UserActivityImple.end(UserActivityImple.java:261)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorServiceImple.confirm(CoordinatorServiceImple.java:156)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst11.at.participants.CompletionCoordinatorImple.commit(CompletionCoordinatorImple.java:41)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.wst11.messaging.CompletionCoordinatorProcessorImpl.commit(CompletionCoordinatorProcessorImpl.java:84)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl$1.executeTask(CompletionCoordinatorPortTypeImpl.java:58)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.services.framework.task.TaskWorker.run(TaskWorker.java:63)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at java.lang.Thread.run(Thread.java:662)
> {code}
> and:
> {code}
> [0m[33m22:27:03,729 WARN [com.arjuna.mw.wstx] (TaskWorker-2) ARJUNA045035: comms timeout attempting to prepare WS-AT participant D0:ffffac118324:4acfdb55:51afacb7:9c
> [0m[31m22:27:03,730 ERROR [stderr] (TaskWorker-2) com.arjuna.mw.wsas.exceptions.SystemCommunicationException: com.arjuna.wst.stub.SystemCommunicationException
> [0m[31m22:27:03,730 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.prepare(DurableTwoPhaseCommitParticipant.java:124)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.confirmOnePhase(DurableTwoPhaseCommitParticipant.java:233)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.ParticipantRecord.topLevelOnePhaseCommit(ParticipantRecord.java:429)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2263)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorControl.complete(CoordinatorControl.java:137)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf11.model.twophase.arjunacore.TwoPhaseHLSImple.complete(TwoPhaseHLSImple.java:130)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.activity.ActivityImple.end(ActivityImple.java:293)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.UserActivityImple.end(UserActivityImple.java:261)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorServiceImple.confirm(CoordinatorServiceImple.java:156)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst11.at.participants.CompletionCoordinatorImple.commit(CompletionCoordinatorImple.java:41)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.wst11.messaging.CompletionCoordinatorProcessorImpl.commit(CompletionCoordinatorProcessorImpl.java:84)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl$1.executeTask(CompletionCoordinatorPortTypeImpl.java:58)
> [0m[31m22:27:03,734 ERROR [stderr] (TaskWorker-2) at com.arjuna.services.framework.task.TaskWorker.run(TaskWorker.java:63)
> [0m[31m22:27:03,734 ERROR [stderr] (TaskWorker-2) at java.lang.Thread.run(Thread.java:662)
> {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
12 years, 8 months
[JBoss JIRA] (JBTM-1867) Confusing error logged after crash recovery on prepare when hornetq object store used
by RH Bugzilla Integration (JIRA)
[ https://issues.jboss.org/browse/JBTM-1867?page=com.atlassian.jira.plugin.... ]
RH Bugzilla Integration commented on JBTM-1867:
-----------------------------------------------
Ondrej Chaloupka <ochaloup(a)redhat.com> made a comment on [bug 992915|https://bugzilla.redhat.com/show_bug.cgi?id=992915]
There is logged an confusing error after crash recovery happens when server crashed in prepare phase.
14:47:59,697 ERROR [stderr] (RequestProcessor-10) com.arjuna.ats.arjuna.exceptions.ObjectStoreException: java.lang.IllegalStateException: Cannot find add info 3
14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqJournalStore.remove_committed(HornetqJournalStore.java:153)
14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqObjectStoreAdaptor.remove_committed(HornetqObjectStoreAdaptor.java:186)
14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.XAResourceRecord.destroyState(XAResourceRecord.java:1271)
14:47:59,699 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=TestXAResourceRecovered(TestXAResourceCommon(0, null)))
14:47:59,699 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.XAResourceRecord.rollback(XAResourceRecord.java:411)
14:47:59,699 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ArjunaOTS.OTSAbstractRecordPOA._invoke(OTSAbstractRecordPOA.java:213)
14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.invokeOperation(RequestProcessor.java:306)
14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.process(RequestProcessor.java:626)
14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.run(RequestProcessor.java:769)
14:47:59,700 ERROR [stderr] (RequestProcessor-10) Caused by: java.lang.IllegalStateException: Cannot find add info 3
14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalImpl.appendDeleteRecord(JournalImpl.java:904)
14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalBase.appendDeleteRecord(JournalBase.java:163)
14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalImpl.appendDeleteRecord(JournalImpl.java:82)
14:47:59,701 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqJournalStore.remove_committed(HornetqJournalStore.java:151)
14:47:59,701 ERROR [stderr] (RequestProcessor-10) ... 7 more
This happens when hornetq object store is used. This is caused because of the rollback is called on resource two times. This is ok - this matches to OTS spec (https://bugzilla.redhat.com/show_bug.cgi?id=988724).
But after the second call of rollback the TM tries to remove Xid from object store. But as the xid does not exist anymore the hornetq object store implementation throws exception that is logged directly on stderr:
XAResourceRecord.destroyState does e.printStackTrace()
I would propose to put this exception to debug or trace as it does not mean error in fact.
> Confusing error logged after crash recovery on prepare when hornetq object store used
> -------------------------------------------------------------------------------------
>
> Key: JBTM-1867
> URL: https://issues.jboss.org/browse/JBTM-1867
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: JTS
> Affects Versions: 4.17.7, 5.0.0.M3
> Reporter: Ondřej Chaloupka
> Assignee: Michael Musgrove
>
> There is logged an confusing error after crash recovery happens when server crashed in prepare phase.
> {code}
> 14:47:59,697 ERROR [stderr] (RequestProcessor-10) com.arjuna.ats.arjuna.exceptions.ObjectStoreException: java.lang.IllegalStateException: Cannot find add info 3
> 14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqJournalStore.remove_committed(HornetqJournalStore.java:153)
> 14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqObjectStoreAdaptor.remove_committed(HornetqObjectStoreAdaptor.java:186)
> 14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.XAResourceRecord.destroyState(XAResourceRecord.java:1271)
> 14:47:59,699 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=TestXAResourceRecovered(TestXAResourceCommon(0, null)))
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.XAResourceRecord.rollback(XAResourceRecord.java:411)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ArjunaOTS.OTSAbstractRecordPOA._invoke(OTSAbstractRecordPOA.java:213)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.invokeOperation(RequestProcessor.java:306)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.process(RequestProcessor.java:626)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.run(RequestProcessor.java:769)
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) Caused by: java.lang.IllegalStateException: Cannot find add info 3
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalImpl.appendDeleteRecord(JournalImpl.java:904)
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalBase.appendDeleteRecord(JournalBase.java:163)
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalImpl.appendDeleteRecord(JournalImpl.java:82)
> 14:47:59,701 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqJournalStore.remove_committed(HornetqJournalStore.java:151)
> 14:47:59,701 ERROR [stderr] (RequestProcessor-10) ... 7 more
> {code}
> This happens when hornetq object store is used. This is caused because of the rollback is called on resource two times. This is ok - this matches to OTS spec (https://bugzilla.redhat.com/show_bug.cgi?id=988724).
> But after the second call of rollback the TM tries to remove Xid from object store. But as the xid does not exist anymore the hornetq object store implementation throws exception that is logged directly on stderr:
> XAResourceRecord.destroyState does e.printStackTrace()
> I would propose to put this exception to debug or trace as it does not mean error in fact.
--
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
12 years, 8 months
[JBoss JIRA] (JBTM-1867) Confusing error logged after crash recovery on prepare when hornetq object store used
by RH Bugzilla Integration (JIRA)
[ https://issues.jboss.org/browse/JBTM-1867?page=com.atlassian.jira.plugin.... ]
RH Bugzilla Integration updated JBTM-1867:
------------------------------------------
Bugzilla Update: Perform
Bugzilla References: https://bugzilla.redhat.com/show_bug.cgi?id=992915
> Confusing error logged after crash recovery on prepare when hornetq object store used
> -------------------------------------------------------------------------------------
>
> Key: JBTM-1867
> URL: https://issues.jboss.org/browse/JBTM-1867
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: JTS
> Affects Versions: 4.17.7, 5.0.0.M3
> Reporter: Ondřej Chaloupka
> Assignee: Michael Musgrove
>
> There is logged an confusing error after crash recovery happens when server crashed in prepare phase.
> {code}
> 14:47:59,697 ERROR [stderr] (RequestProcessor-10) com.arjuna.ats.arjuna.exceptions.ObjectStoreException: java.lang.IllegalStateException: Cannot find add info 3
> 14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqJournalStore.remove_committed(HornetqJournalStore.java:153)
> 14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqObjectStoreAdaptor.remove_committed(HornetqObjectStoreAdaptor.java:186)
> 14:47:59,698 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.XAResourceRecord.destroyState(XAResourceRecord.java:1271)
> 14:47:59,699 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=TestXAResourceRecovered(TestXAResourceCommon(0, null)))
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.XAResourceRecord.rollback(XAResourceRecord.java:411)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ArjunaOTS.OTSAbstractRecordPOA._invoke(OTSAbstractRecordPOA.java:213)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.invokeOperation(RequestProcessor.java:306)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.process(RequestProcessor.java:626)
> 14:47:59,699 ERROR [stderr] (RequestProcessor-10) at org.jacorb.poa.RequestProcessor.run(RequestProcessor.java:769)
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) Caused by: java.lang.IllegalStateException: Cannot find add info 3
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalImpl.appendDeleteRecord(JournalImpl.java:904)
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalBase.appendDeleteRecord(JournalBase.java:163)
> 14:47:59,700 ERROR [stderr] (RequestProcessor-10) at org.hornetq.core.journal.impl.JournalImpl.appendDeleteRecord(JournalImpl.java:82)
> 14:47:59,701 ERROR [stderr] (RequestProcessor-10) at com.arjuna.ats.internal.arjuna.objectstore.hornetq.HornetqJournalStore.remove_committed(HornetqJournalStore.java:151)
> 14:47:59,701 ERROR [stderr] (RequestProcessor-10) ... 7 more
> {code}
> This happens when hornetq object store is used. This is caused because of the rollback is called on resource two times. This is ok - this matches to OTS spec (https://bugzilla.redhat.com/show_bug.cgi?id=988724).
> But after the second call of rollback the TM tries to remove Xid from object store. But as the xid does not exist anymore the hornetq object store implementation throws exception that is logged directly on stderr:
> XAResourceRecord.destroyState does e.printStackTrace()
> I would propose to put this exception to debug or trace as it does not mean error in fact.
--
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
12 years, 8 months
[JBoss JIRA] (JBTM-1751) TransactionRolledBackException thrown during commit in com.jboss.transaction.txinterop.interop.ATTest
by Paul Robinson (JIRA)
[ https://issues.jboss.org/browse/JBTM-1751?page=com.atlassian.jira.plugin.... ]
Paul Robinson commented on JBTM-1751:
-------------------------------------
Sorry, I read your request and then forgot about it. I'll take a look soon.
> TransactionRolledBackException thrown during commit in com.jboss.transaction.txinterop.interop.ATTest
> -----------------------------------------------------------------------------------------------------
>
> Key: JBTM-1751
> URL: https://issues.jboss.org/browse/JBTM-1751
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: XTS
> Reporter: Gytis Trikleris
> Assignee: Amos Feng
> Fix For: 4.17.8, 5.0.0.M4
>
> Original Estimate: 2 hours
> Time Spent: 2 hours
> Remaining Estimate: 1 day
>
> http://172.17.131.2/view/Narayana+BlackTie/job/jbossts-EAP61/1791/console...
> {code}
> [0m[31m22:25:44,948 ERROR [org.jboss.arquillian.protocol.jmx.JMXTestRunner] (pool-1-thread-1) Failed: com.jboss.transaction.txinterop.interop.ATTest.testAT4_1: com.arjuna.wst.TransactionRolledBackException
> at com.arjuna.wst11.stub.CompletionStub.commit(CompletionStub.java:65) [jbossxts-4.17.5.Final-SNAPSHOT.jar:4.17.5.Final-SNAPSHOT]
> at com.jboss.transaction.txinterop.interop.ATTestCase.testAT4_1(ATTestCase.java:281) [classes:]
> at com.jboss.transaction.txinterop.interop.ATTest.testAT4_1(ATTest.java:104) [classes:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) [arquillian-service:]
> at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) [arquillian-service:]
> at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$6$1.invoke(Arquillian.java:270) [arquillian-service:]
> at org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:60) [arquillian-service:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81) [arquillian-service:]
> at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135) [arquillian-service:]
> at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67) [arquillian-service:]
> at org.jboss.arquillian.container.test.impl.execution.ContainerTestExecuter.execute(ContainerTestExecuter.java:38) [arquillian-service:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81) [arquillian-service:]
> at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:89) [arquillian-service:]
> at sun.reflect.GeneratedMethodAccessor91.invoke(Unknown Source) [:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88) [arquillian-service:]
> at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:75) [arquillian-service:]
> at sun.reflect.GeneratedMethodAccessor90.invoke(Unknown Source) [:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88) [arquillian-service:]
> at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60) [arquillian-service:]
> at sun.reflect.GeneratedMethodAccessor87.invoke(Unknown Source) [:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:90) [arquillian-service:]
> at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88) [arquillian-service:]
> at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135) [arquillian-service:]
> at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:111) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$6.evaluate(Arquillian.java:263) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:226) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:240) [arquillian-service:]
> at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) [arquillian-service:]
> at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) [arquillian-service:]
> at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) [arquillian-service:]
> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) [arquillian-service:]
> at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) [arquillian-service:]
> at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) [arquillian-service:]
> at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) [arquillian-service:]
> at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:185) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:314) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.access$100(Arquillian.java:46) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:199) [arquillian-service:]
> at org.junit.runners.ParentRunner.run(ParentRunner.java:300) [arquillian-service:]
> at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147) [arquillian-service:]
> at org.junit.runner.JUnitCore.run(JUnitCore.java:157) [arquillian-service:]
> at org.junit.runner.JUnitCore.run(JUnitCore.java:136) [arquillian-service:]
> at org.jboss.arquillian.junit.container.JUnitTestRunner.execute(JUnitTestRunner.java:65) [arquillian-service:]
> at org.jboss.arquillian.protocol.jmx.JMXTestRunner.runTestMethodInternal(JMXTestRunner.java:128) [arquillian-service:]
> at org.jboss.arquillian.protocol.jmx.JMXTestRunner.runTestMethod(JMXTestRunner.java:107) [arquillian-service:]
> at org.jboss.as.arquillian.service.ArquillianService$ExtendedJMXTestRunner.runTestMethod(ArquillianService.java:214) [arquillian-service:]
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.6.0_37]
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [rt.jar:1.6.0_37]
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [rt.jar:1.6.0_37]
> at java.lang.reflect.Method.invoke(Method.java:597) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(StandardMBeanIntrospector.java:93) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.StandardMBeanIntrospector.invokeM2(StandardMBeanIntrospector.java:27) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.MBeanIntrospector.invokeM(MBeanIntrospector.java:208) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.PerInterface.invoke(PerInterface.java:120) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.MBeanSupport.invoke(MBeanSupport.java:262) [rt.jar:1.6.0_37]
> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.invoke(DefaultMBeanServerInterceptor.java:836) [rt.jar:1.6.0_37]
> at com.sun.jmx.mbeanserver.JmxMBeanServer.invoke(JmxMBeanServer.java:761) [rt.jar:1.6.0_37]
> at org.jboss.as.jmx.PluggableMBeanServerImpl$TcclMBeanServer.invoke(PluggableMBeanServerImpl.java:527)
> at org.jboss.as.jmx.PluggableMBeanServerImpl.invoke(PluggableMBeanServerImpl.java:263)
> at org.jboss.remotingjmx.protocol.v2.ServerProxy$InvokeHandler.handle(ServerProxy.java:915)
> at org.jboss.remotingjmx.protocol.v2.ServerCommon$MessageReciever$1.run(ServerCommon.java:152)
> at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_37]
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_37]
> at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_37]
> {code}
> Another errors in the log:
> {code}
> [0m[33m22:26:26,763 WARN [com.arjuna.mw.wstx] (TaskWorker-2) ARJUNA045035: comms timeout attempting to prepare WS-AT participant D0:ffffac118324:4acfdb55:51afacb7:87
> [0m[31m22:26:26,763 ERROR [stderr] (TaskWorker-2) com.arjuna.mw.wsas.exceptions.SystemCommunicationException: com.arjuna.wst.stub.SystemCommunicationException
> [0m[31m22:26:26,763 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.prepare(DurableTwoPhaseCommitParticipant.java:124)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.confirmOnePhase(DurableTwoPhaseCommitParticipant.java:233)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.ParticipantRecord.topLevelOnePhaseCommit(ParticipantRecord.java:429)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2263)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
> [0m[31m22:26:26,764 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorControl.complete(CoordinatorControl.java:137)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf11.model.twophase.arjunacore.TwoPhaseHLSImple.complete(TwoPhaseHLSImple.java:130)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.activity.ActivityImple.end(ActivityImple.java:293)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.UserActivityImple.end(UserActivityImple.java:261)
> [0m[31m22:26:26,765 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorServiceImple.confirm(CoordinatorServiceImple.java:156)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst11.at.participants.CompletionCoordinatorImple.commit(CompletionCoordinatorImple.java:41)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.wst11.messaging.CompletionCoordinatorProcessorImpl.commit(CompletionCoordinatorProcessorImpl.java:84)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl$1.executeTask(CompletionCoordinatorPortTypeImpl.java:58)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at com.arjuna.services.framework.task.TaskWorker.run(TaskWorker.java:63)
> [0m[31m22:26:26,766 ERROR [stderr] (TaskWorker-2) at java.lang.Thread.run(Thread.java:662)
> {code}
> and:
> {code}
> [0m[33m22:27:03,729 WARN [com.arjuna.mw.wstx] (TaskWorker-2) ARJUNA045035: comms timeout attempting to prepare WS-AT participant D0:ffffac118324:4acfdb55:51afacb7:9c
> [0m[31m22:27:03,730 ERROR [stderr] (TaskWorker-2) com.arjuna.mw.wsas.exceptions.SystemCommunicationException: com.arjuna.wst.stub.SystemCommunicationException
> [0m[31m22:27:03,730 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.prepare(DurableTwoPhaseCommitParticipant.java:124)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst.at.participants.DurableTwoPhaseCommitParticipant.confirmOnePhase(DurableTwoPhaseCommitParticipant.java:233)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.ParticipantRecord.topLevelOnePhaseCommit(ParticipantRecord.java:429)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.onePhaseCommit(BasicAction.java:2263)
> [0m[31m22:27:03,731 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1475)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorControl.complete(CoordinatorControl.java:137)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf11.model.twophase.arjunacore.TwoPhaseHLSImple.complete(TwoPhaseHLSImple.java:130)
> [0m[31m22:27:03,732 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.activity.ActivityImple.end(ActivityImple.java:293)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wsas.UserActivityImple.end(UserActivityImple.java:261)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wscf.model.twophase.arjunacore.CoordinatorServiceImple.confirm(CoordinatorServiceImple.java:156)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.mwlabs.wst11.at.participants.CompletionCoordinatorImple.commit(CompletionCoordinatorImple.java:41)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.wst11.messaging.CompletionCoordinatorProcessorImpl.commit(CompletionCoordinatorProcessorImpl.java:84)
> [0m[31m22:27:03,733 ERROR [stderr] (TaskWorker-2) at com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl$1.executeTask(CompletionCoordinatorPortTypeImpl.java:58)
> [0m[31m22:27:03,734 ERROR [stderr] (TaskWorker-2) at com.arjuna.services.framework.task.TaskWorker.run(TaskWorker.java:63)
> [0m[31m22:27:03,734 ERROR [stderr] (TaskWorker-2) at java.lang.Thread.run(Thread.java:662)
> {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
12 years, 8 months
[JBoss JIRA] (JBTM-1872) com.jboss.transaction.wstf.interop.Sc007Test.test3_4 failed with TransactionRolledBackException
by Amos Feng (JIRA)
[ https://issues.jboss.org/browse/JBTM-1872?page=com.atlassian.jira.plugin.... ]
Amos Feng updated JBTM-1872:
----------------------------
Description:
http://172.17.131.2/view/Narayana+BlackTie/job/jbossts-EAP61-jdbcobjectst...
{noformat}
19:15:34,794 ERROR [org.jboss.arquillian.protocol.jmx.JMXTestRunner] (pool-1-thread-1) Failed: com.jboss.transaction.wstf.interop.Sc007Test.test3_4: com.arjuna.wst.TransactionRolledBackException
at com.arjuna.wst11.stub.CompletionStub.commit(CompletionStub.java:65) [jbossxts-4.17.8.Final-SNAPSHOT.jar:4.17.8.Final-SNAPSHOT]
at com.jboss.transaction.wstf.interop.Sc007TestCase.test3_4(Sc007TestCase.java:283) [classes:]
at com.jboss.transaction.wstf.interop.Sc007Test.test3_4(Sc007Test.java:130) [classes:]
{noformat}
was:http://172.17.131.2/view/Narayana+BlackTie/job/jbossts-EAP61-jdbcobjectstore/21/DB_TYPE=postgres,jdk=jdk6.latest,slave=linux/consoleText
> com.jboss.transaction.wstf.interop.Sc007Test.test3_4 failed with TransactionRolledBackException
> -----------------------------------------------------------------------------------------------
>
> Key: JBTM-1872
> URL: https://issues.jboss.org/browse/JBTM-1872
> Project: JBoss Transaction Manager
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: Testing, XTS
> Reporter: Amos Feng
> Assignee: Amos Feng
> Fix For: 4.17.8
>
>
> http://172.17.131.2/view/Narayana+BlackTie/job/jbossts-EAP61-jdbcobjectst...
> {noformat}
> 19:15:34,794 ERROR [org.jboss.arquillian.protocol.jmx.JMXTestRunner] (pool-1-thread-1) Failed: com.jboss.transaction.wstf.interop.Sc007Test.test3_4: com.arjuna.wst.TransactionRolledBackException
> at com.arjuna.wst11.stub.CompletionStub.commit(CompletionStub.java:65) [jbossxts-4.17.8.Final-SNAPSHOT.jar:4.17.8.Final-SNAPSHOT]
> at com.jboss.transaction.wstf.interop.Sc007TestCase.test3_4(Sc007TestCase.java:283) [classes:]
> at com.jboss.transaction.wstf.interop.Sc007Test.test3_4(Sc007Test.java:130) [classes:]
> {noformat}
--
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
12 years, 8 months