[JBoss JIRA] (JBTM-2220) "Could not restore timer from" error when building Blacktie C++ hybrid transport
by Gytis Trikleris (JIRA)
Gytis Trikleris created JBTM-2220:
-------------------------------------
Summary: "Could not restore timer from" error when building Blacktie C++ hybrid transport
Key: JBTM-2220
URL: https://issues.jboss.org/browse/JBTM-2220
Project: JBoss Transaction Manager
Issue Type: Bug
Security Level: Public (Everyone can see)
Components: BlackTie
Reporter: Gytis Trikleris
Assignee: Amos Feng
Priority: Minor
Fix For: 5.0.3
http://172.17.131.2/view/Status/job/narayana/572/PROFILE=BLACKTIE,jdk=jdk...
{code}
[31m13:03:49,383 ERROR [org.jboss.as.ejb3] (RequestProcessor-10) WFLYEJB0029: Could not restore timer from /home/hudson/workspace/narayana/PROFILE/BLACKTIE/jdk/jdk7.latest/label/linux32el6/blacktie/wildfly-9.0.0.Alpha1-SNAPSHOT/standalone/data/timer-service-data/blacktie-admin-services-ear-5.0.3.Final-SNAPSHOT.blacktie-admin-services-5.0.3.Final-SNAPSHOT.QueueReaperBean/df0ab14e-af95-4892-8543-258e2a0c5a3f.xml: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[4,5]
Message: WFLYCTL0133: Missing required attribute(s): next-date
at org.jboss.as.controller.parsing.ParseUtils.missingRequired(ParseUtils.java:134)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.parseTimer(EjbTimerXmlParser_1_0.java:146)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:112)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:86)
at org.jboss.staxmapper.XMLMapperImpl.processNested(XMLMapperImpl.java:110)
at org.jboss.staxmapper.XMLMapperImpl.parseDocument(XMLMapperImpl.java:69)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.loadTimersFromFile(FileTimerPersistence.java:339)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.getTimers(FileTimerPersistence.java:302)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.access$200(FileTimerPersistence.java:80)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence$PersistTransactionSynchronization.afterCompletion(FileTimerPersistence.java:425)
at com.arjuna.ats.internal.jta.resources.jts.orbspecific.SynchronizationImple.after_completion(SynchronizationImple.java:105) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at com.arjuna.ArjunaOTS.JTAInterposedSynchronizationPOATie.after_completion(JTAInterposedSynchronizationPOATie.java:58) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at com.arjuna.ArjunaOTS.JTAInterposedSynchronizationPOA._invoke(JTAInterposedSynchronizationPOA.java:51) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at org.jacorb.poa.RequestProcessor.invokeOperation(RequestProcessor.java:306) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
at org.jacorb.poa.RequestProcessor.process(RequestProcessor.java:626) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
at org.jacorb.poa.RequestProcessor.run(RequestProcessor.java:769) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
[0m[31m13:03:49,397 ERROR [org.jboss.as.ejb3] (EJB default - 10) WFLYEJB0029: Could not restore timer from /home/hudson/workspace/narayana/PROFILE/BLACKTIE/jdk/jdk7.latest/label/linux32el6/blacktie/wildfly-9.0.0.Alpha1-SNAPSHOT/standalone/data/timer-service-data/blacktie-admin-services-ear-5.0.3.Final-SNAPSHOT.blacktie-admin-services-5.0.3.Final-SNAPSHOT.QueueReaperBean/df0ab14e-af95-4892-8543-258e2a0c5a3f.xml: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[4,5]
Message: WFLYCTL0133: Missing required attribute(s): next-date
at org.jboss.as.controller.parsing.ParseUtils.missingRequired(ParseUtils.java:134) [wildfly-controller-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.parseTimer(EjbTimerXmlParser_1_0.java:146) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:112) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:86) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.staxmapper.XMLMapperImpl.processNested(XMLMapperImpl.java:110) [staxmapper-1.1.0.Final.jar:1.1.0.Final]
at org.jboss.staxmapper.XMLMapperImpl.parseDocument(XMLMapperImpl.java:69) [staxmapper-1.1.0.Final.jar:1.1.0.Final]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.loadTimersFromFile(FileTimerPersistence.java:339) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.getTimers(FileTimerPersistence.java:302) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.persistTimer(FileTimerPersistence.java:193) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.persistTimer(FileTimerPersistence.java:175) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.TimerServiceImpl.persistTimer(TimerServiceImpl.java:604) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.task.TimerTask.run(TimerTask.java:185) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]
at org.jboss.threads.JBossThread.run(JBossThread.java:122) [jboss-threads-2.1.1.Final.jar:2.1.1.Final]
[0m [exec] --28426:1:mallocfr newSuperblock at 0x6B2E2000 (pszB 98288) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:execonte resizing htab from size 6151 to 12289 (idx 4) Total#ECs=6152
[exec] --28426:1:mallocfr newSuperblock at 0x6B2FA000 (pszB 147440) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr reclaimSuperblock at 0x6B2E2000 (pszB 98288) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6B2E2000 (pszB 65520) owner VALGRIND/demangle
[exec] --28426:1:mallocfr deferred_reclaimSuperblock at 0x6B2E2000 (pszB 65520) (prev 0x0) owner VALGRIND/demangle
[exec] --28426:1:mallocfr deferred_reclaimSuperblock at 0x6B2E2000 (pszB 65520) (prev 0x6B2E2000) owner VALGRIND/demangle
[exec] --28426:1:mallocfr deferred_reclaimSuperblock at 0x6B2E2000 (pszB 65520) (prev 0x6B2E2000) owner VALGRIND/demangle
[exec] --28426:1:mallocfr deferred_reclaimSuperblock at 0x6B2E2000 (pszB 65520) (prev 0x6B2E2000) owner VALGRIND/demangle
[exec] --28426:1:hashtabl resizing table `MC_(malloc_list)' from 769 to 1543 (total elems 770)
[exec] --28426:1:execonte resizing htab from size 12289 to 24593 (idx 5) Total#ECs=12290
[exec] --28426:1:mallocfr deferred_reclaimSuperblock NULL (prev 0x6B2E2000) owner VALGRIND/demangle
[exec] --28426:1:mallocfr reclaimSuperblock at 0x6B2E2000 (pszB 65520) owner VALGRIND/demangle
[exec] --28426:1:mallocfr newSuperblock at 0x6B431000 (pszB 221168) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr reclaimSuperblock at 0x6B2FA000 (pszB 147440) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6B8B5000 (pszB 331760) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr reclaimSuperblock at 0x6B431000 (pszB 221168) unsplittable owner VALGRIND/ttaux
[exec] .2014-07-03 13:03:52,064 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::setUp
[exec] --28426:1:mallocfr newSuperblock at 0x6BA99000 (pszB 1048560) owner VALGRIND/exectxt
[exec] --28426:1:execonte resizing htab from size 24593 to 49157 (idx 6) Total#ECs=24594
[exec] --28426:1:hashtabl resizing table `MC_(malloc_list)' from 1543 to 3079 (total elems 1544)
[exec] --28426:1:hashtabl resizing table `MC_(malloc_list)' from 3079 to 6151 (total elems 3080)
[exec] --28426:1:signals extending a stack base 0xbee41000 down by 4096
[exec] --28426:1:signals extending a stack base 0xbee40000 down by 8192
[exec] --28426:1:mallocfr newSuperblock at 0x6B906000 (pszB 495600) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr reclaimSuperblock at 0x6B8B5000 (pszB 331760) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:signals extending a stack base 0xbee3e000 down by 4096
[exec] --28426:1:mallocfr newSuperblock at 0x6B455000 (pszB 65520) owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6C154000 (pszB 1048560) owner VALGRIND/exectxt
[exec] 2014-07-03 13:03:55,885 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test
[exec] --28426:1:mallocfr newSuperblock at 0x6C254000 (pszB 741360) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr reclaimSuperblock at 0x6B906000 (pszB 495600) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6C309000 (pszB 1048560) owner VALGRIND/exectxt
[exec] --28426:1:execonte resizing htab from size 49157 to 98317 (idx 7) Total#ECs=49158
[exec] --28426:1:transtab declare sector 0 full (TT loading 64%, TC loading 76%)
[exec] --28426:1:transtab allocate sector 1
[exec] --28426:1:aspacem allocated thread stack at 0x6e8db000 size 1064960
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): pre-thread_wrapper
[exec] --28426:1:syswrap- thread_wrapper(tid=2): entry
[exec] --28426:1:mallocfr newSuperblock at 0x6B8CD000 (pszB 65520) owner VALGRIND/ttaux
[exec] --28426:1:mallocfr deferred_reclaimSuperblock at 0x6B8CD000 (pszB 65520) (prev 0x0) owner VALGRIND/ttaux
[exec] 2014-07-03 13:03:57,339 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test sent message
[exec] 2014-07-03 13:03:57,622 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test sent message
[exec] --28426:1:mallocfr deferred_reclaimSuperblock NULL (prev 0x6B8CD000) owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6E9DF000 (pszB 1048560) owner VALGRIND/exectxt
[31m13:03:57,909 ERROR [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (RequestProcessor-9) IJ000315: Pool HornetQConnectionDefinition has 1 active handles
[0m [exec] --28426:1:syswrap- thread_wrapper(tid=2): exit
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): post-thread_wrapper
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): not last one standing
[exec] 2014-07-03 13:03:59,657 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test starting to receive (rollback)
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): pre-thread_wrapper
[exec] --28426:1:syswrap- thread_wrapper(tid=2): entry
[exec] 2014-07-03 13:03:59,795 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - Iterating
[exec] --28426:1:mallocfr newSuperblock at 0x5C97000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x6EADF000 (pszB 1048560) owner VALGRIND/exectxt
[exec] 2014-07-03 13:04:00,178 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test received message
[exec] 2014-07-03 13:04:00,471 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test received message
[31m13:04:00,568 ERROR [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (RequestProcessor-10) IJ000315: Pool HornetQConnectionDefinition has 1 active handles
[0m [exec] --28426:1:syswrap- thread_wrapper(tid=2): exit
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): post-thread_wrapper
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): not last one standing
[exec] 2014-07-03 13:04:02,702 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test starting to receive (commit)
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): pre-thread_wrapper
[exec] --28426:1:syswrap- thread_wrapper(tid=2): entry
[exec] 2014-07-03 13:04:02,842 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - Iterating
[exec] --28426:1:mallocfr newSuperblock at 0x6EBDF000 (pszB 1048560) owner VALGRIND/exectxt
[exec] 2014-07-03 13:04:03,249 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test received message
[exec] 2014-07-03 13:04:03,552 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::test received message
[31m13:04:03,808 ERROR [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (RequestProcessor-10) IJ000315: Pool HornetQConnectionDefinition has 1 active handles
[0m [exec] --28426:1:syswrap- thread_wrapper(tid=2): exit
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): post-thread_wrapper
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): not last one standing
[exec] 2014-07-03 13:04:05,744 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - Iterated
[exec] 2014-07-03 13:04:05,748 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestXAStompConnection::tearDown
[exec] --28426:1:mallocfr newSuperblock at 0x6BBF9000 (pszB 65520) owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6ECDF000 (pszB 1048560) owner VALGRIND/exectxt
[exec] .2014-07-03 13:04:06,082 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestStompConnection::setUp
[exec] --28426:1:mallocfr newSuperblock at 0x6097000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x6EE3B000 (pszB 1048560) owner VALGRIND/exectxt
[exec] 2014-07-03 13:04:06,575 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestStompConnection::testLibStomp
[exec] 2014-07-03 13:04:06,621 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - Iterating (takes about 16 seconds on toms machine)
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): pre-thread_wrapper
[exec] --28426:1:syswrap- thread_wrapper(tid=2): entry
[exec] --28426:1:mallocfr newSuperblock at 0x6EF63000 (pszB 98288) unsplittable owner VALGRIND/ttaux
[exec] --28426:1:mallocfr newSuperblock at 0x6EF7F000 (pszB 1048560) owner VALGRIND/exectxt
[exec] --28426:1:mallocfr newSuperblock at 0x6F0CB000 (pszB 1048560) owner VALGRIND/exectxt
[exec] --28426:1:mallocfr newSuperblock at 0x6F1D3000 (pszB 1048560) owner VALGRIND/exectxt
[exec] --28426:1:execonte resizing htab from size 98317 to 196613 (idx 8) Total#ECs=98318
[exec] --28426:1:mallocfr newSuperblock at 0x6497000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x6897000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x6C97000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x7097000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x6F647000 (pszB 4194288) owner VALGRIND/tool
[exec] --28426:1:mallocfr newSuperblock at 0x7497000 (pszB 4194288) owner CLIENT/client
[31m13:04:19,537 ERROR [org.jboss.as.ejb3] (RequestProcessor-10) WFLYEJB0029: Could not restore timer from /home/hudson/workspace/narayana/PROFILE/BLACKTIE/jdk/jdk7.latest/label/linux32el6/blacktie/wildfly-9.0.0.Alpha1-SNAPSHOT/standalone/data/timer-service-data/blacktie-admin-services-ear-5.0.3.Final-SNAPSHOT.blacktie-admin-services-5.0.3.Final-SNAPSHOT.QueueReaperBean/900b5478-18ea-42c6-a90e-a06a69bba56e.xml: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[4,5]
Message: WFLYCTL0133: Missing required attribute(s): next-date
at org.jboss.as.controller.parsing.ParseUtils.missingRequired(ParseUtils.java:134)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.parseTimer(EjbTimerXmlParser_1_0.java:146)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:112)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:86)
at org.jboss.staxmapper.XMLMapperImpl.processNested(XMLMapperImpl.java:110)
at org.jboss.staxmapper.XMLMapperImpl.parseDocument(XMLMapperImpl.java:69)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.loadTimersFromFile(FileTimerPersistence.java:339)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.getTimers(FileTimerPersistence.java:302)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.access$200(FileTimerPersistence.java:80)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence$PersistTransactionSynchronization.afterCompletion(FileTimerPersistence.java:425)
at com.arjuna.ats.internal.jta.resources.jts.orbspecific.SynchronizationImple.after_completion(SynchronizationImple.java:105) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at com.arjuna.ArjunaOTS.JTAInterposedSynchronizationPOATie.after_completion(JTAInterposedSynchronizationPOATie.java:58) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at com.arjuna.ArjunaOTS.JTAInterposedSynchronizationPOA._invoke(JTAInterposedSynchronizationPOA.java:51) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at org.jacorb.poa.RequestProcessor.invokeOperation(RequestProcessor.java:306) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
at org.jacorb.poa.RequestProcessor.process(RequestProcessor.java:626) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
at org.jacorb.poa.RequestProcessor.run(RequestProcessor.java:769) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
[0m[31m13:04:19,562 ERROR [org.jboss.as.ejb3] (EJB default - 1) WFLYEJB0029: Could not restore timer from /home/hudson/workspace/narayana/PROFILE/BLACKTIE/jdk/jdk7.latest/label/linux32el6/blacktie/wildfly-9.0.0.Alpha1-SNAPSHOT/standalone/data/timer-service-data/blacktie-admin-services-ear-5.0.3.Final-SNAPSHOT.blacktie-admin-services-5.0.3.Final-SNAPSHOT.QueueReaperBean/900b5478-18ea-42c6-a90e-a06a69bba56e.xml: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[4,5]
Message: WFLYCTL0133: Missing required attribute(s): next-date
at org.jboss.as.controller.parsing.ParseUtils.missingRequired(ParseUtils.java:134) [wildfly-controller-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.parseTimer(EjbTimerXmlParser_1_0.java:146) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:112) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:86) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.staxmapper.XMLMapperImpl.processNested(XMLMapperImpl.java:110) [staxmapper-1.1.0.Final.jar:1.1.0.Final]
at org.jboss.staxmapper.XMLMapperImpl.parseDocument(XMLMapperImpl.java:69) [staxmapper-1.1.0.Final.jar:1.1.0.Final]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.loadTimersFromFile(FileTimerPersistence.java:339) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.getTimers(FileTimerPersistence.java:302) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.persistTimer(FileTimerPersistence.java:193) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.persistTimer(FileTimerPersistence.java:175) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.TimerServiceImpl.persistTimer(TimerServiceImpl.java:604) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.task.TimerTask.run(TimerTask.java:185) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]
at org.jboss.threads.JBossThread.run(JBossThread.java:122) [jboss-threads-2.1.1.Final.jar:2.1.1.Final]
[0m [exec] --28426:1:mallocfr newSuperblock at 0x7897000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x885F000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x8C5F000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x905F000 (pszB 4194288) owner CLIENT/client
[exec] --28426:1:mallocfr newSuperblock at 0x6FF47000 (pszB 4194288) owner VALGRIND/tool
[exec] 2014-07-03 13:04:46,791 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - Iterated
[exec] 2014-07-03 13:04:46,991 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestStompConnection::tearDown
[exec] --28426:1:syswrap- thread_wrapper(tid=2): exit
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): post-thread_wrapper
[exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): not last one standing
[exec] .2014-07-03 13:04:48,840 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestStompConnection::setUp
[exec] 2014-07-03 13:04:49,556 [0x4e77a40] INFO (AtmiBrokerLogc :60 ) - TestStompConnection::test
[31m13:04:49,685 ERROR [org.jboss.as.ejb3] (RequestProcessor-10) WFLYEJB0029: Could not restore timer from /home/hudson/workspace/narayana/PROFILE/BLACKTIE/jdk/jdk7.latest/label/linux32el6/blacktie/wildfly-9.0.0.Alpha1-SNAPSHOT/standalone/data/timer-service-data/blacktie-admin-services-ear-5.0.3.Final-SNAPSHOT.blacktie-admin-services-5.0.3.Final-SNAPSHOT.QueueReaperBean/b539dc9a-3020-4da8-95f3-579978a08c01.xml: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[4,5]
Message: WFLYCTL0133: Missing required attribute(s): next-date
at org.jboss.as.controller.parsing.ParseUtils.missingRequired(ParseUtils.java:134)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.parseTimer(EjbTimerXmlParser_1_0.java:146)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:112)
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:86)
at org.jboss.staxmapper.XMLMapperImpl.processNested(XMLMapperImpl.java:110)
at org.jboss.staxmapper.XMLMapperImpl.parseDocument(XMLMapperImpl.java:69)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.loadTimersFromFile(FileTimerPersistence.java:339)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.getTimers(FileTimerPersistence.java:302)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.access$200(FileTimerPersistence.java:80)
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence$PersistTransactionSynchronization.afterCompletion(FileTimerPersistence.java:425)
at com.arjuna.ats.internal.jta.resources.jts.orbspecific.SynchronizationImple.after_completion(SynchronizationImple.java:105) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at com.arjuna.ArjunaOTS.JTAInterposedSynchronizationPOATie.after_completion(JTAInterposedSynchronizationPOATie.java:58) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at com.arjuna.ArjunaOTS.JTAInterposedSynchronizationPOA._invoke(JTAInterposedSynchronizationPOA.java:51) [narayana-jts-jacorb-5.0.3.Final-SNAPSHOT.jar:5.0.3.Final-SNAPSHOT (revision: 30cb74)]
at org.jacorb.poa.RequestProcessor.invokeOperation(RequestProcessor.java:306) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
at org.jacorb.poa.RequestProcessor.process(RequestProcessor.java:626) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
at org.jacorb.poa.RequestProcessor.run(RequestProcessor.java:769) [jacorb-2.3.2-jbossorg-5.jar:2.3.2-jbossorg-5]
[0m[31m13:04:49,706 ERROR [org.jboss.as.ejb3] (EJB default - 2) WFLYEJB0029: Could not restore timer from /home/hudson/workspace/narayana/PROFILE/BLACKTIE/jdk/jdk7.latest/label/linux32el6/blacktie/wildfly-9.0.0.Alpha1-SNAPSHOT/standalone/data/timer-service-data/blacktie-admin-services-ear-5.0.3.Final-SNAPSHOT.blacktie-admin-services-5.0.3.Final-SNAPSHOT.QueueReaperBean/b539dc9a-3020-4da8-95f3-579978a08c01.xml: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[4,5]
Message: WFLYCTL0133: Missing required attribute(s): next-date
at org.jboss.as.controller.parsing.ParseUtils.missingRequired(ParseUtils.java:134) [wildfly-controller-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.parseTimer(EjbTimerXmlParser_1_0.java:146) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:112) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.EjbTimerXmlParser_1_0.readElement(EjbTimerXmlParser_1_0.java:86) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.staxmapper.XMLMapperImpl.processNested(XMLMapperImpl.java:110) [staxmapper-1.1.0.Final.jar:1.1.0.Final]
at org.jboss.staxmapper.XMLMapperImpl.parseDocument(XMLMapperImpl.java:69) [staxmapper-1.1.0.Final.jar:1.1.0.Final]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.loadTimersFromFile(FileTimerPersistence.java:339) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.getTimers(FileTimerPersistence.java:302) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.persistTimer(FileTimerPersistence.java:193) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.persistence.filestore.FileTimerPersistence.persistTimer(FileTimerPersistence.java:175) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.TimerServiceImpl.persistTimer(TimerServiceImpl.java:604) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at org.jboss.as.ejb3.timerservice.task.TimerTask.run(TimerTask.java:185) [wildfly-ejb3-9.0.0.Alpha1-SNAPSHOT.jar:9.0.0.Alpha1-SNAPSHOT]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]
at org.jboss.threads.JBossThread.run(JBossThread.java:122) [jboss-threads-2.1.1.Final.jar:2.1.1.Final]
[0m [exec] --28426:1:syswrap- run_a_thread_NORETURN(tid=2): pre-thread_wrapper
[exec] --28426:1:syswrap- thread_wrapper(tid=2): entry
{code}
--
This message was sent by Atlassian JIRA
(v6.2.6#6264)
10 years, 4 months
[JBoss JIRA] (JBTM-2219) TestBACrashDuringCommit tests failed
by Gytis Trikleris (JIRA)
Gytis Trikleris created JBTM-2219:
-------------------------------------
Summary: TestBACrashDuringCommit tests failed
Key: JBTM-2219
URL: https://issues.jboss.org/browse/JBTM-2219
Project: JBoss Transaction Manager
Issue Type: Bug
Security Level: Public (Everyone can see)
Components: XTS
Reporter: Gytis Trikleris
Assignee: Gytis Trikleris
Priority: Minor
Fix For: 5.0.3
http://172.17.131.2/view/Status/job/narayana/573/PROFILE=XTS,jdk=jdk7.lat...
{code}
Running com.arjuna.qa.junit.TestBACrashDuringCommit
Tests run: 9, Failures: 1, Errors: 1, Skipped: 0, Time elapsed: 2,195.701 sec <<< FAILURE!
MultiServiceParticipantCompletionParticipantCloseAndExitTest(com.arjuna.qa.junit.TestBACrashDuringCommit) Time elapsed: 728.856 sec <<< ERROR!
java.lang.RuntimeException: jboss-as was not killed by Byteman, this indicates a test failure
at com.arjuna.qa.extension.BaseServerKillProcessor.kill(BaseServerKillProcessor.java:62)
at org.jboss.arquillian.container.impl.ContainerImpl.kill(ContainerImpl.java:235)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$10.perform(ContainerLifecycleController.java:193)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$10.perform(ContainerLifecycleController.java:187)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.forContainer(ContainerLifecycleController.java:255)
at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.killContainer(ContainerLifecycleController.java:186)
at sun.reflect.GeneratedMethodAccessor8.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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.GeneratedMethodAccessor1.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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.kill(ClientContainerController.java:230)
at com.arjuna.qa.junit.BaseCrashTest.runTest(BaseCrashTest.java:216)
at com.arjuna.qa.junit.TestBACrashDuringCommit.MultiServiceParticipantCompletionParticipantCloseAndExitTest(TestBACrashDuringCommit.java:66)
MultiServiceParticipantCompletionParticipantCloseAndExitTest(com.arjuna.qa.junit.TestBACrashDuringCommit) Time elapsed: 728.859 sec <<< FAILURE!
java.lang.AssertionError: null
at org.junit.Assert.fail(Assert.java:86)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertTrue(Assert.java:52)
at com.arjuna.qa.junit.BaseCrashTest.tearDown(BaseCrashTest.java:172)
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:606)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:33)
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:606)
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:606)
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:102)
at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:84)
at sun.reflect.GeneratedMethodAccessor3.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:65)
at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:271)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
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:309)
at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:147)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
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:606)
at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray2(ReflectionUtils.java:208)
at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:159)
at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:87)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:95)
{code}
--
This message was sent by Atlassian JIRA
(v6.2.6#6264)
10 years, 4 months
[JBoss JIRA] (JBTM-2167) JDBC Object Store Deadlock potential with MySQL
by Tom Jenkinson (JIRA)
[ https://issues.jboss.org/browse/JBTM-2167?page=com.atlassian.jira.plugin.... ]
Tom Jenkinson commented on JBTM-2167:
-------------------------------------
These are the two inserts that supposedly are causing a deadlock:
INSERT INTO ActionJBossTSTxTable
(StateType,Hidden,TypeName,UidString,ObjectState) VALUES
(1,0,'Recovery/FactoryContact','0:ffffac1183232f3:536520f5:0',_binary'#BE
@\0\0\0\0\0\0E�Z\0\0eIOR:000000000000002B49444C3A61726A756E612E636F6D2F41726A756E614F54532F41726A756E61466163746F72793A312E300000000000010000000000000070000102000000000E3137322E31372E3133312E333500DA5300000015363237373531323530302F00181B03020D2A280A3F000000000000020000000000000008000000004A41430000000001000000200000000005010001000000010001000100010109000000020501000100010100\0\0\0\0'
INSERT INTO ActionJBossTSTxTable
(StateType,Hidden,TypeName,UidString,ObjectState) VALUES
(1,0,'Recovery/FactoryContact','0:ffffac118323:c696:536520f5:0',_binary'#BE
@\0\0\0\0\0\0E�p\0\0eIOR:000000000000002B49444C3A61726A756E612E636F6D2F41726A756E614F54532F41726A756E61466163746F72793A312E300000000000010000000000000070000102000000000E3137322E31372E3133312E333500E37900000016333638303032343135382F00181B03020D2B200147160000000000020000000000000008000000004A41430000000001000000200000000005010001000000010001000100010109000000020501000100010100\0\0\0\0')
>From MySQL docs http://dev.mysql.com/doc/refman/5.0/en/innodb-deadlocks.html: "not dangerous unless they are so frequent that you cannot run certain transactions at all. Normally, you must write your applications so that they are always prepared to re-issue a transaction if it gets rolled back because of a deadlock."
> JDBC Object Store Deadlock potential with MySQL
> -----------------------------------------------
>
> Key: JBTM-2167
> URL: https://issues.jboss.org/browse/JBTM-2167
> 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.3
>
>
> {code}
> WARN com.arjuna.ats.arjuna - ARJUNA012258: JDBCImple:write_state caught exception
> 2014-07-06 05:06:00,067 out: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction
> 2014-07-06 05:06:00,067 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> 2014-07-06 05:06:00,067 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
> 2014-07-06 05:06:00,067 out: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> 2014-07-06 05:06:00,067 out: at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.Util.handleNewInstance(Util.java:406)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.Util.getInstance(Util.java:381)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1045)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:956)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3536)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3468)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1957)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2107)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2648)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2086)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2371)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2289)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2274)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCImple_driver.write_state(JDBCImple_driver.java:587)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore.write_committed(JDBCStore.java:158)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(BasicAction.java:2282)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1515)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:96)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:147)
> 2014-07-06 05:06:00,068 out: at org.jboss.jbossts.qa.ArjunaCore.LockManager.client.Worker001.run(Worker001.java:67)
> {code}
> crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
> crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
> txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
> txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
> txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
> txcore_lockrecord LockRecord_Thread_Test047b Fail
--
This message was sent by Atlassian JIRA
(v6.2.6#6264)
10 years, 4 months
[JBoss JIRA] (JBTM-2167) JDBC Object Store Deadlock potential with MySQL
by Tom Jenkinson (JIRA)
[ https://issues.jboss.org/browse/JBTM-2167?page=com.atlassian.jira.plugin.... ]
Tom Jenkinson updated JBTM-2167:
--------------------------------
Summary: JDBC Object Store Deadlock potential with MySQL (was: QA suite jdbc-object-store failure with MySQL)
> JDBC Object Store Deadlock potential with MySQL
> -----------------------------------------------
>
> Key: JBTM-2167
> URL: https://issues.jboss.org/browse/JBTM-2167
> 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.3
>
>
> crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
> crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
> txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
> txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
> txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
> txcore_lockrecord LockRecord_Thread_Test047b Fail
--
This message was sent by Atlassian JIRA
(v6.2.6#6264)
10 years, 4 months
[JBoss JIRA] (JBTM-2167) JDBC Object Store Deadlock potential with MySQL
by Tom Jenkinson (JIRA)
[ https://issues.jboss.org/browse/JBTM-2167?page=com.atlassian.jira.plugin.... ]
Tom Jenkinson updated JBTM-2167:
--------------------------------
Description:
{code}
WARN com.arjuna.ats.arjuna - ARJUNA012258: JDBCImple:write_state caught exception
2014-07-06 05:06:00,067 out: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction
2014-07-06 05:06:00,067 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2014-07-06 05:06:00,067 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
2014-07-06 05:06:00,067 out: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2014-07-06 05:06:00,067 out: at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.Util.handleNewInstance(Util.java:406)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.Util.getInstance(Util.java:381)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1045)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:956)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3536)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3468)
2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1957)
2014-07-06 05:06:00,068 out: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2107)
2014-07-06 05:06:00,068 out: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2648)
2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2086)
2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2371)
2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2289)
2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2274)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCImple_driver.write_state(JDBCImple_driver.java:587)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore.write_committed(JDBCStore.java:158)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(BasicAction.java:2282)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1515)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:96)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:147)
2014-07-06 05:06:00,068 out: at org.jboss.jbossts.qa.ArjunaCore.LockManager.client.Worker001.run(Worker001.java:67)
{code}
crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
txcore_lockrecord LockRecord_Thread_Test047b Fail
was:
crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
txcore_lockrecord LockRecord_Thread_Test047b Fail
> JDBC Object Store Deadlock potential with MySQL
> -----------------------------------------------
>
> Key: JBTM-2167
> URL: https://issues.jboss.org/browse/JBTM-2167
> 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.3
>
>
> {code}
> WARN com.arjuna.ats.arjuna - ARJUNA012258: JDBCImple:write_state caught exception
> 2014-07-06 05:06:00,067 out: com.mysql.jdbc.exceptions.jdbc4.MySQLTransactionRollbackException: Deadlock found when trying to get lock; try restarting transaction
> 2014-07-06 05:06:00,067 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
> 2014-07-06 05:06:00,067 out: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
> 2014-07-06 05:06:00,067 out: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> 2014-07-06 05:06:00,067 out: at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.Util.handleNewInstance(Util.java:406)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.Util.getInstance(Util.java:381)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1045)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:956)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3536)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3468)
> 2014-07-06 05:06:00,067 out: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1957)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2107)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2648)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2086)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2371)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2289)
> 2014-07-06 05:06:00,068 out: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2274)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCImple_driver.write_state(JDBCImple_driver.java:587)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore.write_committed(JDBCStore.java:158)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(BasicAction.java:2282)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1515)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:96)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
> 2014-07-06 05:06:00,068 out: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:147)
> 2014-07-06 05:06:00,068 out: at org.jboss.jbossts.qa.ArjunaCore.LockManager.client.Worker001.run(Worker001.java:67)
> {code}
> crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
> crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
> txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
> txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
> txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
> txcore_lockrecord LockRecord_Thread_Test047b Fail
--
This message was sent by Atlassian JIRA
(v6.2.6#6264)
10 years, 4 months
[JBoss JIRA] (JBTM-2167) QA suite jdbc-object-store failure with MySQL
by Tom Jenkinson (JIRA)
[ https://issues.jboss.org/browse/JBTM-2167?page=com.atlassian.jira.plugin.... ]
Tom Jenkinson updated JBTM-2167:
--------------------------------
Description:
crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
txcore_lockrecord LockRecord_Thread_Test047b Fail
was:
crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
> QA suite jdbc-object-store failure with MySQL
> ---------------------------------------------
>
> Key: JBTM-2167
> URL: https://issues.jboss.org/browse/JBTM-2167
> 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.3
>
>
> crashrecovery08 CrashRecovery08_Test32 Fail (0m44.850s)
> crashrecovery08 CrashRecovery08_Test04 Fail (0m37.253s)
> txcore_lockrecord LockRecord_Thread_Test048a Fail (0m46.514s)
> txcore_lockrecord LockRecord_Thread_Test022b Fail (0m8.687s)
> txcore_lockrecord LockRecord_Thread_Test010a Fail (0m10.751s)
> txcore_lockrecord LockRecord_Thread_Test047b Fail
--
This message was sent by Atlassian JIRA
(v6.2.6#6264)
10 years, 4 months